[05:49:46.361] New invocation is queued and will start shortly
[05:49:47.632] Starting the invocation (attempt 1)
[05:49:47.750] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:49:47.750] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:49:47.750] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9099413381099114000",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"c42ee16bf7c22c3bf45a18f267b197a1ef1d2864\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9099413381099114000",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/c42ee16bf7c22c3bf45a18f267b197a1ef1d2864",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c42ee16bf7c22c3bf45a18f267b197a1ef1d2864",
"gitiles_ref:refs/heads/master"
]
}
[05:49:48.912] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1538286587823517",
"id": "8933983318845257632",
"lease_expiration_ts": "1538430588656055",
"lease_key": "1866325673",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c42ee16bf7c22c3bf45a18f267b197a1ef1d2864\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1538286588832876",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10245",
"builder:Windows deterministic",
"buildset:commit/git/c42ee16bf7c22c3bf45a18f267b197a1ef1d2864",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c42ee16bf7c22c3bf45a18f267b197a1ef1d2864",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9099413381099114000",
"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/10245",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8933983318845257632",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/c42ee16bf7c22c3bf45a18f267b197a1ef1d2864",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c42ee16bf7c22c3bf45a18f267b197a1ef1d2864",
"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/8933983318845257632/+/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:9099413381099114000",
"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:216ff455080a4f94ea455f4ef2ee62b8417f1092",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4041d819ce841610",
"user_agent:luci-scheduler"
],
"updated_ts": "1538286588832975",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10245",
"utcnow_ts": "1538286588903206"
}
}
[05:49:48.912] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10245
[05:49:48.912] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:2:0) after 1m0s
[05:49:51.374] Received PubSub notification, asking Buildbucket for the build status
[05:49:51.423] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:49.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:2:0)
[05:50:49.035] Timer tick, asking Buildbucket for the build status
[05:50:49.183] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:49.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:4:0) after 1m0s
[05:51:49.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:4:0)
[05:51:49.197] Timer tick, asking Buildbucket for the build status
[05:51:49.289] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:49.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:5:0) after 1m0s
[05:52:49.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:5:0)
[05:52:49.311] Timer tick, asking Buildbucket for the build status
[05:52:49.383] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:49.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:6:0) after 1m0s
[05:53:49.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:6:0)
[05:53:49.397] Timer tick, asking Buildbucket for the build status
[05:53:49.451] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:49.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:7:0) after 1m0s
[05:54:49.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:7:0)
[05:54:49.583] Timer tick, asking Buildbucket for the build status
[05:54:49.642] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:49.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:8:0) after 1m0s
[05:55:49.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:8:0)
[05:55:49.697] Timer tick, asking Buildbucket for the build status
[05:55:49.855] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:49.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:9:0) after 1m0s
[05:56:49.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:9:0)
[05:56:49.982] Timer tick, asking Buildbucket for the build status
[05:56:50.045] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:50.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:10:0) after 1m0s
[05:57:50.155] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:10:0)
[05:57:50.155] Timer tick, asking Buildbucket for the build status
[05:57:50.194] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:50.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:11:0) after 1m0s
[05:58:50.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:11:0)
[05:58:50.211] Timer tick, asking Buildbucket for the build status
[05:58:50.377] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:50.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:12:0) after 1m0s
[05:59:50.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:12:0)
[05:59:50.412] Timer tick, asking Buildbucket for the build status
[05:59:50.615] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:50.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:13:0) after 1m0s
[06:00:50.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:13:0)
[06:00:50.631] Timer tick, asking Buildbucket for the build status
[06:00:50.735] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:50.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:14:0) after 1m0s
[06:01:50.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:14:0)
[06:01:50.752] Timer tick, asking Buildbucket for the build status
[06:01:50.808] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:50.808] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:15:0) after 1m0s
[06:02:50.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:15:0)
[06:02:50.830] Timer tick, asking Buildbucket for the build status
[06:02:50.899] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:50.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:16:0) after 1m0s
[06:03:50.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:16:0)
[06:03:50.916] Timer tick, asking Buildbucket for the build status
[06:03:51.070] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:51.070] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:17:0) after 1m0s
[06:04:51.088] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:17:0)
[06:04:51.088] Timer tick, asking Buildbucket for the build status
[06:04:51.253] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:51.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:18:0) after 1m0s
[06:05:51.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:18:0)
[06:05:51.272] Timer tick, asking Buildbucket for the build status
[06:05:51.456] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:51.456] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:19:0) after 1m0s
[06:06:51.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:19:0)
[06:06:51.478] Timer tick, asking Buildbucket for the build status
[06:06:51.828] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:51.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:20:0) after 1m0s
[06:07:51.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:20:0)
[06:07:51.969] Timer tick, asking Buildbucket for the build status
[06:07:52.035] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:52.035] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:21:0) after 1m0s
[06:08:52.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:21:0)
[06:08:52.164] Timer tick, asking Buildbucket for the build status
[06:08:52.220] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:52.220] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:22:0) after 1m0s
[06:09:52.354] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:22:0)
[06:09:52.354] Timer tick, asking Buildbucket for the build status
[06:09:52.489] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:52.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:23:0) after 1m0s
[06:10:52.578] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:23:0)
[06:10:52.578] Timer tick, asking Buildbucket for the build status
[06:10:52.719] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:52.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:24:0) after 1m0s
[06:11:52.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:24:0)
[06:11:52.822] Timer tick, asking Buildbucket for the build status
[06:11:52.881] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:52.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:25:0) after 1m0s
[06:12:53.026] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:25:0)
[06:12:53.026] Timer tick, asking Buildbucket for the build status
[06:12:53.149] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:53.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:26:0) after 1m0s
[06:13:53.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:26:0)
[06:13:53.195] Timer tick, asking Buildbucket for the build status
[06:13:53.266] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:53.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:27:0) after 1m0s
[06:14:53.321] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:27:0)
[06:14:53.321] Timer tick, asking Buildbucket for the build status
[06:14:53.397] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:53.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:28:0) after 1m0s
[06:15:53.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:28:0)
[06:15:53.430] Timer tick, asking Buildbucket for the build status
[06:15:53.489] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:53.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:29:0) after 1m0s
[06:16:53.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:29:0)
[06:16:53.507] Timer tick, asking Buildbucket for the build status
[06:16:53.618] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:53.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:30:0) after 1m0s
[06:17:53.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:30:0)
[06:17:53.645] Timer tick, asking Buildbucket for the build status
[06:17:53.794] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:53.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:31:0) after 1m0s
[06:18:53.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:31:0)
[06:18:53.813] Timer tick, asking Buildbucket for the build status
[06:18:53.908] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:53.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:32:0) after 1m0s
[06:19:53.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:32:0)
[06:19:53.925] Timer tick, asking Buildbucket for the build status
[06:19:53.964] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:53.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:33:0) after 1m0s
[06:20:53.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:33:0)
[06:20:53.982] Timer tick, asking Buildbucket for the build status
[06:20:54.058] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:54.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:34:0) after 1m0s
[06:21:54.080] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:34:0)
[06:21:54.080] Timer tick, asking Buildbucket for the build status
[06:21:54.224] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:54.224] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:35:0) after 1m0s
[06:22:54.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:35:0)
[06:22:54.239] Timer tick, asking Buildbucket for the build status
[06:22:54.277] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:54.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:36:0) after 1m0s
[06:23:54.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:36:0)
[06:23:54.296] Timer tick, asking Buildbucket for the build status
[06:23:54.360] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:54.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:37:0) after 1m0s
[06:24:54.392] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:37:0)
[06:24:54.392] Timer tick, asking Buildbucket for the build status
[06:24:54.576] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:54.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:38:0) after 1m0s
[06:25:54.595] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:38:0)
[06:25:54.595] Timer tick, asking Buildbucket for the build status
[06:25:54.980] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:54.980] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:39:0) after 1m0s
[06:26:55.063] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:39:0)
[06:26:55.063] Timer tick, asking Buildbucket for the build status
[06:26:55.217] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:55.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:40:0) after 1m0s
[06:27:55.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:40:0)
[06:27:55.275] Timer tick, asking Buildbucket for the build status
[06:27:55.311] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:55.311] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:41:0) after 1m0s
[06:28:55.327] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:41:0)
[06:28:55.327] Timer tick, asking Buildbucket for the build status
[06:28:55.496] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:55.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:42:0) after 1m0s
[06:29:55.517] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:42:0)
[06:29:55.517] Timer tick, asking Buildbucket for the build status
[06:29:55.643] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:55.643] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:43:0) after 1m0s
[06:30:55.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:43:0)
[06:30:55.671] Timer tick, asking Buildbucket for the build status
[06:30:56.112] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:56.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:44:0) after 1m0s
[06:31:56.133] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:44:0)
[06:31:56.133] Timer tick, asking Buildbucket for the build status
[06:31:56.187] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:56.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:45:0) after 1m0s
[06:32:56.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:45:0)
[06:32:56.371] Timer tick, asking Buildbucket for the build status
[06:32:56.605] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:56.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:46:0) after 1m0s
[06:33:56.624] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:46:0)
[06:33:56.624] Timer tick, asking Buildbucket for the build status
[06:33:56.786] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:56.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:47:0) after 1m0s
[06:34:56.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:47:0)
[06:34:56.826] Timer tick, asking Buildbucket for the build status
[06:34:56.917] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:56.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:48:0) after 1m0s
[06:35:56.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:48:0)
[06:35:56.944] Timer tick, asking Buildbucket for the build status
[06:35:57.045] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:57.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:49:0) after 1m0s
[06:36:57.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:49:0)
[06:36:57.068] Timer tick, asking Buildbucket for the build status
[06:36:57.138] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:57.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:50:0) after 1m0s
[06:37:57.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:50:0)
[06:37:57.169] Timer tick, asking Buildbucket for the build status
[06:37:57.303] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:57.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:51:0) after 1m0s
[06:38:57.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:51:0)
[06:38:57.335] Timer tick, asking Buildbucket for the build status
[06:38:57.401] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:57.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:52:0) after 1m0s
[06:39:57.425] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:52:0)
[06:39:57.425] Timer tick, asking Buildbucket for the build status
[06:39:58.460] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:58.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:53:0) after 1m0s
[06:40:58.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:53:0)
[06:40:58.495] Timer tick, asking Buildbucket for the build status
[06:40:58.636] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:58.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:54:0) after 1m0s
[06:41:58.656] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:54:0)
[06:41:58.656] Timer tick, asking Buildbucket for the build status
[06:41:58.708] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:58.708] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:55:0) after 1m0s
[06:42:58.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:55:0)
[06:42:58.736] Timer tick, asking Buildbucket for the build status
[06:42:58.835] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:58.835] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:56:0) after 1m0s
[06:43:58.874] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:56:0)
[06:43:58.874] Timer tick, asking Buildbucket for the build status
[06:43:58.931] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:58.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:57:0) after 1m0s
[06:44:58.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:57:0)
[06:44:58.951] Timer tick, asking Buildbucket for the build status
[06:44:59.012] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:59.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:58:0) after 1m0s
[06:45:59.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:58:0)
[06:45:59.048] Timer tick, asking Buildbucket for the build status
[06:45:59.112] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:59.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:59:0) after 1m0s
[06:46:59.130] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:59:0)
[06:46:59.130] Timer tick, asking Buildbucket for the build status
[06:46:59.338] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:59.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:60:0) after 1m0s
[06:47:59.358] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:60:0)
[06:47:59.358] Timer tick, asking Buildbucket for the build status
[06:47:59.981] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:59.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:61:0) after 1m0s
[06:49:00.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:61:0)
[06:49:00.002] Timer tick, asking Buildbucket for the build status
[06:49:00.085] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:00.085] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:62:0) after 1m0s
[06:50:00.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:62:0)
[06:50:00.230] Timer tick, asking Buildbucket for the build status
[06:50:00.402] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:00.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:63:0) after 1m0s
[06:51:00.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:63:0)
[06:51:00.438] Timer tick, asking Buildbucket for the build status
[06:51:00.516] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:00.516] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:64:0) after 1m0s
[06:52:00.540] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:64:0)
[06:52:00.540] Timer tick, asking Buildbucket for the build status
[06:52:02.484] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:02.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:65:0) after 1m0s
[06:53:02.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:65:0)
[06:53:02.501] Timer tick, asking Buildbucket for the build status
[06:53:02.612] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:02.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:66:0) after 1m0s
[06:54:02.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:66:0)
[06:54:02.679] Timer tick, asking Buildbucket for the build status
[06:54:02.786] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:02.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:67:0) after 1m0s
[06:55:02.810] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:67:0)
[06:55:02.810] Timer tick, asking Buildbucket for the build status
[06:55:02.960] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:02.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:68:0) after 1m0s
[06:56:02.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:68:0)
[06:56:02.978] Timer tick, asking Buildbucket for the build status
[06:56:03.053] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:03.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:69:0) after 1m0s
[06:57:03.073] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:69:0)
[06:57:03.073] Timer tick, asking Buildbucket for the build status
[06:57:03.125] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:03.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:70:0) after 1m0s
[06:58:03.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:70:0)
[06:58:03.147] Timer tick, asking Buildbucket for the build status
[06:58:03.198] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:03.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:71:0) after 1m0s
[06:59:03.220] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:71:0)
[06:59:03.220] Timer tick, asking Buildbucket for the build status
[06:59:03.395] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:03.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:72:0) after 1m0s
[07:00:03.416] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:72:0)
[07:00:03.416] Timer tick, asking Buildbucket for the build status
[07:00:04.829] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:04.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:73:0) after 1m0s
[07:01:04.847] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:73:0)
[07:01:04.847] Timer tick, asking Buildbucket for the build status
[07:01:05.085] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:05.085] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:74:0) after 1m0s
[07:02:05.111] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:74:0)
[07:02:05.111] Timer tick, asking Buildbucket for the build status
[07:02:05.163] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:05.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:75:0) after 1m0s
[07:03:05.338] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:75:0)
[07:03:05.338] Timer tick, asking Buildbucket for the build status
[07:03:05.470] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:05.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:76:0) after 1m0s
[07:04:05.487] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:76:0)
[07:04:05.487] Timer tick, asking Buildbucket for the build status
[07:04:05.741] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:05.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:77:0) after 1m0s
[07:05:05.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:77:0)
[07:05:05.772] Timer tick, asking Buildbucket for the build status
[07:05:05.881] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:05.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:78:0) after 1m0s
[07:06:05.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:78:0)
[07:06:05.964] Timer tick, asking Buildbucket for the build status
[07:06:06.033] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:06.033] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:79:0) after 1m0s
[07:07:06.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:79:0)
[07:07:06.144] Timer tick, asking Buildbucket for the build status
[07:07:06.216] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:06.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:80:0) after 1m0s
[07:08:06.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:80:0)
[07:08:06.240] Timer tick, asking Buildbucket for the build status
[07:08:06.384] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:06.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:81:0) after 1m0s
[07:09:06.407] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:81:0)
[07:09:06.407] Timer tick, asking Buildbucket for the build status
[07:09:06.571] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:06.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:82:0) after 1m0s
[07:10:06.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:82:0)
[07:10:06.654] Timer tick, asking Buildbucket for the build status
[07:10:06.808] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:06.808] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:83:0) after 1m0s
[07:11:06.864] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:83:0)
[07:11:06.864] Timer tick, asking Buildbucket for the build status
[07:11:06.926] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:06.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:84:0) after 1m0s
[07:12:06.949] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:84:0)
[07:12:06.949] Timer tick, asking Buildbucket for the build status
[07:12:07.071] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:07.071] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:85:0) after 1m0s
[07:13:07.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:85:0)
[07:13:07.092] Timer tick, asking Buildbucket for the build status
[07:13:07.777] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:07.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:86:0) after 1m0s
[07:14:07.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:86:0)
[07:14:07.807] Timer tick, asking Buildbucket for the build status
[07:14:08.567] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:08.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:87:0) after 1m0s
[07:15:08.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:87:0)
[07:15:08.591] Timer tick, asking Buildbucket for the build status
[07:15:08.800] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:08.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:88:0) after 1m0s
[07:16:08.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:88:0)
[07:16:08.818] Timer tick, asking Buildbucket for the build status
[07:16:08.942] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:08.942] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:89:0) after 1m0s
[07:17:08.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:89:0)
[07:17:08.963] Timer tick, asking Buildbucket for the build status
[07:17:09.117] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:09.117] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:90:0) after 1m0s
[07:18:09.139] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:90:0)
[07:18:09.139] Timer tick, asking Buildbucket for the build status
[07:18:09.204] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:09.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:91:0) after 1m0s
[07:19:09.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:91:0)
[07:19:09.229] Timer tick, asking Buildbucket for the build status
[07:19:09.265] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:09.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:92:0) after 1m0s
[07:20:09.323] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:92:0)
[07:20:09.323] Timer tick, asking Buildbucket for the build status
[07:20:09.410] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:09.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:93:0) after 1m0s
[07:21:09.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:93:0)
[07:21:09.430] Timer tick, asking Buildbucket for the build status
[07:21:09.494] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:09.494] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:94:0) after 1m0s
[07:22:09.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:94:0)
[07:22:09.514] Timer tick, asking Buildbucket for the build status
[07:22:09.589] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:09.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:95:0) after 1m0s
[07:23:09.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:95:0)
[07:23:09.611] Timer tick, asking Buildbucket for the build status
[07:23:09.679] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:09.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:96:0) after 1m0s
[07:24:09.707] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:96:0)
[07:24:09.707] Timer tick, asking Buildbucket for the build status
[07:24:09.780] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:09.780] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:97:0) after 1m0s
[07:25:09.859] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:97:0)
[07:25:09.859] Timer tick, asking Buildbucket for the build status
[07:25:09.938] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:09.938] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:98:0) after 1m0s
[07:26:09.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:98:0)
[07:26:09.972] Timer tick, asking Buildbucket for the build status
[07:26:10.076] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:10.076] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:99:0) after 1m0s
[07:27:10.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:99:0)
[07:27:10.097] Timer tick, asking Buildbucket for the build status
[07:27:10.134] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:10.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:100:0) after 1m0s
[07:28:10.177] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:100:0)
[07:28:10.177] Timer tick, asking Buildbucket for the build status
[07:28:10.358] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:10.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:101:0) after 1m0s
[07:29:10.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:101:0)
[07:29:10.467] Timer tick, asking Buildbucket for the build status
[07:29:10.614] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:10.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:102:0) after 1m0s
[07:30:10.635] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:102:0)
[07:30:10.635] Timer tick, asking Buildbucket for the build status
[07:30:10.671] Build 8933983318845257632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:10.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099413381099114000:103:0) after 1m0s
[07:30:33.762] Received PubSub notification, asking Buildbucket for the build status
[07:30:33.806] Build 8933983318845257632: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:30:33.806] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1538292632378614",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1538286587823517",
"id": "8933983318845257632",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c42ee16bf7c22c3bf45a18f267b197a1ef1d2864\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"jsonResult\": \"null\"}, \"recipeExitCode\": {\"value\": 0}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8933983318845257632/+/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\": [\"3793-c7dcd56\"], \"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\": \"2ebbdab4bfb1ed9dfe52ce756b34829207dbe25f\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"c42ee16bf7c22c3bf45a18f267b197a1ef1d2864\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24875}\", \"$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@{#595340}\", \"branch\": \"refs/heads/master\", \"revision\": \"c42ee16bf7c22c3bf45a18f267b197a1ef1d2864\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"1ee9160a2e0bc6381caca2b8c42f7ce5507619bc\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"79207e6e11df06880ff0a9071479b2903d46d1b1\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1538286587823517, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/c42ee16bf7c22c3bf45a18f267b197a1ef1d2864\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c42ee16bf7c22c3bf45a18f267b197a1ef1d2864\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9099413381099114000\", \"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\": \"8933983318845257632\"}}, \"got_v8_revision\": \"a733a4e00dd69628680e1ae36fee0be97cf3ad73\", \"got_v8_revision_cp\": \"refs/heads/7.1.215@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10245, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"8fd217f2189e07f619e7512cbc81d9e0cc95f4c5\", \"notification_helper_unittests\": \"23e138a52d89ecf8e9bd88803ba9963ee7e68ffa\", \"gpu_perftests\": \"9b21bc108fe6100468fc0917fc0300ea277873f6\", \"vr_common_unittests\": \"0b4aa5880432020d41529307dec6810016242c46\", \"telemetry_unittests\": \"0ceae54e1596c3c938c8178ed073e42e13dc109d\", \"load_library_perf_tests\": \"cd9a397025594adfca72920de127a63df16a227f\", \"devtools_closure_compile\": \"47b1e90c378ab4480e13476081e61e888c71bb2f\", \"chromedriver_py_tests\": \"c6b5cfc4646e7c03debfe7c3d74b48564e361bb6\", \"mojo_core_unittests\": \"ff4e2b7d10b4ce2fc80ff024e1a59df658619e48\", \"install_static_unittests\": \"19a34fa22103daa1d3dd9a53fa16429566a158ba\", \"leveldb_service_unittests\": \"caf8538344d02f14727f20ecc1b2ecc661e47e33\", \"mini_installer_tests\": \"1aac4776e8e6b9c5ee4a6a4ed2c1816d626a5979\", \"jingle_unittests\": \"ce6d2f67abb3b668b387843c36e9f8cea2cac82b\", \"sbox_integration_tests\": \"74115a2b190de5200a1fab348dc3952a262699ce\", \"angle_unittests\": \"7b585b4d4d1e33540dc926a09ac683521a5fe6a2\", \"cast_unittests\": \"912f73a4673b0c0e096555199fbe5b2850f055fb\", \"cc_unittests\": \"88cfab7a8eb89c391807fa6d79c7f330ab42a548\", \"blink_common_unittests\": \"7cd1e5bccc3a32f6c0a41df8822b2012b5e909ef\", \"crashpad_tests\": \"3817c8acc79062efe73e2a0cef85d54f93786f24\", \"telemetry_perf_tests_without_chrome\": \"f876006177ba06f1692309d9e4f8abb1e7bdf0f6\", \"metrics_python_tests\": \"f95b0e49247ef3a6ca3e61b77ec9e90ea94bdf49\", \"message_center_unittests\": \"e4f1639b8f5b483fcac5adab37c19e1eb333a564\", \"ppapi_unittests\": \"20e22378e6663e851102e721591b3f696476159e\", \"extensions_unittests\": \"df33e074290726b80703454bf8a3096762383697\", \"gl_unittests_ozone\": \"5f832f85d77b7ea808530d00e0da45680c2a8e67\", \"app_shell_unittests\": \"332a73bb32afc04d5005ddd8272e4d140f985ad0\", \"chrome_app_unittests\": \"d71c0d33e8a970e56aa9e752610670f4fd7190b0\", \"gl_unittests\": \"5f832f85d77b7ea808530d00e0da45680c2a8e67\", \"gcm_unit_tests\": \"d4360341a04dbd288477803abce962aac960da81\", \"views_unittests\": \"0cad9fd2859b88d75c05bea14ebd3a3cabc99d55\", \"content_shell_crash_test\": \"b6a1685dcac3411c2f547b010a77fd0301122f53\", \"ui_base_unittests\": \"8925ad371508a8a744263b24c795402916f1f4ec\", \"gles2_conform_test\": \"d83e1fe62426e7fb1397def8837b4aba170a2705\", \"cronet_unittests\": \"4d4b2df0e96b4ed508a3d1c4ba770882b8ea969d\", \"performance_test_suite\": \"e4913a27a6a789bd418cebbd30eec0bd9e158241\", \"wm_unittests\": \"742aa148ac00bbf1b39aa00e3b9817cf6c74acb1\", \"boringssl_crypto_tests\": \"9586c7c633ea9bfeb831cb37c1c2da88d26c847b\", \"gin_unittests\": \"185b150147fd6874233eeafbd7844232eda65502\", \"performance_browser_tests\": \"6c6090c7803e8acf7d2fa70dd7b9dea1ea5b3793\", \"extensions_browsertests\": \"cae1779e64c8f48d6b735edfc67bf57d55811212\", \"webkit_python_tests\": \"58bc7fb9a25b1b59be5f58c85ad5f8d0edf17dfe\", \"headless_unittests\": \"c98fc2105aaa2eb99287033a0852a259bb1cfef4\", \"webkit_layout_tests\": \"bd003a81bc97107f14edaf9b598d18983016fe55\", \"vr_pixeltests\": \"fce547a2a1a0bd6af00e6cbef69bcd147e9b12f3\", \"setup_unittests\": \"67c39e30e15297f09d40e5b68d697a672ca22a19\", \"webkit_unit_tests\": \"505109bd257ee00c9590bb834970dcbb0510b9ff\", \"content_unittests\": \"5e17422fabf0bfd3c0777e589d4438ad039b75aa\", \"performance_webview_test_suite\": \"e55ed4e42aad40108933492a888d9faa24922b8d\", \"wtf_unittests\": \"aab7f8f0d2ef34ead6b8737ad41d180e4b436fc9\", \"webkit_layout_tests_exparchive\": \"4de26771d2d6a1fdae3bc467843662d2c9179f41\", \"chrome_cleaner_unittests\": \"3a166a551b7a5fa39968799f7fc5e5c492b00fc3\", \"traffic_annotation_auditor_unittests\": \"4a1f1090a07d5655d50b45e85f162e4a52d1af25\", \"libjingle_xmpp_unittests\": \"86959065ea778de1dca8bddb86ae8116796cc2d9\", \"remoting_unittests\": \"fb1794e86e521763b198dbbdb2136de24a852d9a\", \"vr_common_perftests\": \"4581712789c7e34fcbe31bd4de8031ace7227142\", \"filesystem_service_unittests\": \"9da65b026e9bd791e35cfa4f6ca9c47e1d7b34f2\", \"chrome_elf_import_unittests\": \"b7fe6024e3a09951cb30330d21a4cd594a235fb4\", \"installer_util_unittests\": \"948e79850892f00b5c5d570c3501165cc3714108\", \"gfx_unittests\": \"40893f9f4e0e05c460152226d8ba230b3de90a54\", \"headless_browsertests\": \"eef62e61984f8f4ebed0bb6dc359381ceeff6828\", \"capture_unittests\": \"887e0b4d4226f91c1bc90f86aa942ec93085241e\", \"chrome_elf_unittests\": \"73bae71af37afc10995cb38167c088f61972f0ad\", \"telemetry_gpu_unittests\": \"467b65ba18331a11970ae9c4fbc3524f19666c98\", \"command_buffer_perftests\": \"5674b03092562385c50fa67b87da6b65b4e352c4\", \"media_unittests\": \"8a546cb76a989242a47cae63085826c8fa23d851\", \"chromedriver_unittests\": \"d72f28e65851004f258ca060aefd31ce8ab1f43f\", \"views_mus_interactive_ui_tests\": \"ebd74c5f3aed63e92219351d67bf172a579729bb\", \"storage_unittests\": \"22b9d4f9c2afd7a6fa7efef3f07d64e8dfae3153\", \"media_service_unittests\": \"6fa793bbf2a162fd6a9fc0ffc04125ced279021b\", \"compositor_unittests\": \"0868f4f56651985f36e05824b853ab81a8dc3351\", \"latency_unittests\": \"fc07cd2d2399f138ae1167f341a8799070f45765\", \"video_decode_accelerator_unittest\": \"175d6599907fe4dc759d9dff73758294eae88a2e\", \"swiftshader_unittests\": \"3215d7f389f1414702f29d93246237cc5ed9956d\", \"telemetry_perf_unittests\": \"5ca54a2388bf88460dee9bddb5da5d29e9ba4077\", \"media_blink_unittests\": \"74f663cf111a8df3baf48e099646e8f765c9a4ff\", \"views_mus_unittests\": \"70f0400a0bf3f8c526814cf8817a85d9068805e2\", \"components_unittests\": \"04d6645b3b6e20ff14bb1ddd601b0d803900f61d\", \"snapshot_unittests\": \"25799f153eb43204ee40829d202f2b395d189811\", \"accessibility_unittests\": \"559c303d8f8583c9f7bb01eaa15d9053f7df047e\", \"net_perftests\": \"e9cce5f2f68cac044fb111851ecda0fecbfc2f30\", \"aura_unittests\": \"0d701902ffaf62ffcc00666c403d94690f613f58\", \"services_unittests\": \"7f499e20a3fbcaea29f253fc2435ebff75b2e770\", \"components_perftests\": \"0895486f273cd158ee77f218b6a12386dcf704fd\", \"events_unittests\": \"4ac397de127ad40c2a1cf947533a2dce0e7f7b18\", \"tracing_perftests\": \"a73f934a951bb56bf28054e3f8a172ead667b88a\", \"chromedriver_replay_unittests\": \"50c6aef6c493544165526b2b516579428eb4b618\", \"ui_touch_selection_unittests\": \"d7dde74e7885e4110a625a82d0e09512675809c0\", \"cacheinvalidation_unittests\": \"ea660eb18ec0d16c6da9759d386b512742397924\", \"viz_unittests\": \"37095e4a22615ddacf68109017daa8edcdd87751\", \"browser_tests\": \"9a70f83734106f1e43d6bf92323c82684e65e26f\", \"net_unittests\": \"8d823896fe435907b27b008c818c25752f48625a\", \"midi_unittests\": \"f74d5c8d0aed098ae1d0eb038990c246e1f555c5\", \"boringssl_ssl_tests\": \"100e3ea281b8cdbcb4128b9a9e30b3afef0f3de0\", \"sbox_validation_tests\": \"a87ee11fb6fc963031d4eb12d07faac6855a2a01\", \"elevation_service_unittests\": \"a2689ad29037c89ae1a9ee0ee3537204900f639d\", \"gpu_unittests\": \"f0f5d9c7d915f3044be3176d3a4e9782bf095164\", \"native_theme_unittests\": \"7b9816040ddd12b55589cd76985c089105107351\", \"interactive_ui_tests\": \"9e9dba335b8e180f4b0f5fa16bd5864d9ee1a8d5\", \"mojo_unittests\": \"05574427dc7c2308de7fc598b05fcbdd761fb0dd\", \"url_unittests\": \"18f82182a87727c02708ce5e894958281416d43b\", \"media_perftests\": \"7ff228830ed48240379fd6b19060b135a44bafdd\", \"cronet_tests\": \"38b944a12d18560eb25f9341dfe7e01b83540b30\", \"google_apis_unittests\": \"06f3fbbb414ad19047fbbc5c7c5c0de29c29d57c\", \"telemetry_gpu_integration_test\": \"ea898dd89413f03963ad215aaf0b72d1a87baa3e\", \"blink_heap_unittests\": \"9ef9eacdf6adb38771e340a7cfcd394076ab01ff\", \"base_unittests\": \"585e8d2d1a59f1d7588a0393442cc00309d98b26\", \"sync_integration_tests\": \"89fe705f6017327d29b2fae0ee8b9cf6731f9a90\", \"sql_unittests\": \"6ef5ec0b984c77d3244a576d56a7a24ab7c31e7c\", \"courgette_unittests\": \"732de7fc3a356b0b3b8cc7869a65f36721f63965\", \"blink_fuzzer_unittests\": \"4d8fe1305afa0ce957aeb4ef8dbe2e178279a18f\", \"gl_tests\": \"543c2c92c36d81b78ea4f65a6458cdfc2172e453\", \"zucchini_unittests\": \"7f217fd7ac545e379d27baf2fe8cd47afdfb9605\", \"ipc_tests\": \"891bef7439f2873ab8cb746db00ea367a9881c73\", \"angle_end2end_tests\": \"4c63c956d6e4513bc8c416431ed08936a418053a\", \"blink_platform_unittests\": \"b3ba4124da2860b90cf1df300e7c790eb718956b\", \"components_browsertests\": \"6bfb3dfe3e2148cd3aaec464c73780f7f17a0805\", \"views_perftests\": \"fd0eb60a81cc27d9ee3c6b833955c5d50963d191\", \"angle_perftests\": \"3f1afc9b83d41f03f18def00563724d40ec0510b\", \"display_unittests\": \"8ee31b2d13e4191360e686b910307e9b7b98a2af\", \"sbox_unittests\": \"d77d22016ba8a6d1da09f2c9846422db161699df\", \"service_manager_unittests\": \"a68618fc26abad8f4862b323b99423bca77647b3\", \"pdf_unittests\": \"07efe1b056dc2ca2de7ffb53edd0f47035007e19\", \"skia_unittests\": \"84a822736229a60a0dab0a0d7d058047e1feda5d\", \"angle_white_box_tests\": \"cde1848b4b02500343b2ab16fd7eb203d5635d9e\", \"crypto_unittests\": \"1f02511a73c752fa3d805b2aae0dbe01033b8242\", \"nacl_loader_unittests\": \"7b51f0d0ed0f72fbfce11da3f99f9a9adee4290e\", \"unit_tests\": \"907c789be15b8a20e2b153bb13c79f695936a438\", \"content_browsertests\": \"41bf04275ac56dcd6fede39a152459206e45255e\", \"printing_unittests\": \"4c0f960581c0b4357aab22e477be7bfb9e4dd823\", \"device_unittests\": \"5b6b2f758cb47ab42573652a42298c6a7c1017d9\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1538286589840944",
"status": "COMPLETED",
"status_changed_ts": "1538292632989314",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10245",
"builder:Windows deterministic",
"buildset:commit/git/c42ee16bf7c22c3bf45a18f267b197a1ef1d2864",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c42ee16bf7c22c3bf45a18f267b197a1ef1d2864",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9099413381099114000",
"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/10245",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8933983318845257632",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/c42ee16bf7c22c3bf45a18f267b197a1ef1d2864",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c42ee16bf7c22c3bf45a18f267b197a1ef1d2864",
"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/8933983318845257632/+/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:9099413381099114000",
"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:216ff455080a4f94ea455f4ef2ee62b8417f1092",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4041d819ce841610",
"user_agent:luci-scheduler"
],
"updated_ts": "1538292632991404",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10245",
"utcnow_ts": "1538292633794071"
}
}
[07:30:33.806] Invocation finished in 1h40m47.516293298s with status SUCCEEDED