[14:40:54.341] New invocation is queued and will start shortly
[14:40:55.542] Starting the invocation (attempt 1)
[14:40:55.628] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:40:55.628] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:40:55.628] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9102007277068554448",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"2e95d045f7a3a5230f4f918100375d21cdf99e7d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9102007277068554448",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/2e95d045f7a3a5230f4f918100375d21cdf99e7d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2e95d045f7a3a5230f4f918100375d21cdf99e7d",
"gitiles_ref:refs/heads/master"
]
}
[14:40:57.384] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535812855941630",
"id": "8936577214726822496",
"lease_expiration_ts": "1535956856850580",
"lease_key": "1763622176",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2e95d045f7a3a5230f4f918100375d21cdf99e7d\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1535812857141770",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9945",
"builder:Windows deterministic",
"buildset:commit/git/2e95d045f7a3a5230f4f918100375d21cdf99e7d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2e95d045f7a3a5230f4f918100375d21cdf99e7d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9102007277068554448",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/9945",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936577214726822496",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/2e95d045f7a3a5230f4f918100375d21cdf99e7d",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2e95d045f7a3a5230f4f918100375d21cdf99e7d",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8936577214726822496/+/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:9102007277068554448",
"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:7a77c69d050faf57eaf65e8f6cb7422326b6b83c",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fae65f36d512010",
"user_agent:luci-scheduler"
],
"updated_ts": "1535812857141850",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9945",
"utcnow_ts": "1535812857374760"
}
}
[14:40:57.384] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9945
[14:40:57.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:2:0) after 1m0s
[14:41:57.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:2:0)
[14:41:57.564] Timer tick, asking Buildbucket for the build status
[14:41:57.771] Build 8936577214726822496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:41:57.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:3:0) after 1m0s
[14:42:20.552] Received PubSub notification, asking Buildbucket for the build status
[14:42:20.594] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:57.986] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:3:0)
[14:42:57.986] Timer tick, asking Buildbucket for the build status
[14:42:58.031] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:58.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:5:0) after 1m0s
[14:43:58.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:5:0)
[14:43:58.083] Timer tick, asking Buildbucket for the build status
[14:43:58.248] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:58.248] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:6:0) after 1m0s
[14:44:58.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:6:0)
[14:44:58.329] Timer tick, asking Buildbucket for the build status
[14:44:58.457] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:58.457] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:7:0) after 1m0s
[14:45:58.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:7:0)
[14:45:58.580] Timer tick, asking Buildbucket for the build status
[14:45:58.675] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:58.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:8:0) after 1m0s
[14:46:58.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:8:0)
[14:46:58.802] Timer tick, asking Buildbucket for the build status
[14:46:58.877] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:58.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:9:0) after 1m0s
[14:47:58.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:9:0)
[14:47:58.906] Timer tick, asking Buildbucket for the build status
[14:47:58.965] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:58.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:10:0) after 1m0s
[14:48:59.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:10:0)
[14:48:59.083] Timer tick, asking Buildbucket for the build status
[14:48:59.135] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:59.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:11:0) after 1m0s
[14:49:59.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:11:0)
[14:49:59.226] Timer tick, asking Buildbucket for the build status
[14:49:59.306] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:59.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:12:0) after 1m0s
[14:50:59.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:12:0)
[14:50:59.329] Timer tick, asking Buildbucket for the build status
[14:50:59.377] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:59.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:13:0) after 1m0s
[14:51:59.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:13:0)
[14:51:59.482] Timer tick, asking Buildbucket for the build status
[14:51:59.542] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:59.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:14:0) after 1m0s
[14:52:59.560] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:14:0)
[14:52:59.560] Timer tick, asking Buildbucket for the build status
[14:52:59.631] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:59.631] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:15:0) after 1m0s
[14:53:59.651] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:15:0)
[14:53:59.651] Timer tick, asking Buildbucket for the build status
[14:53:59.710] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:59.710] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:16:0) after 1m0s
[14:54:59.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:16:0)
[14:54:59.726] Timer tick, asking Buildbucket for the build status
[14:54:59.784] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:59.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:17:0) after 1m0s
[14:55:59.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:17:0)
[14:55:59.802] Timer tick, asking Buildbucket for the build status
[14:55:59.878] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:59.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:18:0) after 1m0s
[14:56:59.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:18:0)
[14:56:59.896] Timer tick, asking Buildbucket for the build status
[14:57:00.058] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:00.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:19:0) after 1m0s
[14:58:00.075] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:19:0)
[14:58:00.075] Timer tick, asking Buildbucket for the build status
[14:58:00.387] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:00.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:20:0) after 1m0s
[14:59:00.402] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:20:0)
[14:59:00.402] Timer tick, asking Buildbucket for the build status
[14:59:00.617] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:00.617] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:21:0) after 1m0s
[15:00:00.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:21:0)
[15:00:00.634] Timer tick, asking Buildbucket for the build status
[15:00:00.750] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:00.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:22:0) after 1m0s
[15:01:00.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:22:0)
[15:01:00.771] Timer tick, asking Buildbucket for the build status
[15:01:00.843] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:00.843] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:23:0) after 1m0s
[15:02:00.948] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:23:0)
[15:02:00.948] Timer tick, asking Buildbucket for the build status
[15:02:01.159] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:01.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:24:0) after 1m0s
[15:03:01.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:24:0)
[15:03:01.221] Timer tick, asking Buildbucket for the build status
[15:03:01.291] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:01.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:25:0) after 1m0s
[15:04:01.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:25:0)
[15:04:01.405] Timer tick, asking Buildbucket for the build status
[15:04:01.516] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:01.516] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:26:0) after 1m0s
[15:05:01.668] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:26:0)
[15:05:01.668] Timer tick, asking Buildbucket for the build status
[15:05:01.783] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:01.783] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:27:0) after 1m0s
[15:06:01.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:27:0)
[15:06:01.827] Timer tick, asking Buildbucket for the build status
[15:06:01.906] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:01.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:28:0) after 1m0s
[15:07:01.976] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:28:0)
[15:07:01.976] Timer tick, asking Buildbucket for the build status
[15:07:07.666] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:07.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:29:0) after 1m0s
[15:08:07.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:29:0)
[15:08:07.683] Timer tick, asking Buildbucket for the build status
[15:08:07.751] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:07.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:30:0) after 1m0s
[15:09:07.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:30:0)
[15:09:07.772] Timer tick, asking Buildbucket for the build status
[15:09:08.325] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:08.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:31:0) after 1m0s
[15:10:08.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:31:0)
[15:10:08.341] Timer tick, asking Buildbucket for the build status
[15:10:08.396] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:08.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:32:0) after 1m0s
[15:11:08.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:32:0)
[15:11:08.417] Timer tick, asking Buildbucket for the build status
[15:11:08.556] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:08.556] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:33:0) after 1m0s
[15:12:08.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:33:0)
[15:12:08.584] Timer tick, asking Buildbucket for the build status
[15:12:08.668] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:08.668] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:34:0) after 1m0s
[15:13:08.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:34:0)
[15:13:08.686] Timer tick, asking Buildbucket for the build status
[15:13:08.768] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:08.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:35:0) after 1m0s
[15:14:08.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:35:0)
[15:14:08.791] Timer tick, asking Buildbucket for the build status
[15:14:08.852] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:08.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:36:0) after 1m0s
[15:15:08.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:36:0)
[15:15:08.873] Timer tick, asking Buildbucket for the build status
[15:15:08.954] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:08.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:37:0) after 1m0s
[15:16:08.973] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:37:0)
[15:16:08.973] Timer tick, asking Buildbucket for the build status
[15:16:09.037] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:09.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:38:0) after 1m0s
[15:17:09.069] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:38:0)
[15:17:09.069] Timer tick, asking Buildbucket for the build status
[15:17:09.147] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:09.147] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:39:0) after 1m0s
[15:18:09.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:39:0)
[15:18:09.164] Timer tick, asking Buildbucket for the build status
[15:18:09.285] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:09.285] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:40:0) after 1m0s
[15:19:09.309] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:40:0)
[15:19:09.309] Timer tick, asking Buildbucket for the build status
[15:19:09.403] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:09.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:41:0) after 1m0s
[15:20:09.474] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:41:0)
[15:20:09.474] Timer tick, asking Buildbucket for the build status
[15:20:09.550] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:09.550] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:42:0) after 1m0s
[15:21:09.568] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:42:0)
[15:21:09.568] Timer tick, asking Buildbucket for the build status
[15:21:09.699] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:09.699] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:43:0) after 1m0s
[15:22:09.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:43:0)
[15:22:09.723] Timer tick, asking Buildbucket for the build status
[15:22:09.983] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:09.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:44:0) after 1m0s
[15:23:10.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:44:0)
[15:23:10.005] Timer tick, asking Buildbucket for the build status
[15:23:10.123] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:10.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:45:0) after 1m0s
[15:24:10.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:45:0)
[15:24:10.141] Timer tick, asking Buildbucket for the build status
[15:24:10.197] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:10.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:46:0) after 1m0s
[15:25:10.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:46:0)
[15:25:10.229] Timer tick, asking Buildbucket for the build status
[15:25:10.341] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:10.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:47:0) after 1m0s
[15:26:10.359] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:47:0)
[15:26:10.359] Timer tick, asking Buildbucket for the build status
[15:26:10.409] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:10.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:48:0) after 1m0s
[15:27:10.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:48:0)
[15:27:10.427] Timer tick, asking Buildbucket for the build status
[15:27:10.494] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:10.494] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:49:0) after 1m0s
[15:28:10.522] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:49:0)
[15:28:10.522] Timer tick, asking Buildbucket for the build status
[15:28:10.637] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:10.637] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:50:0) after 1m0s
[15:29:10.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:50:0)
[15:29:10.658] Timer tick, asking Buildbucket for the build status
[15:29:10.733] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:10.733] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:51:0) after 1m0s
[15:30:10.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:51:0)
[15:30:10.754] Timer tick, asking Buildbucket for the build status
[15:30:10.852] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:10.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:52:0) after 1m0s
[15:31:10.870] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:52:0)
[15:31:10.870] Timer tick, asking Buildbucket for the build status
[15:31:10.929] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:10.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:53:0) after 1m0s
[15:32:10.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:53:0)
[15:32:10.951] Timer tick, asking Buildbucket for the build status
[15:32:10.984] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:10.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:54:0) after 1m0s
[15:33:11.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:54:0)
[15:33:11.003] Timer tick, asking Buildbucket for the build status
[15:33:11.082] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:11.082] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:55:0) after 1m0s
[15:34:11.106] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:55:0)
[15:34:11.106] Timer tick, asking Buildbucket for the build status
[15:34:11.220] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:11.220] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:56:0) after 1m0s
[15:35:11.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:56:0)
[15:35:11.238] Timer tick, asking Buildbucket for the build status
[15:35:11.294] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:11.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:57:0) after 1m0s
[15:36:11.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:57:0)
[15:36:11.315] Timer tick, asking Buildbucket for the build status
[15:36:11.352] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:11.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:58:0) after 1m0s
[15:37:11.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:58:0)
[15:37:11.394] Timer tick, asking Buildbucket for the build status
[15:37:11.575] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:11.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:59:0) after 1m0s
[15:38:11.595] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:59:0)
[15:38:11.595] Timer tick, asking Buildbucket for the build status
[15:38:11.635] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:11.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:60:0) after 1m0s
[15:39:11.713] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:60:0)
[15:39:11.713] Timer tick, asking Buildbucket for the build status
[15:39:11.787] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:11.787] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:61:0) after 1m0s
[15:40:11.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:61:0)
[15:40:11.880] Timer tick, asking Buildbucket for the build status
[15:40:12.364] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:12.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:62:0) after 1m0s
[15:41:12.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:62:0)
[15:41:12.385] Timer tick, asking Buildbucket for the build status
[15:41:12.510] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:12.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:63:0) after 1m0s
[15:42:12.540] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:63:0)
[15:42:12.540] Timer tick, asking Buildbucket for the build status
[15:42:12.677] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:12.677] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:64:0) after 1m0s
[15:43:12.808] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:64:0)
[15:43:12.808] Timer tick, asking Buildbucket for the build status
[15:43:12.897] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:12.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:65:0) after 1m0s
[15:44:12.917] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:65:0)
[15:44:12.917] Timer tick, asking Buildbucket for the build status
[15:44:12.980] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:12.980] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:66:0) after 1m0s
[15:45:13.054] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:66:0)
[15:45:13.054] Timer tick, asking Buildbucket for the build status
[15:45:13.101] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:13.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:67:0) after 1m0s
[15:46:13.120] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:67:0)
[15:46:13.120] Timer tick, asking Buildbucket for the build status
[15:46:13.189] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:13.189] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:68:0) after 1m0s
[15:47:13.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:68:0)
[15:47:13.227] Timer tick, asking Buildbucket for the build status
[15:47:13.552] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:13.552] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:69:0) after 1m0s
[15:48:13.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:69:0)
[15:48:13.582] Timer tick, asking Buildbucket for the build status
[15:48:13.635] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:13.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:70:0) after 1m0s
[15:49:13.661] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:70:0)
[15:49:13.661] Timer tick, asking Buildbucket for the build status
[15:49:13.714] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:13.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:71:0) after 1m0s
[15:50:13.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:71:0)
[15:50:13.736] Timer tick, asking Buildbucket for the build status
[15:50:13.866] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:13.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:72:0) after 1m0s
[15:51:13.885] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:72:0)
[15:51:13.885] Timer tick, asking Buildbucket for the build status
[15:51:14.236] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:14.236] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:73:0) after 1m0s
[15:52:14.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:73:0)
[15:52:14.273] Timer tick, asking Buildbucket for the build status
[15:52:14.326] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:14.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:74:0) after 1m0s
[15:53:14.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:74:0)
[15:53:14.434] Timer tick, asking Buildbucket for the build status
[15:53:14.512] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:14.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:75:0) after 1m0s
[15:54:14.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:75:0)
[15:54:14.533] Timer tick, asking Buildbucket for the build status
[15:54:14.720] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:14.720] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:76:0) after 1m0s
[15:55:14.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:76:0)
[15:55:14.742] Timer tick, asking Buildbucket for the build status
[15:55:14.840] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:14.840] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:77:0) after 1m0s
[15:56:14.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:77:0)
[15:56:14.856] Timer tick, asking Buildbucket for the build status
[15:56:14.971] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:14.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:78:0) after 1m0s
[15:57:14.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:78:0)
[15:57:14.995] Timer tick, asking Buildbucket for the build status
[15:57:15.067] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:15.067] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:79:0) after 1m0s
[15:58:15.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:79:0)
[15:58:15.089] Timer tick, asking Buildbucket for the build status
[15:58:15.236] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:15.236] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:80:0) after 1m0s
[15:59:15.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:80:0)
[15:59:15.427] Timer tick, asking Buildbucket for the build status
[15:59:15.520] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:15.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:81:0) after 1m0s
[16:00:15.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:81:0)
[16:00:15.538] Timer tick, asking Buildbucket for the build status
[16:00:15.651] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:15.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:82:0) after 1m0s
[16:01:15.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:82:0)
[16:01:15.683] Timer tick, asking Buildbucket for the build status
[16:01:15.737] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:15.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:83:0) after 1m0s
[16:02:15.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:83:0)
[16:02:15.756] Timer tick, asking Buildbucket for the build status
[16:02:15.816] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:15.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:84:0) after 1m0s
[16:03:15.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:84:0)
[16:03:15.841] Timer tick, asking Buildbucket for the build status
[16:03:15.910] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:15.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:85:0) after 1m0s
[16:04:15.933] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:85:0)
[16:04:15.933] Timer tick, asking Buildbucket for the build status
[16:04:15.992] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:15.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:86:0) after 1m0s
[16:05:16.011] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:86:0)
[16:05:16.011] Timer tick, asking Buildbucket for the build status
[16:05:16.120] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:16.120] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:87:0) after 1m0s
[16:06:16.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:87:0)
[16:06:16.323] Timer tick, asking Buildbucket for the build status
[16:06:16.512] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:16.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:88:0) after 1m0s
[16:07:16.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:88:0)
[16:07:16.531] Timer tick, asking Buildbucket for the build status
[16:07:16.567] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:16.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:89:0) after 1m0s
[16:08:16.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:89:0)
[16:08:16.588] Timer tick, asking Buildbucket for the build status
[16:08:16.639] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:16.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:90:0) after 1m0s
[16:09:16.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:90:0)
[16:09:16.658] Timer tick, asking Buildbucket for the build status
[16:09:16.847] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:16.847] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:91:0) after 1m0s
[16:10:16.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:91:0)
[16:10:16.869] Timer tick, asking Buildbucket for the build status
[16:10:16.952] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:16.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:92:0) after 1m0s
[16:11:16.971] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:92:0)
[16:11:16.971] Timer tick, asking Buildbucket for the build status
[16:11:17.064] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:17.064] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:93:0) after 1m0s
[16:12:17.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:93:0)
[16:12:17.083] Timer tick, asking Buildbucket for the build status
[16:12:17.149] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:17.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:94:0) after 1m0s
[16:13:17.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:94:0)
[16:13:17.169] Timer tick, asking Buildbucket for the build status
[16:13:17.224] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:17.224] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:95:0) after 1m0s
[16:14:17.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:95:0)
[16:14:17.250] Timer tick, asking Buildbucket for the build status
[16:14:17.304] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:17.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:96:0) after 1m0s
[16:15:17.324] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:96:0)
[16:15:17.324] Timer tick, asking Buildbucket for the build status
[16:15:17.451] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:17.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:97:0) after 1m0s
[16:16:17.472] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:97:0)
[16:16:17.472] Timer tick, asking Buildbucket for the build status
[16:16:17.665] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:17.665] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:98:0) after 1m0s
[16:17:17.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:98:0)
[16:17:17.768] Timer tick, asking Buildbucket for the build status
[16:17:17.865] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:17.865] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:99:0) after 1m0s
[16:18:17.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:99:0)
[16:18:17.937] Timer tick, asking Buildbucket for the build status
[16:18:18.047] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:18.047] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:100:0) after 1m0s
[16:19:18.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:100:0)
[16:19:18.086] Timer tick, asking Buildbucket for the build status
[16:19:18.182] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:18.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:101:0) after 1m0s
[16:20:18.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:101:0)
[16:20:18.216] Timer tick, asking Buildbucket for the build status
[16:20:18.346] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:18.346] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:102:0) after 1m0s
[16:21:18.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:102:0)
[16:21:18.367] Timer tick, asking Buildbucket for the build status
[16:21:18.427] Build 8936577214726822496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:18.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102007277068554448:103:0) after 1m0s
[16:21:46.539] Received PubSub notification, asking Buildbucket for the build status
[16:21:46.645] Build 8936577214726822496: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:21:46.645] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1535818905019070",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535812855941630",
"failure_reason": "BUILD_FAILURE",
"id": "8936577214726822496",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2e95d045f7a3a5230f4f918100375d21cdf99e7d\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure compare_build_artifacts\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"failure\": {\"failure\": {\"step\": \"compare_build_artifacts\"}, \"humanReason\": \"Step('compare_build_artifacts') failed with return_code 1\"}}, \"recipeExitCode\": {\"value\": 1}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8936577214726822496/+/annotations\"}, \"swarming\": {\"bot_dimensions\": {\"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3740-90b257a\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"id\": [\"swarm2173-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"cb6ec4b1f91af259cbdfd1bd22bb98ed5551955e\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"2e95d045f7a3a5230f4f918100375d21cdf99e7d\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24472}\", \"$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@{#588285}\", \"branch\": \"refs/heads/master\", \"revision\": \"2e95d045f7a3a5230f4f918100375d21cdf99e7d\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"f18b35284288ac851b77db88df3e2e8d2273db97\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"88fc6da3e28c9abb47b5329e4f241dc3cb8e84d7\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1535812855941630, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/2e95d045f7a3a5230f4f918100375d21cdf99e7d\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2e95d045f7a3a5230f4f918100375d21cdf99e7d\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9102007277068554448\", \"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\": \"8936577214726822496\"}}, \"got_v8_revision\": \"e0a58f83255d1dae907e2ba4564ad8928a7dedf4\", \"got_v8_revision_cp\": \"refs/branch-heads/7.0@{#6}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9945, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"635504698c8e1cbfae24064df61b5cd2c88e9a8b\", \"notification_helper_unittests\": \"2908ad7fea4990ae88214bf28aebfce5be09986e\", \"gpu_perftests\": \"63554eb365d57150d22cc4963cd332a8c2c24fa1\", \"vr_common_unittests\": \"2915817301682de49a28777a3be0f7d963da026e\", \"telemetry_unittests\": \"f8e9efec46c21b55542c1c0cc3310cb0731f0ef4\", \"load_library_perf_tests\": \"0c3917fe7d19deacd1db8d34ca5b5f803eb9a29c\", \"devtools_closure_compile\": \"b34cca3078dd0fb087691b975f49b95513d53f95\", \"chromedriver_py_tests\": \"887a17390ba873f8673ecb81d85a7b911854ba87\", \"mojo_core_unittests\": \"41eefd3be52c0129c768278eaa0fb6ef82d32cd3\", \"install_static_unittests\": \"3a42bcfb0ed7838e5840c1086b5cb4dfdddb47f7\", \"leveldb_service_unittests\": \"4ab665f30f2f909ac2cf257a0e198d2b9565bc6a\", \"mini_installer_tests\": \"b506a0a8a86f976d488233cb62b334678f8840ce\", \"jingle_unittests\": \"5152d4cd350b4164a7d456684b19184cb11c6ff7\", \"sbox_integration_tests\": \"e063849e376c70bd70f872e9abb7f448430ed9f6\", \"angle_unittests\": \"128a57cda1332ed31ba6bd1713d1fd3324049e5d\", \"cast_unittests\": \"cdb70d60d2a42e3f248e6d442f944ce9b17d86b9\", \"cc_unittests\": \"903bde8e85529282da0c61dbcd5230182dddf0e6\", \"blink_common_unittests\": \"31ad90caeb94478341b71d795e9fc37a726ce909\", \"crashpad_tests\": \"d10f8506797ae1a7654db12811b7f699918f37a1\", \"telemetry_perf_tests_without_chrome\": \"5225458b5bf92387df5fd53f185d7f6e372d98f4\", \"metrics_python_tests\": \"a54ccbdcb57a0d4e104d4d9469fa880fb724183b\", \"message_center_unittests\": \"7302295480bf573527e8aea514a3cf84dca0c627\", \"ppapi_unittests\": \"bc08c5fe86eb4b240abb2a2bed776f8a2f513206\", \"extensions_unittests\": \"dd4eeadbd0473caf3325dde2be228c729870949d\", \"gl_unittests_ozone\": \"9d46212ebae58c9b017649bdd2faae3f21380e9c\", \"app_shell_unittests\": \"1413c0ecfc5d11301f1808b8363ae5b3f0461c41\", \"chrome_app_unittests\": \"60e463a8b0935c14acab950c36c66429a782b734\", \"gl_unittests\": \"9d46212ebae58c9b017649bdd2faae3f21380e9c\", \"gcm_unit_tests\": \"fd5fcf47d8e8595d070d598b43bdfb42e93d8d4f\", \"views_unittests\": \"b85aedb0059436768fcf4b2d9e997bd8bdda7c08\", \"content_shell_crash_test\": \"8ea677130a74f38b56734620a1e8b347357a2eb7\", \"ui_base_unittests\": \"13250b8f6e3ca26805346998d53775eaea2429d2\", \"gles2_conform_test\": \"6f8245b9e9d98a028b5529be65854305ba650c91\", \"cronet_unittests\": \"fefa44f37a5fa8f06e34872da830ab5bcb2f6271\", \"performance_test_suite\": \"477546ca593fa5e69e9d6b3f0531e72d4d0c9b41\", \"wm_unittests\": \"f758fa8a9d14428b353b77fe6576661fbbbe8744\", \"boringssl_crypto_tests\": \"9f8229d9c126f78ab1b2d0516c12406e2c42a72f\", \"gin_unittests\": \"291204f8a7cdb1795658c9f716431f93d027cf05\", \"performance_browser_tests\": \"5d4fb916fef074868d63bc53027e9fe1df0e7eea\", \"extensions_browsertests\": \"2f38fe9ca3384042c8e6fac365c0362d11b436f7\", \"webkit_python_tests\": \"acc53a78d44fcbdeb97af6a8be4ffaab8086f5c1\", \"headless_unittests\": \"db3223961d300c171e66d1dd47a584efce4c7021\", \"webkit_layout_tests\": \"168b3b880dfd4adf6a09d39e207a25d47aadba66\", \"vr_pixeltests\": \"e22f6ad3a78c5be87ca44cec5c5b1f981fe97ea2\", \"setup_unittests\": \"e2daf79a8e3fd9e25bbed3a97cb2215f3f0c700f\", \"webkit_unit_tests\": \"d381c68c25b79d0b2671d9b553aabd16b05188fd\", \"content_unittests\": \"7369957c0ace8dea4ff573df43933c6b6199e14c\", \"performance_webview_test_suite\": \"5a4ca736cb696a87c014b28fcbcdc214a48bfb25\", \"wtf_unittests\": \"94e4a4dfe5af6fb61aaffce085aa752e7efd889d\", \"webkit_layout_tests_exparchive\": \"4f363716e3535d00d1b7e5e6249cd6b031a2c18d\", \"chrome_cleaner_unittests\": \"cf4afe90da8a37052d0f0fe9a36b4944c2db2d91\", \"traffic_annotation_auditor_unittests\": \"b06eca1a3f2675ad846f211e4b47c574fcf72e50\", \"libjingle_xmpp_unittests\": \"83d95937cebf05f0a781eab7f483121759b7e0e7\", \"remoting_unittests\": \"5e1a3d119e260b2417ca4d09095d031efac73624\", \"vr_common_perftests\": \"2944e2d86ee1bbefa23cb091953892dd588b41fa\", \"filesystem_service_unittests\": \"b30d92383a61a12608014eb5e59483c079793695\", \"chrome_elf_import_unittests\": \"d48fbeb9a1d297a3c6cd8921243ed7a987085455\", \"installer_util_unittests\": \"6407f9cd212fa9021e00080e4966d2bb73ca4f56\", \"gfx_unittests\": \"7d9ae6f32e4e663512bf03bc531e19bb9c498435\", \"headless_browsertests\": \"8c77de30fb189bee9b52708d3eb0e2c0ef7e494e\", \"capture_unittests\": \"09a017ffff0061f8c56d21f2fb3b66e5f37d703f\", \"chrome_elf_unittests\": \"2e3e22f722a60b55673e3da224fd4e6ff468f949\", \"telemetry_gpu_unittests\": \"779ed602c7d9ab9f46e9b333bf61c506c6413751\", \"command_buffer_perftests\": \"ba3a4adbb08e1501793b6011e70d8180d8e87f47\", \"media_unittests\": \"6fc32e9e42c1be5769251f52da53914366cc5489\", \"chromedriver_unittests\": \"0b63459630c7d673e5e19f3f0528846e39d72382\", \"views_mus_interactive_ui_tests\": \"c23f8e50b0c9065c7cd3f403484091dce1730f06\", \"storage_unittests\": \"6bc825bd825ef0a5f610806be096a2d37a099a6d\", \"media_service_unittests\": \"e94027c5b5cd3dbab3b5c81ad6674f43f2158ab7\", \"compositor_unittests\": \"b08a5a5c99e965c720d83ee680b56cbe1fa50dd7\", \"latency_unittests\": \"e364c416091795448987a7ffe6e212a096ebdf40\", \"video_decode_accelerator_unittest\": \"aa2bd5672950e7bf05116a488a89b63a709d6c51\", \"swiftshader_unittests\": \"3ba7c0d18c32f34b9a8745848e37f18b276096db\", \"telemetry_perf_unittests\": \"563e67ee753ec0ed3e8178c6eb5ea6f5a68c615d\", \"media_blink_unittests\": \"8e406b8b5037873a36dfa5ba05a3fdfa6dac04ff\", \"views_mus_unittests\": \"138b4651719a9bea6e69ad2abd71b45b710eb8c4\", \"components_unittests\": \"6e325ce41104b26e4a50e7fff3822b2cb2b14e7c\", \"snapshot_unittests\": \"91ace6bcb618f5b6b9564ce073974b4a4795c0d8\", \"accessibility_unittests\": \"cb094fa3fb0b28fad0821d0b0972501237481c09\", \"net_perftests\": \"f9e66b996256b71ce2640c1152a295b0467f71d9\", \"aura_unittests\": \"9d6050a060ed21f1454264fb25b90308a1e2d226\", \"services_unittests\": \"6449489f5e1da3b48d497b93a310c4d441f2dd0c\", \"components_perftests\": \"fb13c1e414549a33b3a9c36677c5be0c57eff6eb\", \"events_unittests\": \"c90899f207a21c1f32a2baffe59f55b8e5f847b7\", \"tracing_perftests\": \"255bd980950ff598d5df5d84d98b3ae5d9bfd299\", \"chromedriver_replay_unittests\": \"e2ec5b5deadfbf1a0e00246e86e8f3e11e9cace0\", \"ui_touch_selection_unittests\": \"e3a5767b179ddd6f737d4350b8bb8b1540ba94ec\", \"cacheinvalidation_unittests\": \"d7df4006139baf0a7cc7690644d4991b67eb4e53\", \"viz_unittests\": \"ea33e4b9bd036e070c077a357bedcdb2fb7bf37c\", \"browser_tests\": \"26b313126d068eb2d5266d260b0cc272705f1872\", \"net_unittests\": \"d54de39545c695968f8f7f82bb17bec607b2cb50\", \"midi_unittests\": \"876dfe1f5984cdb3c70ac96ef62dbe4ca0ae49a2\", \"boringssl_ssl_tests\": \"626c84ee21eaa90916769e6944cbcb130f3d541d\", \"sbox_validation_tests\": \"00de59c0c34a14a1ca6c71c02114135dd2c89a4f\", \"elevation_service_unittests\": \"2263b5f8a888e2658b75f2e48de9ad2292cb5847\", \"gpu_unittests\": \"c5391bbd1e6f6b1cc9c4395f213d54c2892d70a9\", \"native_theme_unittests\": \"905128205818b1605fee1db0a0326d264c62aa34\", \"interactive_ui_tests\": \"ff16a786198a64694b79bf810db6e788796fd140\", \"mojo_unittests\": \"ff10eca20d912b8a8ec896c1c1b1c8804c5dda32\", \"url_unittests\": \"6b808abfda23252eaf5eb87f4ab28cd4a29de771\", \"media_perftests\": \"8ea078ec2608f0f88edf43f20dd9f3210ba92961\", \"cronet_tests\": \"46a45c6ecefc4a7bf9af0c98e4eb1fbdce60ada8\", \"google_apis_unittests\": \"31331b9b62fa38805b69dc99afeecc9364186e04\", \"telemetry_gpu_integration_test\": \"7e733f8d30e12f762f40e046f3541798e3cfaa6f\", \"blink_heap_unittests\": \"e49912a279c7939c597bd574b997bcd2d75aafcd\", \"base_unittests\": \"3cc215f86fce468ac2b359e71c2aeb6803d8e3f6\", \"sync_integration_tests\": \"f4d107901682fea950f28f9f47a0bf4762d302e4\", \"sql_unittests\": \"d4f9326b3b57051394fd400c6538703cbcb28bc5\", \"courgette_unittests\": \"c07e9ed4f7ec82f56dfcfd55bba180235ed5ceb8\", \"blink_fuzzer_unittests\": \"907df1716e8d48883ca1c29f91425aa7d33cdf3c\", \"gl_tests\": \"65c2def7bd1513316873c0ab6dd3a3602bfb9351\", \"zucchini_unittests\": \"e8a7254424dfff4ad5c7f6f06c0b7319f9f8216e\", \"ipc_tests\": \"491ca09e5f365e58fe8fa1b483482b3ab632d397\", \"angle_end2end_tests\": \"443e02aecc050ced557a5b037c37075f199b759a\", \"blink_platform_unittests\": \"7fccfdbe300fd787e5b24ce2969953fb5e6b0772\", \"components_browsertests\": \"5938eef296cf19507161d5b89bde9812f8e9d2a6\", \"views_perftests\": \"5425616f983c8d97140f4de04f436574e22c1687\", \"angle_perftests\": \"8821fe429541fa22328c62f3981adfd5029b61f5\", \"display_unittests\": \"676823087e9a117965ec32e781369d0153554c81\", \"sbox_unittests\": \"3415d8e6cfa5f13e645f4bc3e8da0364bfe3eae8\", \"service_manager_unittests\": \"3b3cab8bf99c95f3226a3872437280bddc2648bb\", \"pdf_unittests\": \"7167e7f44b58ec6abbcb458bd10f132714158f26\", \"skia_unittests\": \"7e115f3cbcdff9c3d6c5e490229e214db1ad4552\", \"angle_white_box_tests\": \"20172edbaa224625789f4f00eca62d00e8871e73\", \"crypto_unittests\": \"5266c8f98113190a375974ecd713aafb03bb3615\", \"nacl_loader_unittests\": \"8a54fc1b2d10814c9ff3360d2f26bcf61f3211aa\", \"unit_tests\": \"ddb23f5b2f32c1d05b2d611f2588a526698a214d\", \"content_browsertests\": \"a25ffc6589d2fe7a6d9a88b059ae3a8d0f720a9a\", \"printing_unittests\": \"95916b6bd70659a3e4d2d21838949edb978b95e9\", \"device_unittests\": \"f59d2daae5dec5ed8de92da434af4d72f353c4d0\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1535812939553630",
"status": "COMPLETED",
"status_changed_ts": "1535818906073340",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9945",
"builder:Windows deterministic",
"buildset:commit/git/2e95d045f7a3a5230f4f918100375d21cdf99e7d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2e95d045f7a3a5230f4f918100375d21cdf99e7d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9102007277068554448",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/9945",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936577214726822496",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/2e95d045f7a3a5230f4f918100375d21cdf99e7d",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2e95d045f7a3a5230f4f918100375d21cdf99e7d",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8936577214726822496/+/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:9102007277068554448",
"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:7a77c69d050faf57eaf65e8f6cb7422326b6b83c",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fae65f36d512010",
"user_agent:luci-scheduler"
],
"updated_ts": "1535818906076520",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9945",
"utcnow_ts": "1535818906564440"
}
}
[16:21:46.645] Invocation finished in 1h40m52.321001527s with status FAILED