[23:10:31.103] New invocation is queued and will start shortly
[23:10:32.506] Starting the invocation (attempt 1)
[23:10:32.537] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:10:32.537] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:10:32.537] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9099982081748681456",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"c635c06ebe25e8376972fa36ee44642e79507629\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9099982081748681456",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/c635c06ebe25e8376972fa36ee44642e79507629",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c635c06ebe25e8376972fa36ee44642e79507629",
"gitiles_ref:refs/heads/master"
]
}
[23:10:37.440] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1537744232669100",
"id": "8934552019442697968",
"lease_expiration_ts": "1537888237167659",
"lease_key": "1667582637",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c635c06ebe25e8376972fa36ee44642e79507629\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1537744237366783",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10179",
"builder:Windows deterministic",
"buildset:commit/git/c635c06ebe25e8376972fa36ee44642e79507629",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c635c06ebe25e8376972fa36ee44642e79507629",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9099982081748681456",
"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/10179",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8934552019442697968",
"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/c635c06ebe25e8376972fa36ee44642e79507629",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c635c06ebe25e8376972fa36ee44642e79507629",
"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/8934552019442697968/+/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:9099982081748681456",
"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:ded6e667eedfba9ca83cacf95f0d2164f0246aeb",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:40218478cf2ba810",
"user_agent:luci-scheduler"
],
"updated_ts": "1537744237366883",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10179",
"utcnow_ts": "1537744237430549"
}
}
[23:10:37.440] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10179
[23:10:37.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:2:0) after 1m0s
[23:10:44.686] Received PubSub notification, asking Buildbucket for the build status
[23:10:44.731] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:37.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:2:0)
[23:11:37.458] Timer tick, asking Buildbucket for the build status
[23:11:37.500] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:37.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:4:0) after 1m0s
[23:12:37.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:4:0)
[23:12:37.519] Timer tick, asking Buildbucket for the build status
[23:12:37.587] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:37.587] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:5:0) after 1m0s
[23:13:37.607] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:5:0)
[23:13:37.607] Timer tick, asking Buildbucket for the build status
[23:13:37.818] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:37.818] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:6:0) after 1m0s
[23:14:37.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:6:0)
[23:14:37.836] Timer tick, asking Buildbucket for the build status
[23:14:38.101] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:38.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:7:0) after 1m0s
[23:15:38.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:7:0)
[23:15:38.123] Timer tick, asking Buildbucket for the build status
[23:15:38.185] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:38.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:8:0) after 1m0s
[23:16:38.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:8:0)
[23:16:38.260] Timer tick, asking Buildbucket for the build status
[23:16:38.361] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:38.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:9:0) after 1m0s
[23:17:38.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:9:0)
[23:17:38.405] Timer tick, asking Buildbucket for the build status
[23:17:38.504] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:38.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:10:0) after 1m0s
[23:18:38.560] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:10:0)
[23:18:38.560] Timer tick, asking Buildbucket for the build status
[23:18:38.621] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:38.621] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:11:0) after 1m0s
[23:19:38.636] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:11:0)
[23:19:38.636] Timer tick, asking Buildbucket for the build status
[23:19:38.790] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:38.790] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:12:0) after 1m0s
[23:20:38.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:12:0)
[23:20:38.815] Timer tick, asking Buildbucket for the build status
[23:20:38.874] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:38.874] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:13:0) after 1m0s
[23:21:38.901] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:13:0)
[23:21:38.902] Timer tick, asking Buildbucket for the build status
[23:21:38.958] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:38.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:14:0) after 1m0s
[23:22:39.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:14:0)
[23:22:39.015] Timer tick, asking Buildbucket for the build status
[23:22:39.080] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:39.080] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:15:0) after 1m0s
[23:23:39.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:15:0)
[23:23:39.113] Timer tick, asking Buildbucket for the build status
[23:23:39.184] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:39.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:16:0) after 1m0s
[23:24:39.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:16:0)
[23:24:39.200] Timer tick, asking Buildbucket for the build status
[23:24:39.293] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:39.293] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:17:0) after 1m0s
[23:25:39.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:17:0)
[23:25:39.347] Timer tick, asking Buildbucket for the build status
[23:25:39.386] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:39.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:18:0) after 1m0s
[23:26:39.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:18:0)
[23:26:39.414] Timer tick, asking Buildbucket for the build status
[23:26:39.470] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:39.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:19:0) after 1m0s
[23:27:39.512] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:19:0)
[23:27:39.512] Timer tick, asking Buildbucket for the build status
[23:27:39.802] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:39.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:20:0) after 1m0s
[23:28:39.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:20:0)
[23:28:39.831] Timer tick, asking Buildbucket for the build status
[23:28:39.890] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:39.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:21:0) after 1m0s
[23:29:39.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:21:0)
[23:29:39.910] Timer tick, asking Buildbucket for the build status
[23:29:40.308] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:40.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:22:0) after 1m0s
[23:30:40.334] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:22:0)
[23:30:40.334] Timer tick, asking Buildbucket for the build status
[23:30:40.409] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:40.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:23:0) after 1m0s
[23:31:40.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:23:0)
[23:31:40.434] Timer tick, asking Buildbucket for the build status
[23:31:40.524] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:40.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:24:0) after 1m0s
[23:32:40.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:24:0)
[23:32:40.546] Timer tick, asking Buildbucket for the build status
[23:32:40.725] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:40.725] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:25:0) after 1m0s
[23:33:40.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:25:0)
[23:33:40.743] Timer tick, asking Buildbucket for the build status
[23:33:40.922] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:40.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:26:0) after 1m0s
[23:34:40.939] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:26:0)
[23:34:40.939] Timer tick, asking Buildbucket for the build status
[23:34:41.121] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:41.121] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:27:0) after 1m0s
[23:35:41.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:27:0)
[23:35:41.141] Timer tick, asking Buildbucket for the build status
[23:35:41.717] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:41.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:28:0) after 1m0s
[23:36:41.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:28:0)
[23:36:41.733] Timer tick, asking Buildbucket for the build status
[23:36:41.804] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:41.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:29:0) after 1m0s
[23:37:41.823] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:29:0)
[23:37:41.823] Timer tick, asking Buildbucket for the build status
[23:37:41.990] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:41.990] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:30:0) after 1m0s
[23:38:42.011] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:30:0)
[23:38:42.011] Timer tick, asking Buildbucket for the build status
[23:38:42.049] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:42.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:31:0) after 1m0s
[23:39:42.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:31:0)
[23:39:42.096] Timer tick, asking Buildbucket for the build status
[23:39:42.210] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:42.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:32:0) after 1m0s
[23:40:42.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:32:0)
[23:40:42.260] Timer tick, asking Buildbucket for the build status
[23:40:42.326] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:42.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:33:0) after 1m0s
[23:41:42.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:33:0)
[23:41:42.445] Timer tick, asking Buildbucket for the build status
[23:41:42.525] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:42.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:34:0) after 1m0s
[23:42:42.603] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:34:0)
[23:42:42.603] Timer tick, asking Buildbucket for the build status
[23:42:42.657] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:42.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:35:0) after 1m0s
[23:43:42.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:35:0)
[23:43:42.700] Timer tick, asking Buildbucket for the build status
[23:43:42.796] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:42.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:36:0) after 1m0s
[23:44:42.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:36:0)
[23:44:42.936] Timer tick, asking Buildbucket for the build status
[23:44:43.153] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:43.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:37:0) after 1m0s
[23:45:43.279] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:37:0)
[23:45:43.279] Timer tick, asking Buildbucket for the build status
[23:45:43.341] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:43.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:38:0) after 1m0s
[23:46:43.511] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:38:0)
[23:46:43.511] Timer tick, asking Buildbucket for the build status
[23:46:43.580] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:43.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:39:0) after 1m0s
[23:47:43.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:39:0)
[23:47:43.599] Timer tick, asking Buildbucket for the build status
[23:47:43.661] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:43.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:40:0) after 1m0s
[23:48:43.680] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:40:0)
[23:48:43.680] Timer tick, asking Buildbucket for the build status
[23:48:43.835] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:43.835] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:41:0) after 1m0s
[23:49:43.890] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:41:0)
[23:49:43.890] Timer tick, asking Buildbucket for the build status
[23:49:44.077] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:44.077] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:42:0) after 1m0s
[23:50:44.128] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:42:0)
[23:50:44.128] Timer tick, asking Buildbucket for the build status
[23:50:44.211] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:44.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:43:0) after 1m0s
[23:51:44.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:43:0)
[23:51:44.233] Timer tick, asking Buildbucket for the build status
[23:51:44.311] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:44.311] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:44:0) after 1m0s
[23:52:44.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:44:0)
[23:52:44.341] Timer tick, asking Buildbucket for the build status
[23:52:44.410] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:44.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:45:0) after 1m0s
[23:53:44.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:45:0)
[23:53:44.430] Timer tick, asking Buildbucket for the build status
[23:53:44.524] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:44.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:46:0) after 1m0s
[23:54:44.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:46:0)
[23:54:44.591] Timer tick, asking Buildbucket for the build status
[23:54:44.822] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:44.822] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:47:0) after 1m0s
[23:55:44.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:47:0)
[23:55:44.843] Timer tick, asking Buildbucket for the build status
[23:55:44.924] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:44.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:48:0) after 1m0s
[23:56:44.948] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:48:0)
[23:56:44.948] Timer tick, asking Buildbucket for the build status
[23:56:45.032] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:45.032] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:49:0) after 1m0s
[23:57:45.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:49:0)
[23:57:45.058] Timer tick, asking Buildbucket for the build status
[23:57:45.125] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:45.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:50:0) after 1m0s
[23:58:45.192] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:50:0)
[23:58:45.192] Timer tick, asking Buildbucket for the build status
[23:58:45.310] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:45.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:51:0) after 1m0s
[23:59:45.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:51:0)
[23:59:45.326] Timer tick, asking Buildbucket for the build status
[23:59:45.433] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:45.433] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:52:0) after 1m0s
[00:00:45.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:52:0)
[00:00:45.459] Timer tick, asking Buildbucket for the build status
[00:00:45.520] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:45.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:53:0) after 1m0s
[00:01:45.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:53:0)
[00:01:45.551] Timer tick, asking Buildbucket for the build status
[00:01:45.591] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:45.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:54:0) after 1m0s
[00:02:45.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:54:0)
[00:02:45.618] Timer tick, asking Buildbucket for the build status
[00:02:45.734] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:45.734] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:55:0) after 1m0s
[00:03:45.753] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:55:0)
[00:03:45.753] Timer tick, asking Buildbucket for the build status
[00:03:45.917] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:45.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:56:0) after 1m0s
[00:04:45.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:56:0)
[00:04:45.937] Timer tick, asking Buildbucket for the build status
[00:04:46.044] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:46.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:57:0) after 1m0s
[00:05:46.063] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:57:0)
[00:05:46.063] Timer tick, asking Buildbucket for the build status
[00:05:46.134] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:46.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:58:0) after 1m0s
[00:06:46.173] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:58:0)
[00:06:46.173] Timer tick, asking Buildbucket for the build status
[00:06:46.256] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:46.256] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:59:0) after 1m0s
[00:07:46.310] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:59:0)
[00:07:46.310] Timer tick, asking Buildbucket for the build status
[00:07:46.641] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:46.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:60:0) after 1m0s
[00:08:46.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:60:0)
[00:08:46.659] Timer tick, asking Buildbucket for the build status
[00:08:46.721] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:46.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:61:0) after 1m0s
[00:09:46.745] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:61:0)
[00:09:46.745] Timer tick, asking Buildbucket for the build status
[00:09:46.880] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:46.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:62:0) after 1m0s
[00:10:46.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:62:0)
[00:10:46.897] Timer tick, asking Buildbucket for the build status
[00:10:46.969] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:46.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:63:0) after 1m0s
[00:11:46.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:63:0)
[00:11:46.992] Timer tick, asking Buildbucket for the build status
[00:11:47.055] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:47.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:64:0) after 1m0s
[00:12:47.075] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:64:0)
[00:12:47.075] Timer tick, asking Buildbucket for the build status
[00:12:47.239] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:47.239] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:65:0) after 1m0s
[00:13:47.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:65:0)
[00:13:47.258] Timer tick, asking Buildbucket for the build status
[00:13:47.323] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:47.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:66:0) after 1m0s
[00:14:47.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:66:0)
[00:14:47.344] Timer tick, asking Buildbucket for the build status
[00:14:47.423] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:47.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:67:0) after 1m0s
[00:15:47.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:67:0)
[00:15:47.445] Timer tick, asking Buildbucket for the build status
[00:15:47.532] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:47.532] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:68:0) after 1m0s
[00:16:47.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:68:0)
[00:16:47.556] Timer tick, asking Buildbucket for the build status
[00:16:47.624] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:47.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:69:0) after 1m0s
[00:17:47.663] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:69:0)
[00:17:47.663] Timer tick, asking Buildbucket for the build status
[00:17:47.868] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:47.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:70:0) after 1m0s
[00:18:47.891] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:70:0)
[00:18:47.891] Timer tick, asking Buildbucket for the build status
[00:18:48.000] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:48.000] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:71:0) after 1m0s
[00:19:48.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:71:0)
[00:19:48.022] Timer tick, asking Buildbucket for the build status
[00:19:48.054] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:48.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:72:0) after 1m0s
[00:20:48.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:72:0)
[00:20:48.078] Timer tick, asking Buildbucket for the build status
[00:20:48.197] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:48.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:73:0) after 1m0s
[00:21:48.235] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:73:0)
[00:21:48.235] Timer tick, asking Buildbucket for the build status
[00:21:48.420] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:48.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:74:0) after 1m0s
[00:22:48.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:74:0)
[00:22:48.441] Timer tick, asking Buildbucket for the build status
[00:22:48.576] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:48.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:75:0) after 1m0s
[00:23:48.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:75:0)
[00:23:48.598] Timer tick, asking Buildbucket for the build status
[00:23:48.667] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:48.667] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:76:0) after 1m0s
[00:24:48.688] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:76:0)
[00:24:48.688] Timer tick, asking Buildbucket for the build status
[00:24:48.760] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:48.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:77:0) after 1m0s
[00:25:48.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:77:0)
[00:25:48.780] Timer tick, asking Buildbucket for the build status
[00:25:48.920] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:48.920] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:78:0) after 1m0s
[00:26:48.941] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:78:0)
[00:26:48.941] Timer tick, asking Buildbucket for the build status
[00:26:49.022] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:49.022] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:79:0) after 1m0s
[00:27:49.041] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:79:0)
[00:27:49.041] Timer tick, asking Buildbucket for the build status
[00:27:49.102] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:49.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:80:0) after 1m0s
[00:28:49.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:80:0)
[00:28:49.230] Timer tick, asking Buildbucket for the build status
[00:28:49.401] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:49.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:81:0) after 1m0s
[00:29:49.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:81:0)
[00:29:49.419] Timer tick, asking Buildbucket for the build status
[00:29:49.542] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:49.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:82:0) after 1m0s
[00:30:49.561] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:82:0)
[00:30:49.561] Timer tick, asking Buildbucket for the build status
[00:30:49.857] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:49.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:83:0) after 1m0s
[00:31:49.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:83:0)
[00:31:49.880] Timer tick, asking Buildbucket for the build status
[00:31:50.024] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:50.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:84:0) after 1m0s
[00:32:50.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:84:0)
[00:32:50.042] Timer tick, asking Buildbucket for the build status
[00:32:50.158] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:50.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:85:0) after 1m0s
[00:33:50.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:85:0)
[00:33:50.179] Timer tick, asking Buildbucket for the build status
[00:33:50.288] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:50.288] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:86:0) after 1m0s
[00:34:50.320] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:86:0)
[00:34:50.320] Timer tick, asking Buildbucket for the build status
[00:34:50.420] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:50.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:87:0) after 1m0s
[00:35:50.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:87:0)
[00:35:50.445] Timer tick, asking Buildbucket for the build status
[00:35:50.523] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:50.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:88:0) after 1m0s
[00:36:50.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:88:0)
[00:36:50.545] Timer tick, asking Buildbucket for the build status
[00:36:50.630] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:50.630] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:89:0) after 1m0s
[00:37:50.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:89:0)
[00:37:50.658] Timer tick, asking Buildbucket for the build status
[00:37:50.712] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:50.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:90:0) after 1m0s
[00:38:50.731] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:90:0)
[00:38:50.731] Timer tick, asking Buildbucket for the build status
[00:38:50.781] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:50.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:91:0) after 1m0s
[00:39:50.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:91:0)
[00:39:50.804] Timer tick, asking Buildbucket for the build status
[00:39:50.905] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:50.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:92:0) after 1m0s
[00:40:50.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:92:0)
[00:40:50.929] Timer tick, asking Buildbucket for the build status
[00:40:51.657] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:51.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:93:0) after 1m0s
[00:41:51.699] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:93:0)
[00:41:51.699] Timer tick, asking Buildbucket for the build status
[00:41:51.774] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:51.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:94:0) after 1m0s
[00:42:51.859] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:94:0)
[00:42:51.859] Timer tick, asking Buildbucket for the build status
[00:42:52.004] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:52.004] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:95:0) after 1m0s
[00:43:52.023] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:95:0)
[00:43:52.023] Timer tick, asking Buildbucket for the build status
[00:43:52.091] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:52.091] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:96:0) after 1m0s
[00:44:52.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:96:0)
[00:44:52.203] Timer tick, asking Buildbucket for the build status
[00:44:52.298] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:52.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:97:0) after 1m0s
[00:45:52.330] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:97:0)
[00:45:52.330] Timer tick, asking Buildbucket for the build status
[00:45:52.643] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:52.643] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:98:0) after 1m0s
[00:46:52.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:98:0)
[00:46:52.665] Timer tick, asking Buildbucket for the build status
[00:46:52.709] Build 8934552019442697968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:52.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099982081748681456:99:0) after 1m0s
[00:47:12.393] Received PubSub notification, asking Buildbucket for the build status
[00:47:12.483] Build 8934552019442697968: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:47:12.483] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1537750031198337",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1537744232669100",
"id": "8934552019442697968",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c635c06ebe25e8376972fa36ee44642e79507629\"}}",
"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/8934552019442697968/+/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\": [\"3783-5e6adab\"], \"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\": \"9f85491d8830fe6f3b8e0efa2e954f71bd9c64af\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"c635c06ebe25e8376972fa36ee44642e79507629\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24780}\", \"$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@{#593459}\", \"branch\": \"refs/heads/master\", \"revision\": \"c635c06ebe25e8376972fa36ee44642e79507629\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"0ad0c27a0bcee24ec9dc5c27df090034e152bd65\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"55e57f96fbf84a7d42e807d25a304362b76da917\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1537744232669100, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/c635c06ebe25e8376972fa36ee44642e79507629\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c635c06ebe25e8376972fa36ee44642e79507629\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9099982081748681456\", \"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\": \"8934552019442697968\"}}, \"got_v8_revision\": \"2f22d11156ccb1f88587896c27151944cad48cba\", \"got_v8_revision_cp\": \"refs/heads/7.1.163@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10179, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"314761de097e67d00ada5f4aabf483290a4098d9\", \"notification_helper_unittests\": \"136f153d5f499f719c520f023a77d9b938c567ff\", \"gpu_perftests\": \"75887b9f09125b71b6706cf008b1414929b89a39\", \"vr_common_unittests\": \"3cf67395e1e2954f9e89718e781dcc0e5b3a237b\", \"telemetry_unittests\": \"0ddaaaed74bcab4778307303daa83278203d1d54\", \"load_library_perf_tests\": \"8d4762b7a4a084b6f986a705050f6849dcce96e3\", \"devtools_closure_compile\": \"4c14287de95e3ba0fa6108ba6cb04b37f0119f15\", \"chromedriver_py_tests\": \"ad93de3f96bdb33b2ee1f2ce2a1f17f155ba448f\", \"mojo_core_unittests\": \"02df1ba071024bcd3fe54c557a7cfa47b55953a0\", \"install_static_unittests\": \"db30dd750715021e0d3ad0427f8c3c0e883f1ba2\", \"leveldb_service_unittests\": \"60d2d8ebd93e6a89baf85c42902c7ef909d5e9a4\", \"mini_installer_tests\": \"f8e1e41fef0b5a90a984f4f34f33651f6f829a92\", \"jingle_unittests\": \"9a5e17036d95078b248f5658c58deea76da8d249\", \"sbox_integration_tests\": \"4a923b829898ab00ffdc84750a1e0beb2c6ca19a\", \"angle_unittests\": \"36283d923d4014be8d56671705c88c87ad601a01\", \"cast_unittests\": \"e1b1f30de57255190d2ed1315600ade46dcda94e\", \"cc_unittests\": \"90f1f0cd53c6120716dd4229eba2105efbdd7f0b\", \"blink_common_unittests\": \"bd44ff080b86b5da281ea5ad0fd5e026dddaa2fc\", \"crashpad_tests\": \"cb61d015b365751d1a356bd65f7cd1adfa4598b8\", \"telemetry_perf_tests_without_chrome\": \"08f8014dd17cc8b72167f465aeecb48d1450ad74\", \"metrics_python_tests\": \"e95e6ad6e22e16e8380bffda0940094e78415b5f\", \"message_center_unittests\": \"2054d1523d088d25b68ebea5f8e33ebceb39dd9c\", \"ppapi_unittests\": \"cc8dcfb37a6a92c3e495d4033e88e30ef516691f\", \"extensions_unittests\": \"a312409337171333fcaed4524c62ce9181ebce73\", \"gl_unittests_ozone\": \"cb33148ad4c6dfa98676664abc7a76028a7bdb44\", \"app_shell_unittests\": \"8bb31116a798d80ab79015bcc4160bfce3a93834\", \"chrome_app_unittests\": \"dfa2d903fe96bf2f3b8ef2e592cda3f6f5145c6a\", \"gl_unittests\": \"cb33148ad4c6dfa98676664abc7a76028a7bdb44\", \"gcm_unit_tests\": \"43ee812707947794de0fcc11f101d502c43340e8\", \"views_unittests\": \"a7643cfaa1fbd4ea7b85c5f93e97c594f67ea9f3\", \"content_shell_crash_test\": \"fb3e0637e6e7c2c1cc15f8ed8828ca76f83a7ca2\", \"ui_base_unittests\": \"571ebd402575382f175e706dd6fd2362fd7920c0\", \"gles2_conform_test\": \"44e5605930fc986aa3d998038c43777ce4594430\", \"cronet_unittests\": \"bca59d50526c2b272553e57d3c9c64f066f561ef\", \"performance_test_suite\": \"b25d13858f65ae893bd2815776b930293adefade\", \"wm_unittests\": \"6d81ccef56cbe8fc628870ba2ccbacb62a745030\", \"boringssl_crypto_tests\": \"42f0af3f82979a9893eac3c3643f76b84529b018\", \"gin_unittests\": \"9aa08335250086609388cc7ab6e34268a90a47e0\", \"performance_browser_tests\": \"9cf62f912d955104b15beea44bd3af414e0b95c8\", \"extensions_browsertests\": \"4200309300e8ca74e57a7f467dc995b4b297bec8\", \"webkit_python_tests\": \"d158224fa80fea9494613a4e2cc83c3710e1a2b2\", \"headless_unittests\": \"dc4ac7efe25a76997edf9ebcfdfab7a20092635f\", \"webkit_layout_tests\": \"6838e334d0fbd32e44ba279282bcabe3a63c8970\", \"vr_pixeltests\": \"10dba2b64a7a8c5b8e49b4cec9fedb72a175d54b\", \"setup_unittests\": \"56b710e3858f41e578d21cafc12288fe8cede364\", \"webkit_unit_tests\": \"24f2f8101fa8f0a02361783cf92f4caf4db28ce1\", \"content_unittests\": \"5d077e08984e5d7dba6e9c47db45a47093003a91\", \"performance_webview_test_suite\": \"2766d23e12e975717238d96b270a34fc3d7d39d1\", \"wtf_unittests\": \"6330a5dd1e366167efe69357e7de6901969305ce\", \"webkit_layout_tests_exparchive\": \"b1b69979c7e0a8a94ee4b5f852b1ed0371b01dba\", \"chrome_cleaner_unittests\": \"b4bbd377ebe2e6f191960cc64082c8447a1835d0\", \"traffic_annotation_auditor_unittests\": \"84f8f55a7ce53e8d25db995da87ee68e7f3a085e\", \"libjingle_xmpp_unittests\": \"c49e03c8a53c9da5f9ca94a7107852ee8b304bf9\", \"remoting_unittests\": \"980827044cf9c1d5fd4dc59fcbe59c934ef158b8\", \"vr_common_perftests\": \"ddeca5e3b3a61cbc79d0bd57e2ebe603a7fa1ae6\", \"filesystem_service_unittests\": \"610cb3d5b1811147f3b61b816795fecc7356feb7\", \"chrome_elf_import_unittests\": \"7491942c4e24e9141c6b522ee4c8bc8552dc1e31\", \"installer_util_unittests\": \"4ca11fda636fb40df5d8644f5f633ad111faa3e0\", \"gfx_unittests\": \"7fd8dd90526223ea5785a35af0b15416cdb84d12\", \"headless_browsertests\": \"3a6e6d5720eb4a4c77d65d9224b66611c2afb216\", \"capture_unittests\": \"518d4b9475a17da1cde25e23e318b02a66eb4858\", \"chrome_elf_unittests\": \"6c953391952896b4fe0e5c9577be34345b3e1da9\", \"telemetry_gpu_unittests\": \"4ac9381e6a8d96a78b0c3b3c7c9fa029d5f40814\", \"command_buffer_perftests\": \"b97d04e37c33e2ae8740760b1e34f1de506c567e\", \"media_unittests\": \"92ad1c5fb53859e8a622d3f3ac8bc976a04969a1\", \"chromedriver_unittests\": \"c260d847d9b8c93c078202089390ecf22c561b44\", \"views_mus_interactive_ui_tests\": \"cf198b71a31f6941b38f18630473a5be6c1843f6\", \"storage_unittests\": \"76b0f740408ff5af8eaf808a2993fcefb8180b6c\", \"media_service_unittests\": \"50f178e9d9b1242c24f9b6ccb84115fe5a70b37d\", \"compositor_unittests\": \"097352350326634427c0b03fc5b1abe842e9abf5\", \"latency_unittests\": \"69e025be9e309fddee93bf6d3b8c749fd19823ff\", \"video_decode_accelerator_unittest\": \"dc6a0e16949c563d8ebaf3df4a8609e283512761\", \"swiftshader_unittests\": \"878de22b3275f0ccb373e993ceeba1231407f3dd\", \"telemetry_perf_unittests\": \"5b682e3d2b4f374d65f520a9e5338b6774091429\", \"media_blink_unittests\": \"3e9168df58b8f474cc2f9bbb32e016bd4b32a75d\", \"views_mus_unittests\": \"712caa446c8c3bbc32033f42156b86e77f844d4d\", \"components_unittests\": \"dfcabcf4b07bf1f9c81b4808e7d6614a740cc870\", \"snapshot_unittests\": \"0c39568ec9a82d1c59378202eb8c11557f4e7f2b\", \"accessibility_unittests\": \"d24f6f2b39eb6004958bfa2924e52e2d37e19c57\", \"net_perftests\": \"40ec6344ecd55d2db0b2cea9826770b5078170e2\", \"aura_unittests\": \"92c031e2a5bb760fb96150861f23e257ac994d70\", \"services_unittests\": \"2305614773cdf08f3b781c615bc01402fe43b18f\", \"components_perftests\": \"e7c5b620eda91ce955c6d9a9ef626fed5a73eeb1\", \"events_unittests\": \"61478936914c00e63f29d019719fa99c2af95c9d\", \"tracing_perftests\": \"2e0057526c5b177d2e565b17036ddb960a1e0416\", \"chromedriver_replay_unittests\": \"3d75567c4cb5be67d5cf025731258bcd9f3d4b8a\", \"ui_touch_selection_unittests\": \"45db612be15175e731ce5d4e37e20b838e63b5ed\", \"cacheinvalidation_unittests\": \"470ce1496e645fb24338f72c0841750602a6619d\", \"viz_unittests\": \"19c20fc044f39caf1d775769d2832a01915bae0e\", \"browser_tests\": \"02106c6662d5c01a9ffd2e2903f1e4c132665182\", \"net_unittests\": \"4bbeec85fd7ae0ed2f8f7d9da683b137dc70fcd5\", \"midi_unittests\": \"9aae1a581541c8ef687cb66dae0e1ee7181debc8\", \"boringssl_ssl_tests\": \"0ba0fcaff6f265a48cc1c1f2b1babea798fea781\", \"sbox_validation_tests\": \"d15adb290a5bce94bc16392b15c5393ca62e6f1e\", \"elevation_service_unittests\": \"3daf1ca8035606d3a56142305402466b3875ca96\", \"gpu_unittests\": \"ea28aee3e71ff16fab6e13a05beb3c6c50f6dbcb\", \"native_theme_unittests\": \"253f2428c006be9f266ff2fa78e9e7485a3f8b36\", \"interactive_ui_tests\": \"789e87b4ec888083712e9308f4d562ffa9f637f9\", \"mojo_unittests\": \"33620517a98a1b536e7e1fc928570830aa9e3d2b\", \"url_unittests\": \"09a06ab47fc2e88ae8de2230f772ff9ed5349be5\", \"media_perftests\": \"474fa4269211c0945306d30ce60507726c858dd0\", \"cronet_tests\": \"e7929eb7e48e0083193b7b9e67369af246b59f45\", \"google_apis_unittests\": \"5c675c442c67c89f956b23552d1b63cd9cd6c58d\", \"telemetry_gpu_integration_test\": \"30f8ad01da3b58d52ff24cc7cd449d0de705de48\", \"blink_heap_unittests\": \"17ae0a7a3de7a74062c176b89cf30c60822c3565\", \"base_unittests\": \"4443796ab94f8574ba4e0d6f8e73ba714db421a2\", \"sync_integration_tests\": \"7b43ec54ac7bd741aa0556d86854ff03d53b7214\", \"sql_unittests\": \"0c82cf8097d8ff4c73c58d453cda44d80ed15df7\", \"courgette_unittests\": \"82303ce9819980655b6189049f61d94926da0782\", \"blink_fuzzer_unittests\": \"01a2b69f4b10bac82890ed6695d6bca80af1e531\", \"gl_tests\": \"aad1fc63e0028fc93a81356594b16b19522e17cf\", \"zucchini_unittests\": \"5da8593b94100e441824f2ae1a55abfdbc07b9e4\", \"ipc_tests\": \"de87119a47f2b478e95081b6880e8a2e279c43a8\", \"angle_end2end_tests\": \"a8119e8ea4d599c672434baccf7f71381995a99a\", \"blink_platform_unittests\": \"dd60383f672836c167905f1e93fd64286297af57\", \"components_browsertests\": \"66aa8533491f34dc727d0adcab331edab9bea2f1\", \"views_perftests\": \"00692d728f71d755a96ecb4a404c91a9ea7746fd\", \"angle_perftests\": \"55ec85e31e7aee4b59de1628a993aa9eff53b09a\", \"display_unittests\": \"5359341a8c6fa449ef4401f9abfb449039549f3b\", \"sbox_unittests\": \"258c2a9ba87697429803db4247c29be882a5b0d1\", \"service_manager_unittests\": \"7651c1a43b63d0f33b26cf2293b86a03c97b407a\", \"pdf_unittests\": \"4966aeff27af58f0eaca5bb09d8ee4d6307cc13a\", \"skia_unittests\": \"053a3987484fb4c0e54ec550e44662a99f84edd5\", \"angle_white_box_tests\": \"e167a97d329013427fd9085d2394caf7ef166b8d\", \"crypto_unittests\": \"6426c53042791fd08bea24507d020c953d5a5c62\", \"nacl_loader_unittests\": \"43613e5e0514f0d70d9533f7d2bd3d9a1a318ea4\", \"unit_tests\": \"220b58ef6e0481688b101cfd120a649358014446\", \"content_browsertests\": \"c10f31276b10b972e7af3ad0d7811abde653c27a\", \"printing_unittests\": \"88de371e60dd22e5eaf94b4d3d7cc0b6dc35699f\", \"device_unittests\": \"46b3d894cc22f1ccd87081e31ca9f5778ba6a971\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1537744243548162",
"status": "COMPLETED",
"status_changed_ts": "1537750031637845",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10179",
"builder:Windows deterministic",
"buildset:commit/git/c635c06ebe25e8376972fa36ee44642e79507629",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c635c06ebe25e8376972fa36ee44642e79507629",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9099982081748681456",
"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/10179",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8934552019442697968",
"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/c635c06ebe25e8376972fa36ee44642e79507629",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c635c06ebe25e8376972fa36ee44642e79507629",
"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/8934552019442697968/+/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:9099982081748681456",
"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:ded6e667eedfba9ca83cacf95f0d2164f0246aeb",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:40218478cf2ba810",
"user_agent:luci-scheduler"
],
"updated_ts": "1537750031639870",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10179",
"utcnow_ts": "1537750032443474"
}
}
[00:47:12.483] Invocation finished in 1h36m41.396711876s with status SUCCEEDED