[14:53:16.252] New invocation is queued and will start shortly
[14:53:17.546] Starting the invocation (attempt 1)
[14:53:17.584] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:53:17.584] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:53:17.584] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9101191126423522528",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"1fac2bcb83a6898e81f4845cf634f132f855bc23\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9101191126423522528",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/1fac2bcb83a6898e81f4845cf634f132f855bc23",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1fac2bcb83a6898e81f4845cf634f132f855bc23",
"gitiles_ref:refs/heads/master"
]
}
[14:53:18.802] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1536591197678793",
"id": "8935761064261893712",
"lease_expiration_ts": "1536735198595678",
"lease_key": "1741190388",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1fac2bcb83a6898e81f4845cf634f132f855bc23\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1536591198712642",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10041",
"builder:Windows deterministic",
"buildset:commit/git/1fac2bcb83a6898e81f4845cf634f132f855bc23",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1fac2bcb83a6898e81f4845cf634f132f855bc23",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9101191126423522528",
"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/10041",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8935761064261893712",
"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/1fac2bcb83a6898e81f4845cf634f132f855bc23",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1fac2bcb83a6898e81f4845cf634f132f855bc23",
"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/8935761064261893712/+/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:9101191126423522528",
"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:71d843ea58cc76a6b4422ca80f2115179bb30d7f",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fdcca8031906710",
"user_agent:luci-scheduler"
],
"updated_ts": "1536591198712723",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10041",
"utcnow_ts": "1536591198787640"
}
}
[14:53:18.802] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10041
[14:53:18.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:2:0) after 1m0s
[14:53:21.675] Received PubSub notification, asking Buildbucket for the build status
[14:53:21.741] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:18.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:2:0)
[14:54:18.818] Timer tick, asking Buildbucket for the build status
[14:54:18.858] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:18.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:4:0) after 1m0s
[14:55:18.948] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:4:0)
[14:55:18.948] Timer tick, asking Buildbucket for the build status
[14:55:19.008] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:19.008] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:5:0) after 1m0s
[14:56:19.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:5:0)
[14:56:19.025] Timer tick, asking Buildbucket for the build status
[14:56:19.100] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:19.100] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:6:0) after 1m0s
[14:57:19.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:6:0)
[14:57:19.118] Timer tick, asking Buildbucket for the build status
[14:57:19.195] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:19.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:7:0) after 1m0s
[14:58:19.223] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:7:0)
[14:58:19.223] Timer tick, asking Buildbucket for the build status
[14:58:19.289] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:19.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:8:0) after 1m0s
[14:59:19.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:8:0)
[14:59:19.384] Timer tick, asking Buildbucket for the build status
[14:59:19.446] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:19.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:9:0) after 1m0s
[15:00:19.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:9:0)
[15:00:19.464] Timer tick, asking Buildbucket for the build status
[15:00:19.695] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:19.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:10:0) after 1m0s
[15:01:19.714] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:10:0)
[15:01:19.714] Timer tick, asking Buildbucket for the build status
[15:01:19.760] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:19.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:11:0) after 1m0s
[15:02:19.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:11:0)
[15:02:19.777] Timer tick, asking Buildbucket for the build status
[15:02:19.839] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:19.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:12:0) after 1m0s
[15:03:19.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:12:0)
[15:03:19.910] Timer tick, asking Buildbucket for the build status
[15:03:19.990] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:19.990] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:13:0) after 1m0s
[15:04:20.034] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:13:0)
[15:04:20.034] Timer tick, asking Buildbucket for the build status
[15:04:20.210] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:20.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:14:0) after 1m0s
[15:05:20.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:14:0)
[15:05:20.228] Timer tick, asking Buildbucket for the build status
[15:05:20.326] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:20.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:15:0) after 1m0s
[15:06:20.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:15:0)
[15:06:20.345] Timer tick, asking Buildbucket for the build status
[15:06:20.417] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:20.417] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:16:0) after 1m0s
[15:07:20.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:16:0)
[15:07:20.437] Timer tick, asking Buildbucket for the build status
[15:07:20.522] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:20.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:17:0) after 1m0s
[15:08:20.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:17:0)
[15:08:20.539] Timer tick, asking Buildbucket for the build status
[15:08:20.731] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:20.731] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:18:0) after 1m0s
[15:09:20.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:18:0)
[15:09:20.752] Timer tick, asking Buildbucket for the build status
[15:09:20.827] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:20.827] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:19:0) after 1m0s
[15:10:20.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:19:0)
[15:10:20.856] Timer tick, asking Buildbucket for the build status
[15:10:20.891] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:20.891] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:20:0) after 1m0s
[15:11:20.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:20:0)
[15:11:20.910] Timer tick, asking Buildbucket for the build status
[15:11:21.195] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:21.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:21:0) after 1m0s
[15:12:21.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:21:0)
[15:12:21.214] Timer tick, asking Buildbucket for the build status
[15:12:21.275] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:21.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:22:0) after 1m0s
[15:13:21.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:22:0)
[15:13:21.306] Timer tick, asking Buildbucket for the build status
[15:13:21.457] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:21.457] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:23:0) after 1m0s
[15:14:21.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:23:0)
[15:14:21.494] Timer tick, asking Buildbucket for the build status
[15:14:21.610] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:21.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:24:0) after 1m0s
[15:15:21.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:24:0)
[15:15:21.629] Timer tick, asking Buildbucket for the build status
[15:15:21.817] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:21.817] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:25:0) after 1m0s
[15:16:21.898] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:25:0)
[15:16:21.898] Timer tick, asking Buildbucket for the build status
[15:16:22.028] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:22.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:26:0) after 1m0s
[15:17:22.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:26:0)
[15:17:22.067] Timer tick, asking Buildbucket for the build status
[15:17:22.125] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:22.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:27:0) after 1m0s
[15:18:22.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:27:0)
[15:18:22.147] Timer tick, asking Buildbucket for the build status
[15:18:22.276] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:22.276] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:28:0) after 1m0s
[15:19:22.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:28:0)
[15:19:22.293] Timer tick, asking Buildbucket for the build status
[15:19:22.354] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:22.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:29:0) after 1m0s
[15:20:22.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:29:0)
[15:20:22.377] Timer tick, asking Buildbucket for the build status
[15:20:22.523] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:22.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:30:0) after 1m0s
[15:21:22.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:30:0)
[15:21:22.592] Timer tick, asking Buildbucket for the build status
[15:21:22.648] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:22.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:31:0) after 1m0s
[15:22:22.740] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:31:0)
[15:22:22.741] Timer tick, asking Buildbucket for the build status
[15:22:22.877] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:22.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:32:0) after 1m0s
[15:23:22.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:32:0)
[15:23:22.896] Timer tick, asking Buildbucket for the build status
[15:23:22.939] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:22.939] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:33:0) after 1m0s
[15:24:22.958] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:33:0)
[15:24:22.958] Timer tick, asking Buildbucket for the build status
[15:24:23.019] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:23.019] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:34:0) after 1m0s
[15:25:23.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:34:0)
[15:25:23.037] Timer tick, asking Buildbucket for the build status
[15:25:23.080] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:23.080] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:35:0) after 1m0s
[15:26:23.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:35:0)
[15:26:23.099] Timer tick, asking Buildbucket for the build status
[15:26:23.249] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:23.249] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:36:0) after 1m0s
[15:27:23.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:36:0)
[15:27:23.268] Timer tick, asking Buildbucket for the build status
[15:27:23.319] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:23.319] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:37:0) after 1m0s
[15:28:23.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:37:0)
[15:28:23.344] Timer tick, asking Buildbucket for the build status
[15:28:23.614] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:23.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:38:0) after 1m0s
[15:29:23.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:38:0)
[15:29:23.634] Timer tick, asking Buildbucket for the build status
[15:29:23.797] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:23.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:39:0) after 1m0s
[15:30:23.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:39:0)
[15:30:23.831] Timer tick, asking Buildbucket for the build status
[15:30:24.092] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:24.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:40:0) after 1m0s
[15:31:24.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:40:0)
[15:31:24.125] Timer tick, asking Buildbucket for the build status
[15:31:24.210] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:24.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:41:0) after 1m0s
[15:32:24.254] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:41:0)
[15:32:24.254] Timer tick, asking Buildbucket for the build status
[15:32:24.345] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:24.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:42:0) after 1m0s
[15:33:24.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:42:0)
[15:33:24.364] Timer tick, asking Buildbucket for the build status
[15:33:24.417] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:24.417] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:43:0) after 1m0s
[15:34:24.436] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:43:0)
[15:34:24.436] Timer tick, asking Buildbucket for the build status
[15:34:24.470] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:24.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:44:0) after 1m0s
[15:35:24.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:44:0)
[15:35:24.492] Timer tick, asking Buildbucket for the build status
[15:35:24.528] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:24.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:45:0) after 1m0s
[15:36:24.643] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:45:0)
[15:36:24.643] Timer tick, asking Buildbucket for the build status
[15:36:24.737] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:24.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:46:0) after 1m0s
[15:37:24.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:46:0)
[15:37:24.772] Timer tick, asking Buildbucket for the build status
[15:37:24.832] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:24.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:47:0) after 1m0s
[15:38:24.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:47:0)
[15:38:24.853] Timer tick, asking Buildbucket for the build status
[15:38:24.918] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:24.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:48:0) after 1m0s
[15:39:24.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:48:0)
[15:39:24.945] Timer tick, asking Buildbucket for the build status
[15:39:25.004] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:25.004] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:49:0) after 1m0s
[15:40:25.024] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:49:0)
[15:40:25.024] Timer tick, asking Buildbucket for the build status
[15:40:25.094] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:25.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:50:0) after 1m0s
[15:41:25.116] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:50:0)
[15:41:25.116] Timer tick, asking Buildbucket for the build status
[15:41:25.158] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:25.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:51:0) after 1m0s
[15:42:25.177] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:51:0)
[15:42:25.177] Timer tick, asking Buildbucket for the build status
[15:42:25.235] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:25.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:52:0) after 1m0s
[15:43:25.257] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:52:0)
[15:43:25.257] Timer tick, asking Buildbucket for the build status
[15:43:25.294] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:25.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:53:0) after 1m0s
[15:44:25.321] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:53:0)
[15:44:25.321] Timer tick, asking Buildbucket for the build status
[15:44:25.493] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:25.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:54:0) after 1m0s
[15:45:25.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:54:0)
[15:45:25.513] Timer tick, asking Buildbucket for the build status
[15:45:25.559] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:25.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:55:0) after 1m0s
[15:46:25.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:55:0)
[15:46:25.592] Timer tick, asking Buildbucket for the build status
[15:46:25.658] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:25.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:56:0) after 1m0s
[15:47:25.676] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:56:0)
[15:47:25.676] Timer tick, asking Buildbucket for the build status
[15:47:25.736] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:25.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:57:0) after 1m0s
[15:48:25.758] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:57:0)
[15:48:25.758] Timer tick, asking Buildbucket for the build status
[15:48:25.829] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:25.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:58:0) after 1m0s
[15:49:25.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:58:0)
[15:49:25.848] Timer tick, asking Buildbucket for the build status
[15:49:25.906] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:25.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:59:0) after 1m0s
[15:50:25.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:59:0)
[15:50:25.990] Timer tick, asking Buildbucket for the build status
[15:50:26.058] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:26.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:60:0) after 1m0s
[15:51:26.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:60:0)
[15:51:26.137] Timer tick, asking Buildbucket for the build status
[15:51:26.309] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:26.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:61:0) after 1m0s
[15:52:26.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:61:0)
[15:52:26.409] Timer tick, asking Buildbucket for the build status
[15:52:26.464] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:26.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:62:0) after 1m0s
[15:53:26.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:62:0)
[15:53:26.482] Timer tick, asking Buildbucket for the build status
[15:53:26.558] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:26.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:63:0) after 1m0s
[15:54:26.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:63:0)
[15:54:26.592] Timer tick, asking Buildbucket for the build status
[15:54:26.673] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:26.673] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:64:0) after 1m0s
[15:55:26.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:64:0)
[15:55:26.692] Timer tick, asking Buildbucket for the build status
[15:55:26.773] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:26.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:65:0) after 1m0s
[15:56:26.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:65:0)
[15:56:26.796] Timer tick, asking Buildbucket for the build status
[15:56:26.847] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:26.847] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:66:0) after 1m0s
[15:57:26.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:66:0)
[15:57:26.889] Timer tick, asking Buildbucket for the build status
[15:57:26.981] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:26.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:67:0) after 1m0s
[15:58:27.000] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:67:0)
[15:58:27.000] Timer tick, asking Buildbucket for the build status
[15:58:27.155] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:27.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:68:0) after 1m0s
[15:59:27.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:68:0)
[15:59:27.175] Timer tick, asking Buildbucket for the build status
[15:59:27.238] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:27.238] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:69:0) after 1m0s
[16:00:27.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:69:0)
[16:00:27.355] Timer tick, asking Buildbucket for the build status
[16:00:27.436] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:27.436] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:70:0) after 1m0s
[16:01:27.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:70:0)
[16:01:27.459] Timer tick, asking Buildbucket for the build status
[16:01:27.736] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:27.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:71:0) after 1m0s
[16:02:27.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:71:0)
[16:02:27.757] Timer tick, asking Buildbucket for the build status
[16:02:27.864] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:27.864] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:72:0) after 1m0s
[16:03:27.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:72:0)
[16:03:27.887] Timer tick, asking Buildbucket for the build status
[16:03:27.938] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:27.938] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:73:0) after 1m0s
[16:04:27.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:73:0)
[16:04:27.978] Timer tick, asking Buildbucket for the build status
[16:04:28.128] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:28.128] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:74:0) after 1m0s
[16:05:28.151] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:74:0)
[16:05:28.151] Timer tick, asking Buildbucket for the build status
[16:05:28.213] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:28.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:75:0) after 1m0s
[16:06:28.259] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:75:0)
[16:06:28.259] Timer tick, asking Buildbucket for the build status
[16:06:28.545] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:28.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:76:0) after 1m0s
[16:07:28.585] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:76:0)
[16:07:28.585] Timer tick, asking Buildbucket for the build status
[16:07:28.644] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:28.645] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:77:0) after 1m0s
[16:08:28.666] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:77:0)
[16:08:28.666] Timer tick, asking Buildbucket for the build status
[16:08:28.762] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:28.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:78:0) after 1m0s
[16:09:28.789] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:78:0)
[16:09:28.789] Timer tick, asking Buildbucket for the build status
[16:09:28.850] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:28.850] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:79:0) after 1m0s
[16:10:28.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:79:0)
[16:10:28.871] Timer tick, asking Buildbucket for the build status
[16:10:28.931] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:28.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:80:0) after 1m0s
[16:11:28.952] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:80:0)
[16:11:28.952] Timer tick, asking Buildbucket for the build status
[16:11:29.043] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:29.043] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:81:0) after 1m0s
[16:12:29.064] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:81:0)
[16:12:29.064] Timer tick, asking Buildbucket for the build status
[16:12:29.481] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:29.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:82:0) after 1m0s
[16:13:29.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:82:0)
[16:13:29.514] Timer tick, asking Buildbucket for the build status
[16:13:29.569] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:29.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:83:0) after 1m0s
[16:14:29.635] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:83:0)
[16:14:29.635] Timer tick, asking Buildbucket for the build status
[16:14:29.701] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:29.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:84:0) after 1m0s
[16:15:29.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:84:0)
[16:15:29.728] Timer tick, asking Buildbucket for the build status
[16:15:29.841] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:29.841] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:85:0) after 1m0s
[16:16:29.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:85:0)
[16:16:29.861] Timer tick, asking Buildbucket for the build status
[16:16:29.933] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:29.933] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:86:0) after 1m0s
[16:17:29.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:86:0)
[16:17:29.953] Timer tick, asking Buildbucket for the build status
[16:17:30.050] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:30.050] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:87:0) after 1m0s
[16:18:30.128] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:87:0)
[16:18:30.128] Timer tick, asking Buildbucket for the build status
[16:18:30.209] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:30.209] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:88:0) after 1m0s
[16:19:30.234] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:88:0)
[16:19:30.234] Timer tick, asking Buildbucket for the build status
[16:19:30.289] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:30.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:89:0) after 1m0s
[16:20:30.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:89:0)
[16:20:30.312] Timer tick, asking Buildbucket for the build status
[16:20:30.348] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:30.348] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:90:0) after 1m0s
[16:21:30.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:90:0)
[16:21:30.384] Timer tick, asking Buildbucket for the build status
[16:21:30.427] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:30.428] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:91:0) after 1m0s
[16:22:30.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:91:0)
[16:22:30.456] Timer tick, asking Buildbucket for the build status
[16:22:30.620] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:30.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:92:0) after 1m0s
[16:23:30.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:92:0)
[16:23:30.642] Timer tick, asking Buildbucket for the build status
[16:23:30.689] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:30.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:93:0) after 1m0s
[16:24:30.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:93:0)
[16:24:30.710] Timer tick, asking Buildbucket for the build status
[16:24:30.780] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:30.780] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:94:0) after 1m0s
[16:25:30.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:94:0)
[16:25:30.803] Timer tick, asking Buildbucket for the build status
[16:25:30.878] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:30.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:95:0) after 1m0s
[16:26:30.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:95:0)
[16:26:30.900] Timer tick, asking Buildbucket for the build status
[16:26:30.956] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:30.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:96:0) after 1m0s
[16:27:30.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:96:0)
[16:27:30.978] Timer tick, asking Buildbucket for the build status
[16:27:31.022] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:31.022] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:97:0) after 1m0s
[16:28:31.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:97:0)
[16:28:31.058] Timer tick, asking Buildbucket for the build status
[16:28:31.118] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:31.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:98:0) after 1m0s
[16:29:31.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:98:0)
[16:29:31.143] Timer tick, asking Buildbucket for the build status
[16:29:31.228] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:31.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:99:0) after 1m0s
[16:30:31.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:99:0)
[16:30:31.250] Timer tick, asking Buildbucket for the build status
[16:30:31.503] Build 8935761064261893712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:31.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101191126423522528:100:0) after 1m0s
[16:30:52.436] Received PubSub notification, asking Buildbucket for the build status
[16:30:52.508] Build 8935761064261893712: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:30:52.508] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1536597051382367",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1536591197678793",
"id": "8935761064261893712",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1fac2bcb83a6898e81f4845cf634f132f855bc23\"}}",
"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/8935761064261893712/+/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\": [\"3769-3c22b45\"], \"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\": \"1fac2bcb83a6898e81f4845cf634f132f855bc23\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24641}\", \"$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@{#589907}\", \"branch\": \"refs/heads/master\", \"revision\": \"1fac2bcb83a6898e81f4845cf634f132f855bc23\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"76dac9ac2f266bb9468d5fcfe10824f415d8e01a\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"a735ee2f6df8ec7ef55207e7fdffcbac87b900af\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1536591197678793, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/1fac2bcb83a6898e81f4845cf634f132f855bc23\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1fac2bcb83a6898e81f4845cf634f132f855bc23\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9101191126423522528\", \"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\": \"8935761064261893712\"}}, \"got_v8_revision\": \"33b726db1e2a5b7de8ddca88e165596c34d08a0d\", \"got_v8_revision_cp\": \"refs/heads/7.1.71@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10041, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"a37146f56f2aa84f880c56cbcb4ebbb940a68fe3\", \"notification_helper_unittests\": \"f69db68a5d2fac9b041cbfde4c266da66155120e\", \"gpu_perftests\": \"ce52b98fbd5493360bdb6e2ee3167edd0a702631\", \"vr_common_unittests\": \"7906c7cc9c7ef928f289d473ef91e580ea39d3d7\", \"telemetry_unittests\": \"8966ae1542cb9d9cd9a391f6cc997948b8cd90d1\", \"load_library_perf_tests\": \"f8433b5977ac918e2ed1b42981639446302dfff6\", \"devtools_closure_compile\": \"5d580efa65fb051a58fd7467b45b5a954f8ed6b2\", \"chromedriver_py_tests\": \"541ce09d73b844bf8acf195e07a004e92620f21f\", \"mojo_core_unittests\": \"96bb3564df74911aa49fcd095a19d0bc65d28ac8\", \"install_static_unittests\": \"f30fa77e909750b502fb327804c6f7ab168acdfa\", \"leveldb_service_unittests\": \"feee4adf625ac48555632abfce077fb4e042025b\", \"mini_installer_tests\": \"8544a2002fb4029c6b103449f327bb579fc9d0a5\", \"jingle_unittests\": \"6822de357d64d4afa7391389fc04a9e94f16809b\", \"sbox_integration_tests\": \"6a8efcace5926889b88ab179d0ff84f14b1372f3\", \"angle_unittests\": \"157d877423624e3bcc3b29cb1907766251acf286\", \"cast_unittests\": \"9376b3e4eda9f0793407dfc60c3aba8e51fe2e60\", \"cc_unittests\": \"448906423a0e9fd703826e66e1aefa32ee4d1dbf\", \"blink_common_unittests\": \"4a80b8dae0e78cd6482796a43190e0c47bdcc1b6\", \"crashpad_tests\": \"e9e8574254317625ae72ca290d56e1cefef1f718\", \"telemetry_perf_tests_without_chrome\": \"772acd7b3efef86351489b160312018330dcb2c6\", \"metrics_python_tests\": \"a54ccbdcb57a0d4e104d4d9469fa880fb724183b\", \"message_center_unittests\": \"983b7cc79b94f3fad2d9b3c6fb89024942780b3b\", \"ppapi_unittests\": \"c268d6b88669753d284cc3858c82fcd470bd6baf\", \"extensions_unittests\": \"4fe8a9a18d4c659b74f2772110eac3391f9e2952\", \"gl_unittests_ozone\": \"64ee98d9aa50eed393b0aeac24bebbb2d2b9d92a\", \"app_shell_unittests\": \"853d711b5b471dc3e9435429f5b90d0dfa713544\", \"chrome_app_unittests\": \"ed3e0edf3bd947c873d97d193aa1e162f0067925\", \"gl_unittests\": \"64ee98d9aa50eed393b0aeac24bebbb2d2b9d92a\", \"gcm_unit_tests\": \"b7cd07b0e0e00bf425c60e3279f3d6a3fb50cf77\", \"views_unittests\": \"caea6372c5b741b75cfe540f0fa032869c2576c4\", \"content_shell_crash_test\": \"71ab1db3a6a6f70fd21fc1665bf487b7526f71a2\", \"ui_base_unittests\": \"54948ee56c1b6f7eb7569e3c51fffdb3f69e32aa\", \"gles2_conform_test\": \"ddbc258d08fca836cec02de445efa105abeeba1d\", \"cronet_unittests\": \"88d4453741c3aac5baa35f9cf65b6cb5f26bd9ea\", \"performance_test_suite\": \"a912a511158169d539ac0a9239423436e1027beb\", \"wm_unittests\": \"40af2c53b68930e83a80e5aa06e57db5d4f3798c\", \"boringssl_crypto_tests\": \"87b6c3e0c6ea49be86b5b64d51b5807a6bf98bd7\", \"gin_unittests\": \"84fe8c5ad7893474f1d37a4dc09838cb0758dc6b\", \"performance_browser_tests\": \"58ff8ab185208da7ecce2b5a3bc2ee885c0a2bd2\", \"extensions_browsertests\": \"c2cf233572ece7c97d093df327a4c7d66de2d68d\", \"webkit_python_tests\": \"6c52728041dde94e5a415ee36313c7c339d8d3a9\", \"headless_unittests\": \"c265938cc5cde6d4ab1c0258b8ca63debf5cdb47\", \"webkit_layout_tests\": \"8f0a29917e13c7457212165c1c6d300ee9a89b47\", \"vr_pixeltests\": \"990dbab424ba9d9d878f2c2d8cd94c68a42d1d1c\", \"setup_unittests\": \"5474b6ca50a77899d9f63802cd67f32d669ca588\", \"webkit_unit_tests\": \"ae92cc5c37c2326a4a191936d61b0e52255b55b9\", \"content_unittests\": \"d2aca44bc407e89f8cf292be45a42aab53e091df\", \"performance_webview_test_suite\": \"b41421280ab3aeb927562268106ca5edb70e8d7f\", \"wtf_unittests\": \"b53996161985bbc0c016533a53e1323c5f1ca2ad\", \"webkit_layout_tests_exparchive\": \"4ea82e74b3ed0bcf883d914fb5a7962bbf579f4b\", \"chrome_cleaner_unittests\": \"a24fd12b6ea5bdd859043a3dfc51cb845a8b6def\", \"traffic_annotation_auditor_unittests\": \"2708d2567302359a3d82700e3144f50b02f5cb5b\", \"libjingle_xmpp_unittests\": \"b05108b2abcfdc49c2b9f934a08dce5a2b439bbd\", \"remoting_unittests\": \"bd01a9b296cbfe43ab41bf9beb35fe6f3af78f6d\", \"vr_common_perftests\": \"2dc0f43e1e417a69d37061a7113e24657b90d201\", \"filesystem_service_unittests\": \"666820f3f897b20e6943cd266b76d613a6ad391f\", \"chrome_elf_import_unittests\": \"57310c91ad723b5df569eff958a00fae1d202ff2\", \"installer_util_unittests\": \"54454d553e6cdf2c045d0e12ab64c83f20e6658f\", \"gfx_unittests\": \"f6b611f0518111f4dff91f3568368275250aa2c5\", \"headless_browsertests\": \"c4d7e204f048b08a891b50089da568bda439a8c5\", \"capture_unittests\": \"92abcf317f2793f09047fec996487654cd257e5c\", \"chrome_elf_unittests\": \"c041ffacc1284dbf6dda0a37fd95ac111a85dfef\", \"telemetry_gpu_unittests\": \"e5f325ec12394cb02569ed0879b75d0206fb7076\", \"command_buffer_perftests\": \"868f5b69792482d4fc2c74281051ae31df89a511\", \"media_unittests\": \"5d61e46b37469c6813ceebdb6b31e84dcfd8d64e\", \"chromedriver_unittests\": \"6879fd5e56426682d5027baab73f59cd69af3ca5\", \"views_mus_interactive_ui_tests\": \"9291645392d160a2598cb5915990136db466ad8a\", \"storage_unittests\": \"c8a5c5f57dd4cc7fcf06578c51bf8ef043cbd20e\", \"media_service_unittests\": \"cda05f15407ec7fd203738e372be9e2c2647929a\", \"compositor_unittests\": \"024180609bcb165c283dead8de51c283583ab952\", \"latency_unittests\": \"0cbff624c355ec3e14849b9a1a393cdc38726a4e\", \"video_decode_accelerator_unittest\": \"6df8535cb0221ff6c44f20efe676c5320d7d8e1f\", \"swiftshader_unittests\": \"80ea8ffe921225aedafa0c4a5c964e89af3e669e\", \"telemetry_perf_unittests\": \"de1770584f1bdac0c6d67d17b3d4ecf522d3602f\", \"media_blink_unittests\": \"bb8964acd04b5d4c52de5120b5c6197178388ac1\", \"views_mus_unittests\": \"4f7a042fa5f8d35792267e3388475c42b1fa338a\", \"components_unittests\": \"d185998f7c26ffdb448f0d1e0c0f7000cfdd2354\", \"snapshot_unittests\": \"f28760c696e0632319d7f566cdc513582ab7041a\", \"accessibility_unittests\": \"3a81b88dee50c1feaebd3b6da0c5ea44941eb38f\", \"net_perftests\": \"bd25bd8b0e1dcdafb043441d666da139d9bbce9a\", \"aura_unittests\": \"0a8ed157982703dd10f52e9d8035e16189c427b7\", \"services_unittests\": \"9d047fcfd91884d8443ab6f9d314cf1fc7f3f9b7\", \"components_perftests\": \"342b2458eead3d124ab5fe88c08bc0605536afa5\", \"events_unittests\": \"5674f6404722ae907f44c55f7e43ed1a00742b78\", \"tracing_perftests\": \"5dca39de8d940c7d6d65c15bb0cfb7e3cbfe3713\", \"chromedriver_replay_unittests\": \"e2ec5b5deadfbf1a0e00246e86e8f3e11e9cace0\", \"ui_touch_selection_unittests\": \"fbb784ad36fd97d0a47cea90102be956501b49bb\", \"cacheinvalidation_unittests\": \"35f544cd39355e85d0f656b8111656bf14dbdcff\", \"viz_unittests\": \"38db58ae214c0103d0ce45894bc55fc1eb542411\", \"browser_tests\": \"eeada73bd8e2ba50098ffc91af127231f1f9af12\", \"net_unittests\": \"55f2e3c004f0626c1ada34b14a834fe0e673c190\", \"midi_unittests\": \"978b76d0c252acfb2cf35a35c1a39eefd0632bc2\", \"boringssl_ssl_tests\": \"a087de3585d217e18bcc29f88245d6429dfd72d3\", \"sbox_validation_tests\": \"d2d1ce3237739718581478963a0607780f909821\", \"elevation_service_unittests\": \"cd1a8717a9fe97dc7d1e555df5af26222569654a\", \"gpu_unittests\": \"cca13e14bd99ee88483fa8a7e7efb3970c0e7f7b\", \"native_theme_unittests\": \"7bd1497d5d163dcb3df63929b245c5a7dce5adf5\", \"interactive_ui_tests\": \"776652e52f61b06291c3af16bef042d791cc0fe6\", \"mojo_unittests\": \"61dacdd6bfecd5c71cb9c7b1057dcb389116fef1\", \"url_unittests\": \"ac1551a0049ae2e794e36b28017b8857474130d7\", \"media_perftests\": \"1367ba23a65d0597c2c5a336029db15864dd9444\", \"cronet_tests\": \"51a4e9a942be259fc72db43cdd0f4f529b521571\", \"google_apis_unittests\": \"2c873e3c2ca197686616d161bb95294363abfa32\", \"telemetry_gpu_integration_test\": \"cb589fbfe8cfcb0c1238a0c59aedb07839ef4bc1\", \"blink_heap_unittests\": \"d8d00898cdc91341ac581b8eba5d27d498d91d0b\", \"base_unittests\": \"2c29a0cadbf1d1f8d0f92af0e0a5668ce74687b5\", \"sync_integration_tests\": \"88aaeba5cd83116515856f64f3879c8a00841076\", \"sql_unittests\": \"d8f89f698e23195e74d0985aec34edc2397642cf\", \"courgette_unittests\": \"efba834b138eace53c2d53350843301a3f506f0e\", \"blink_fuzzer_unittests\": \"06367fb6dfd64aa468bda5ae7a2e4a9cfecdeaf1\", \"gl_tests\": \"1e05e8eaca5ded742104623d2518e8d38a7b128d\", \"zucchini_unittests\": \"323f07c22a18797ec734e01390dfcff41239a12d\", \"ipc_tests\": \"f4b22c008e3f5c3443d111d4c96a301cd686b60e\", \"angle_end2end_tests\": \"de04e22a3587f2e9a518857e2f40e879b29d3a51\", \"blink_platform_unittests\": \"5f8018d0d48349ddddec750e200d5757e409d539\", \"components_browsertests\": \"c1595565e145a6302f0a325699276e0069a2467a\", \"views_perftests\": \"a075447316c8bbe4a79cd3acca26949fe539a937\", \"angle_perftests\": \"62c7e39e2e1ea758a6b0fe96aed6b7d57b0acc5a\", \"display_unittests\": \"a9a60c95a8c3624910daf4c30c2ca7b1e7ec06c7\", \"sbox_unittests\": \"b245459ad2dbd8810573c529993824ad7a2a3418\", \"service_manager_unittests\": \"0204402099799faeec0dd54fa5bc252e8bdcce26\", \"pdf_unittests\": \"851ed499624030fdea5c046e3e32a70a9f18d48f\", \"skia_unittests\": \"399891f0d90d0b4298e8f6aa41c9bdc85b93e797\", \"angle_white_box_tests\": \"f93f612ceb283c0d718c797ccb27bd361aefd6a8\", \"crypto_unittests\": \"14f3652905d9a2f56d6d02d2e0cd78dbdc9718ca\", \"nacl_loader_unittests\": \"e71c2532a69ad3b67d7ee07b0d52a04996d6b158\", \"unit_tests\": \"6f2bfe450adc64f6c81ff9215f2d1c09e1322312\", \"content_browsertests\": \"65abfab0a6386a469ad068c636bd6fa069e4dc5c\", \"printing_unittests\": \"c2f5b9c8384f31ac92b38ccd03599d09f49b5a68\", \"device_unittests\": \"c2b3deb26a004602646fbe0963cba006adb5929e\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1536591199079075",
"status": "COMPLETED",
"status_changed_ts": "1536597052012152",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10041",
"builder:Windows deterministic",
"buildset:commit/git/1fac2bcb83a6898e81f4845cf634f132f855bc23",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1fac2bcb83a6898e81f4845cf634f132f855bc23",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9101191126423522528",
"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/10041",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8935761064261893712",
"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/1fac2bcb83a6898e81f4845cf634f132f855bc23",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1fac2bcb83a6898e81f4845cf634f132f855bc23",
"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/8935761064261893712/+/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:9101191126423522528",
"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:71d843ea58cc76a6b4422ca80f2115179bb30d7f",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fdcca8031906710",
"user_agent:luci-scheduler"
],
"updated_ts": "1536597052018939",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10041",
"utcnow_ts": "1536597052488036"
}
}
[16:30:52.508] Invocation finished in 1h37m36.275231613s with status SUCCEEDED