[09:14:46.358] New invocation is queued and will start shortly
[09:14:47.575] Starting the invocation (attempt 1)
[09:14:47.613] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:14:47.613] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:14:47.613] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9098766304793568848",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"86ae786cf056f4465e0421f05d67da54559a96f7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9098766304793568848",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/86ae786cf056f4465e0421f05d67da54559a96f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86ae786cf056f4465e0421f05d67da54559a96f7",
"gitiles_ref:refs/heads/master"
]
}
[09:14:52.058] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1538903687833958",
"id": "8933336242585548928",
"lease_expiration_ts": "1539047691694508",
"lease_key": "798262193",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"86ae786cf056f4465e0421f05d67da54559a96f7\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1538903691965020",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10327",
"builder:Windows deterministic",
"buildset:commit/git/86ae786cf056f4465e0421f05d67da54559a96f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86ae786cf056f4465e0421f05d67da54559a96f7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9098766304793568848",
"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/10327",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8933336242585548928",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:1",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/86ae786cf056f4465e0421f05d67da54559a96f7",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86ae786cf056f4465e0421f05d67da54559a96f7",
"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/8933336242585548928/+/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:9098766304793568848",
"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:7a2c6a280a68ea7a8c9976d934a89c38009336b9",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4066a0585aaf8b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1538903691965124",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10327",
"utcnow_ts": "1538903692045641"
}
}
[09:14:52.058] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10327
[09:14:52.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:2:0) after 1m0s
[09:14:53.533] Received PubSub notification, asking Buildbucket for the build status
[09:14:53.622] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:52.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:2:0)
[09:15:52.172] Timer tick, asking Buildbucket for the build status
[09:15:52.325] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:52.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:4:0) after 1m0s
[09:16:52.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:4:0)
[09:16:52.434] Timer tick, asking Buildbucket for the build status
[09:16:52.479] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:52.479] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:5:0) after 1m0s
[09:17:52.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:5:0)
[09:17:52.582] Timer tick, asking Buildbucket for the build status
[09:17:52.744] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:52.744] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:6:0) after 1m0s
[09:18:52.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:6:0)
[09:18:52.899] Timer tick, asking Buildbucket for the build status
[09:18:53.112] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:53.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:7:0) after 1m0s
[09:19:53.218] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:7:0)
[09:19:53.218] Timer tick, asking Buildbucket for the build status
[09:19:53.298] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:53.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:8:0) after 1m0s
[09:20:53.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:8:0)
[09:20:53.357] Timer tick, asking Buildbucket for the build status
[09:20:53.407] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:53.407] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:9:0) after 1m0s
[09:21:53.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:9:0)
[09:21:53.461] Timer tick, asking Buildbucket for the build status
[09:21:53.543] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:53.543] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:10:0) after 1m0s
[09:22:53.596] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:10:0)
[09:22:53.596] Timer tick, asking Buildbucket for the build status
[09:22:53.662] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:53.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:11:0) after 1m0s
[09:23:53.810] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:11:0)
[09:23:53.810] Timer tick, asking Buildbucket for the build status
[09:23:53.868] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:53.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:12:0) after 1m0s
[09:24:53.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:12:0)
[09:24:53.929] Timer tick, asking Buildbucket for the build status
[09:24:53.969] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:53.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:13:0) after 1m0s
[09:25:54.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:13:0)
[09:25:54.005] Timer tick, asking Buildbucket for the build status
[09:25:54.076] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:54.076] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:14:0) after 1m0s
[09:26:54.098] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:14:0)
[09:26:54.098] Timer tick, asking Buildbucket for the build status
[09:26:54.273] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:54.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:15:0) after 1m0s
[09:27:54.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:15:0)
[09:27:54.300] Timer tick, asking Buildbucket for the build status
[09:27:54.366] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:54.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:16:0) after 1m0s
[09:28:54.396] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:16:0)
[09:28:54.396] Timer tick, asking Buildbucket for the build status
[09:28:54.460] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:54.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:17:0) after 1m0s
[09:29:54.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:17:0)
[09:29:54.478] Timer tick, asking Buildbucket for the build status
[09:29:54.645] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:54.645] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:18:0) after 1m0s
[09:30:54.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:18:0)
[09:30:54.678] Timer tick, asking Buildbucket for the build status
[09:30:54.841] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:54.841] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:19:0) after 1m0s
[09:31:54.864] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:19:0)
[09:31:54.864] Timer tick, asking Buildbucket for the build status
[09:31:54.963] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:54.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:20:0) after 1m0s
[09:32:54.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:20:0)
[09:32:54.985] Timer tick, asking Buildbucket for the build status
[09:32:55.074] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:55.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:21:0) after 1m0s
[09:33:55.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:21:0)
[09:33:55.097] Timer tick, asking Buildbucket for the build status
[09:33:55.148] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:55.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:22:0) after 1m0s
[09:34:55.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:22:0)
[09:34:55.169] Timer tick, asking Buildbucket for the build status
[09:34:55.365] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:55.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:23:0) after 1m0s
[09:35:55.416] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:23:0)
[09:35:55.416] Timer tick, asking Buildbucket for the build status
[09:35:55.483] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:55.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:24:0) after 1m0s
[09:36:55.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:24:0)
[09:36:55.646] Timer tick, asking Buildbucket for the build status
[09:36:55.842] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:55.843] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:25:0) after 1m0s
[09:37:55.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:25:0)
[09:37:55.972] Timer tick, asking Buildbucket for the build status
[09:37:56.334] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:56.334] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:26:0) after 1m0s
[09:38:56.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:26:0)
[09:38:56.441] Timer tick, asking Buildbucket for the build status
[09:38:56.551] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:56.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:27:0) after 1m0s
[09:39:56.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:27:0)
[09:39:56.681] Timer tick, asking Buildbucket for the build status
[09:39:56.774] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:56.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:28:0) after 1m0s
[09:40:56.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:28:0)
[09:40:56.925] Timer tick, asking Buildbucket for the build status
[09:40:57.132] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:57.132] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:29:0) after 1m0s
[09:41:57.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:29:0)
[09:41:57.150] Timer tick, asking Buildbucket for the build status
[09:41:57.212] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:57.212] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:30:0) after 1m0s
[09:42:57.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:30:0)
[09:42:57.230] Timer tick, asking Buildbucket for the build status
[09:42:57.413] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:57.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:31:0) after 1m0s
[09:43:57.439] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:31:0)
[09:43:57.439] Timer tick, asking Buildbucket for the build status
[09:43:57.666] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:57.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:32:0) after 1m0s
[09:44:57.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:32:0)
[09:44:57.684] Timer tick, asking Buildbucket for the build status
[09:44:57.812] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:57.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:33:0) after 1m0s
[09:45:57.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:33:0)
[09:45:57.831] Timer tick, asking Buildbucket for the build status
[09:45:57.907] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:57.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:34:0) after 1m0s
[09:46:58.010] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:34:0)
[09:46:58.010] Timer tick, asking Buildbucket for the build status
[09:46:58.086] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:58.086] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:35:0) after 1m0s
[09:47:58.115] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:35:0)
[09:47:58.115] Timer tick, asking Buildbucket for the build status
[09:47:58.228] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:58.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:36:0) after 1m0s
[09:48:58.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:36:0)
[09:48:58.247] Timer tick, asking Buildbucket for the build status
[09:48:58.349] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:58.349] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:37:0) after 1m0s
[09:49:58.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:37:0)
[09:49:58.367] Timer tick, asking Buildbucket for the build status
[09:49:58.450] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:58.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:38:0) after 1m0s
[09:50:58.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:38:0)
[09:50:58.467] Timer tick, asking Buildbucket for the build status
[09:50:58.508] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:58.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:39:0) after 1m0s
[09:51:58.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:39:0)
[09:51:58.640] Timer tick, asking Buildbucket for the build status
[09:51:58.691] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:58.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:40:0) after 1m0s
[09:52:58.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:40:0)
[09:52:58.780] Timer tick, asking Buildbucket for the build status
[09:52:58.875] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:58.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:41:0) after 1m0s
[09:53:58.893] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:41:0)
[09:53:58.893] Timer tick, asking Buildbucket for the build status
[09:53:59.014] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:59.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:42:0) after 1m0s
[09:54:59.041] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:42:0)
[09:54:59.041] Timer tick, asking Buildbucket for the build status
[09:54:59.107] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:59.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:43:0) after 1m0s
[09:55:59.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:43:0)
[09:55:59.205] Timer tick, asking Buildbucket for the build status
[09:55:59.248] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:59.248] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:44:0) after 1m0s
[09:56:59.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:44:0)
[09:56:59.340] Timer tick, asking Buildbucket for the build status
[09:56:59.380] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:59.380] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:45:0) after 1m0s
[09:57:59.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:45:0)
[09:57:59.431] Timer tick, asking Buildbucket for the build status
[09:57:59.529] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:59.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:46:0) after 1m0s
[09:58:59.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:46:0)
[09:58:59.551] Timer tick, asking Buildbucket for the build status
[09:58:59.617] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:59.617] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:47:0) after 1m0s
[09:59:59.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:47:0)
[09:59:59.634] Timer tick, asking Buildbucket for the build status
[09:59:59.717] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:59.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:48:0) after 1m0s
[10:00:59.851] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:48:0)
[10:00:59.851] Timer tick, asking Buildbucket for the build status
[10:00:59.971] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:59.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:49:0) after 1m0s
[10:01:59.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:49:0)
[10:01:59.996] Timer tick, asking Buildbucket for the build status
[10:02:00.088] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:00.088] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:50:0) after 1m0s
[10:03:00.109] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:50:0)
[10:03:00.109] Timer tick, asking Buildbucket for the build status
[10:03:00.209] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:00.209] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:51:0) after 1m0s
[10:04:00.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:51:0)
[10:04:00.229] Timer tick, asking Buildbucket for the build status
[10:04:00.295] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:00.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:52:0) after 1m0s
[10:05:00.316] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:52:0)
[10:05:00.316] Timer tick, asking Buildbucket for the build status
[10:05:00.393] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:00.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:53:0) after 1m0s
[10:06:00.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:53:0)
[10:06:00.497] Timer tick, asking Buildbucket for the build status
[10:06:00.650] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:00.650] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:54:0) after 1m0s
[10:07:00.817] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:54:0)
[10:07:00.817] Timer tick, asking Buildbucket for the build status
[10:07:00.936] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:00.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:55:0) after 1m0s
[10:08:00.955] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:55:0)
[10:08:00.955] Timer tick, asking Buildbucket for the build status
[10:08:01.255] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:01.255] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:56:0) after 1m0s
[10:09:01.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:56:0)
[10:09:01.285] Timer tick, asking Buildbucket for the build status
[10:09:01.354] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:01.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:57:0) after 1m0s
[10:10:01.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:57:0)
[10:10:01.484] Timer tick, asking Buildbucket for the build status
[10:10:01.556] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:01.556] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:58:0) after 1m0s
[10:11:01.616] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:58:0)
[10:11:01.616] Timer tick, asking Buildbucket for the build status
[10:11:01.796] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:01.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:59:0) after 1m0s
[10:12:01.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:59:0)
[10:12:01.928] Timer tick, asking Buildbucket for the build status
[10:12:02.101] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:02.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:60:0) after 1m0s
[10:13:02.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:60:0)
[10:13:02.146] Timer tick, asking Buildbucket for the build status
[10:13:02.310] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:02.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:61:0) after 1m0s
[10:14:02.396] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:61:0)
[10:14:02.396] Timer tick, asking Buildbucket for the build status
[10:14:02.454] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:02.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:62:0) after 1m0s
[10:15:02.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:62:0)
[10:15:02.480] Timer tick, asking Buildbucket for the build status
[10:15:02.568] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:02.568] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:63:0) after 1m0s
[10:16:02.587] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:63:0)
[10:16:02.587] Timer tick, asking Buildbucket for the build status
[10:16:02.659] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:02.659] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:64:0) after 1m0s
[10:17:02.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:64:0)
[10:17:02.678] Timer tick, asking Buildbucket for the build status
[10:17:03.695] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:03.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:65:0) after 1m0s
[10:18:03.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:65:0)
[10:18:03.814] Timer tick, asking Buildbucket for the build status
[10:18:03.968] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:03.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:66:0) after 1m0s
[10:19:03.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:66:0)
[10:19:03.988] Timer tick, asking Buildbucket for the build status
[10:19:04.033] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:04.033] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:67:0) after 1m0s
[10:20:04.134] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:67:0)
[10:20:04.134] Timer tick, asking Buildbucket for the build status
[10:20:04.388] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:04.388] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:68:0) after 1m0s
[10:21:04.410] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:68:0)
[10:21:04.410] Timer tick, asking Buildbucket for the build status
[10:21:04.549] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:04.550] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:69:0) after 1m0s
[10:22:04.600] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:69:0)
[10:22:04.600] Timer tick, asking Buildbucket for the build status
[10:22:04.715] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:04.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:70:0) after 1m0s
[10:23:04.751] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:70:0)
[10:23:04.751] Timer tick, asking Buildbucket for the build status
[10:23:04.795] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:04.795] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:71:0) after 1m0s
[10:24:04.816] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:71:0)
[10:24:04.816] Timer tick, asking Buildbucket for the build status
[10:24:04.914] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:04.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:72:0) after 1m0s
[10:25:04.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:72:0)
[10:25:04.934] Timer tick, asking Buildbucket for the build status
[10:25:05.076] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:05.076] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:73:0) after 1m0s
[10:26:05.102] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:73:0)
[10:26:05.102] Timer tick, asking Buildbucket for the build status
[10:26:05.184] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:05.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:74:0) after 1m0s
[10:27:05.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:74:0)
[10:27:05.207] Timer tick, asking Buildbucket for the build status
[10:27:05.273] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:05.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:75:0) after 1m0s
[10:28:05.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:75:0)
[10:28:05.365] Timer tick, asking Buildbucket for the build status
[10:28:05.416] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:05.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:76:0) after 1m0s
[10:29:05.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:76:0)
[10:29:05.441] Timer tick, asking Buildbucket for the build status
[10:29:05.545] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:05.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:77:0) after 1m0s
[10:30:05.567] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:77:0)
[10:30:05.567] Timer tick, asking Buildbucket for the build status
[10:30:05.630] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:05.631] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:78:0) after 1m0s
[10:31:05.651] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:78:0)
[10:31:05.651] Timer tick, asking Buildbucket for the build status
[10:31:06.240] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:06.240] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:79:0) after 1m0s
[10:32:06.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:79:0)
[10:32:06.264] Timer tick, asking Buildbucket for the build status
[10:32:06.303] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:06.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:80:0) after 1m0s
[10:33:06.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:80:0)
[10:33:06.322] Timer tick, asking Buildbucket for the build status
[10:33:06.409] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:06.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:81:0) after 1m0s
[10:34:06.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:81:0)
[10:34:06.429] Timer tick, asking Buildbucket for the build status
[10:34:06.542] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:06.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:82:0) after 1m0s
[10:35:06.561] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:82:0)
[10:35:06.561] Timer tick, asking Buildbucket for the build status
[10:35:06.683] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:06.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:83:0) after 1m0s
[10:36:06.704] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:83:0)
[10:36:06.705] Timer tick, asking Buildbucket for the build status
[10:36:06.793] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:06.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:84:0) after 1m0s
[10:37:06.823] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:84:0)
[10:37:06.823] Timer tick, asking Buildbucket for the build status
[10:37:06.972] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:06.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:85:0) after 1m0s
[10:38:06.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:85:0)
[10:38:06.992] Timer tick, asking Buildbucket for the build status
[10:38:07.069] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:07.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:86:0) after 1m0s
[10:39:07.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:86:0)
[10:39:07.089] Timer tick, asking Buildbucket for the build status
[10:39:07.221] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:07.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:87:0) after 1m0s
[10:40:07.246] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:87:0)
[10:40:07.246] Timer tick, asking Buildbucket for the build status
[10:40:07.357] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:07.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:88:0) after 1m0s
[10:41:07.401] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:88:0)
[10:41:07.401] Timer tick, asking Buildbucket for the build status
[10:41:07.447] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:07.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:89:0) after 1m0s
[10:42:07.471] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:89:0)
[10:42:07.471] Timer tick, asking Buildbucket for the build status
[10:42:07.630] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:07.630] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:90:0) after 1m0s
[10:43:07.653] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:90:0)
[10:43:07.653] Timer tick, asking Buildbucket for the build status
[10:43:07.723] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:07.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:91:0) after 1m0s
[10:44:07.746] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:91:0)
[10:44:07.746] Timer tick, asking Buildbucket for the build status
[10:44:07.825] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:07.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:92:0) after 1m0s
[10:45:07.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:92:0)
[10:45:07.846] Timer tick, asking Buildbucket for the build status
[10:45:07.902] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:07.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:93:0) after 1m0s
[10:46:07.942] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:93:0)
[10:46:07.942] Timer tick, asking Buildbucket for the build status
[10:46:07.993] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:07.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:94:0) after 1m0s
[10:47:08.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:94:0)
[10:47:08.013] Timer tick, asking Buildbucket for the build status
[10:47:08.401] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:08.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:95:0) after 1m0s
[10:48:08.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:95:0)
[10:48:08.427] Timer tick, asking Buildbucket for the build status
[10:48:08.490] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:08.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:96:0) after 1m0s
[10:49:08.520] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:96:0)
[10:49:08.520] Timer tick, asking Buildbucket for the build status
[10:49:08.567] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:08.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:97:0) after 1m0s
[10:50:08.589] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:97:0)
[10:50:08.589] Timer tick, asking Buildbucket for the build status
[10:50:08.664] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:08.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:98:0) after 1m0s
[10:51:08.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:98:0)
[10:51:08.698] Timer tick, asking Buildbucket for the build status
[10:51:08.945] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:08.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:99:0) after 1m0s
[10:52:08.973] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:99:0)
[10:52:08.973] Timer tick, asking Buildbucket for the build status
[10:52:09.089] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:09.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:100:0) after 1m0s
[10:53:09.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:100:0)
[10:53:09.149] Timer tick, asking Buildbucket for the build status
[10:53:09.207] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:09.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:101:0) after 1m0s
[10:54:09.231] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:101:0)
[10:54:09.231] Timer tick, asking Buildbucket for the build status
[10:54:09.338] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:09.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:102:0) after 1m0s
[10:55:09.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:102:0)
[10:55:09.364] Timer tick, asking Buildbucket for the build status
[10:55:09.511] Build 8933336242585548928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:09.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098766304793568848:103:0) after 1m0s
[10:56:05.851] Received PubSub notification, asking Buildbucket for the build status
[10:56:05.891] Build 8933336242585548928: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:56:05.891] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1538909763913942",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1538903687833958",
"id": "8933336242585548928",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"86ae786cf056f4465e0421f05d67da54559a96f7\"}}",
"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/8933336242585548928/+/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\": [\"3801-8761768\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"id\": [\"swarm2173-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"2ebbdab4bfb1ed9dfe52ce756b34829207dbe25f\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"86ae786cf056f4465e0421f05d67da54559a96f7\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25031}\", \"$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@{#597455}\", \"branch\": \"refs/heads/master\", \"revision\": \"86ae786cf056f4465e0421f05d67da54559a96f7\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"343f4144bee5ddde5efec1e1217403b444386cb4\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"450bf36f7918d18f3186be8ce570d9eac63b309d\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1538903687833958, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/86ae786cf056f4465e0421f05d67da54559a96f7\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86ae786cf056f4465e0421f05d67da54559a96f7\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9098766304793568848\", \"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\": \"8933336242585548928\"}}, \"got_v8_revision\": \"7b190ebb6ca740cdb783c5f1d6e8aa1745076c3b\", \"got_v8_revision_cp\": \"refs/heads/7.1.278@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10327, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"4ba8d0824251bbc0a263c36854e2a4827c88ba83\", \"notification_helper_unittests\": \"aa769b86b450db0aabb7645525be43c0616ce5c1\", \"gpu_perftests\": \"c6f62b5e0f3dfdc3a4ddbd4f4cdadf43466da4c5\", \"vr_common_unittests\": \"8c7ffb5f5d60ad50e2a03fb1fbfb13a6d8c2774d\", \"telemetry_unittests\": \"1a9ea63f490704dbf4a8812690649b7f69a5bec1\", \"load_library_perf_tests\": \"c5cfacf5c411c16e174c131a1dcb6ac510fdd7e7\", \"devtools_closure_compile\": \"947ceeb4ceaa909d325ac331beebc7cb1b6ac0a7\", \"chromedriver_py_tests\": \"5d6157a0a7c09ba37906e5aab9e4462cd2c0b08b\", \"mojo_core_unittests\": \"8fe6bdc0c985b53cc85d0b372c8141587da0af90\", \"install_static_unittests\": \"2f8a0ae016d5d0743753ad6795c56fd72c43a875\", \"leveldb_service_unittests\": \"e2e486ceef849a148cc36d0fd109c2f7c6c14d2d\", \"mini_installer_tests\": \"b52ace7ac48e3091f39641f49aab49b73ed38f41\", \"jingle_unittests\": \"2f0c50320e894d4fdbc295b0e513d5e862bb50bc\", \"sbox_integration_tests\": \"8efd21ea8fb38a765986d8b42eab81648ca76f79\", \"angle_unittests\": \"e049ff5d76aee14e88c53bda5bf7edf3faaf087c\", \"cast_unittests\": \"1e8da85ef34b992722ced44aeae30e1e52bbaa25\", \"cc_unittests\": \"3ae4b3ebf53a3a0219a817619100214336c231d2\", \"blink_common_unittests\": \"02b928b0400a77564150e0ae0cf49d8892a2baaa\", \"crashpad_tests\": \"9b0ae4447a04927c8deb0308982b22f2f2c50b88\", \"telemetry_perf_tests_without_chrome\": \"ffdc0137847b125257645bbdc4d86e70f1b04fdb\", \"metrics_python_tests\": \"b4e4090d7c42df6b958c4a40710412bed7ee9ad6\", \"message_center_unittests\": \"7c34b2f291cf2b895d424ee5f922702b4d7bd934\", \"ppapi_unittests\": \"fea37b06a3eec99f917480f925a1e66b389b78ee\", \"extensions_unittests\": \"af0bd2be3e8e0a47b135c26c86633c2bdca9a3f8\", \"gl_unittests_ozone\": \"fa754fcd857c8502adb940b62dc43d80f7c05e98\", \"app_shell_unittests\": \"d577dcef2d91842b73fffc488f9ebef289daa732\", \"chrome_app_unittests\": \"1fa8435bcf6ee02d1f9bff385910ef6042aa2ec4\", \"gl_unittests\": \"fa754fcd857c8502adb940b62dc43d80f7c05e98\", \"gcm_unit_tests\": \"94a0d20088a1c0af26306cb5ab1381c14e042138\", \"views_unittests\": \"f8522efa9dcc0eff855996256c91800b21b93757\", \"content_shell_crash_test\": \"9dd452b4d20a30506a693ef38c3e98ccefa9de2a\", \"ui_base_unittests\": \"8d53a924f1226620be03028d756a9a3bb73af6d6\", \"gles2_conform_test\": \"1a00b887a93fd56f4fa44b43611d47707708c1c3\", \"cronet_unittests\": \"b1e125e0327bf8318ed5d050baac1863f9844abd\", \"performance_test_suite\": \"e9b77e6ae8630c6213dabadc5f478ff5cfd1b7fe\", \"wm_unittests\": \"1569cc10d67c4325df54df983228290a21dcbc48\", \"boringssl_crypto_tests\": \"4d819dd79edb0e8fb7f3376e36ba278d0b0a85e4\", \"gin_unittests\": \"bdeb06ad1a6460925be1270e8bc628507f709eb5\", \"performance_browser_tests\": \"647696c1613472208b8bf0efca1930b3074f3bd7\", \"extensions_browsertests\": \"595c2054773945cb3a65d7376ab0f90514466f78\", \"webkit_python_tests\": \"1182026ac5ebb0aa298541a05a56d3d287256f41\", \"headless_unittests\": \"fc145239a3555a285a185faaa48c27ca4214c8fc\", \"webkit_layout_tests\": \"066819237b0c440e9ef31cebe00e92917e29eb1d\", \"vr_pixeltests\": \"d36f48a86e21f501dc3d265cc84df2f40ea040f4\", \"setup_unittests\": \"85b1dcdcec80d4f74796836af6a3d5b7fc2d7862\", \"webkit_unit_tests\": \"5e1983416b0b78dbcce3fd8bbdc08d2a14c40e53\", \"content_unittests\": \"f3d3b632e56a6d77bf5acd751da526956ca2dc59\", \"performance_webview_test_suite\": \"e8e5ee841a58c05f6360d58572379d92d91954d1\", \"wtf_unittests\": \"ee0cb02830d8d1e5a8cbfd4221c146b14f6c6774\", \"webkit_layout_tests_exparchive\": \"3bd1e760edb5a6c1b6b3eb84c9369f0fed8aba01\", \"chrome_cleaner_unittests\": \"ce0d480065cfb7e4a0fdb94c6b28df36e1cce117\", \"traffic_annotation_auditor_unittests\": \"d75bab6e1c5c1deebfe0783295edd9b147d1139e\", \"libjingle_xmpp_unittests\": \"b62eabac73d7187ab7f438a02acc8690d93c338a\", \"remoting_unittests\": \"9439d16778cee3299e047c69a5ebf9786287883c\", \"vr_common_perftests\": \"d1117b33124c4a6d999a30b2fd7fa51f1d9f8c68\", \"filesystem_service_unittests\": \"2157f64454a452e19cf7619d1f88dfd34409c846\", \"chrome_elf_import_unittests\": \"d705e586ec7d0d436f5e376b835fa8b459b40273\", \"installer_util_unittests\": \"ce05c0f74ab865e5650a4a9840732e3cb59ce665\", \"gfx_unittests\": \"5f5f7afc059833173f0c9d971775e944374de4d6\", \"headless_browsertests\": \"b01f7d7db05ac784fdde5d7461522e31e3e335f2\", \"capture_unittests\": \"b9f8bba3527e93e40b5b3da3f431d51207abbf59\", \"chrome_elf_unittests\": \"06a154057dd4ad329fbfdf52e5797cc547c8cd2e\", \"telemetry_gpu_unittests\": \"74deb9a1eacc0a042f05d8bc40d1ee07a6fc879d\", \"command_buffer_perftests\": \"0c692a7e5dfdf06db2337f03caf83044768547fc\", \"media_unittests\": \"c22acaf53e52eddd20234fa95370b957e87b4fb7\", \"chromedriver_unittests\": \"a2b5b5f6c59ca692819536af791f228b087f0fc8\", \"views_mus_interactive_ui_tests\": \"4e2700a18db860bc8308a77bf8503841854a8212\", \"storage_unittests\": \"9b09a66bc66ddc8eae45077be3e8282fb12a2305\", \"media_service_unittests\": \"363778bc9646dc80f45c14d8a91eaf91357735fc\", \"compositor_unittests\": \"8a3679bfc2b958e6e3ac490dd67a3827e34ea985\", \"latency_unittests\": \"22d5ea95617d1db653ece5ee85588daf052c3a47\", \"video_decode_accelerator_unittest\": \"5e479f631d2a7b0e94eb2b62dfba87585dca841f\", \"swiftshader_unittests\": \"52019a53d061274c58351e1ac236c6a3937b3854\", \"telemetry_perf_unittests\": \"d35ebacf89261daaadf73949187d687cb136827a\", \"media_blink_unittests\": \"2085e8d967acc82a59cd48d687fbca47952d8fb2\", \"views_mus_unittests\": \"b1e30911c1c7f35744ba6241a1f475bb7f8e568b\", \"components_unittests\": \"be9bfb3e5a78c58cb0dd171daf85a079df7171bf\", \"snapshot_unittests\": \"4adf73120824fb963183b23166ca498ba9fa017b\", \"accessibility_unittests\": \"9d731d60fbf60b396d14857153222a58171c41f4\", \"net_perftests\": \"2d576afc47373616b34c116c657312744d71f096\", \"aura_unittests\": \"906f071d97f26f09a3a03f9c9f11ee646365ba8a\", \"services_unittests\": \"0a85620696136c29a451275ed20cc07c3b9e2f8f\", \"components_perftests\": \"069c721a8eba0c9650b1dfe3f9a8a71eb4f03dfd\", \"events_unittests\": \"ff2b125925eb2734cf1b6270aaca022b779ee26a\", \"tracing_perftests\": \"832f76d9ebd4e712b2e8ff1afd08c69b2abf0b0d\", \"chromedriver_replay_unittests\": \"5013a0f078eac50a549885e896698d163bf73605\", \"ui_touch_selection_unittests\": \"d8e3fd5ed32d66a6a05d7ca508194502923d9ca8\", \"cacheinvalidation_unittests\": \"319471427f886532789059b47be2dd4408334054\", \"viz_unittests\": \"fa67eb90018d0ff348501995fe147fd7c8a6e1b5\", \"browser_tests\": \"0f495b14bfb1c19099c4e550b529ce486f0c6bec\", \"net_unittests\": \"862917e4264635fda3c5b3b588906678ff1c0ad6\", \"midi_unittests\": \"2490b84ca40759b029d724acacab7de82aff1b6e\", \"boringssl_ssl_tests\": \"1ebf7764be0690c1416fc8baffaf4819b2fdca75\", \"sbox_validation_tests\": \"3cfc6bfb15ff935c6503daa418c165cd0bcb565e\", \"elevation_service_unittests\": \"79fb972e5d798b272209ac05431375b2ce66edd0\", \"gpu_unittests\": \"fdea3f542a9136367b00220fc3e03aafdc7e9728\", \"native_theme_unittests\": \"b9ba7d326be1fc89d58ad087fd1955347707896f\", \"interactive_ui_tests\": \"50dd6427af0fd561d8da3282bc9ed9feb0d55145\", \"mojo_unittests\": \"de2e77d8cd1d8d1d1cfb8f42ed8209b3194b6878\", \"url_unittests\": \"bb3bcbf1c7b811ce3b4ac04b4717f768ab3c0f47\", \"media_perftests\": \"6a9b8e077c47678e8edbbfec1928663b7bc00805\", \"cronet_tests\": \"747ab2a401911ad2e35220085479af78ee2d9b73\", \"google_apis_unittests\": \"411b795db8f490038cfb1f3227a24cead7cbdd16\", \"telemetry_gpu_integration_test\": \"fde311b3b8987717d39b53faf257082df3c0172d\", \"blink_heap_unittests\": \"c7740ad01d7e974430127566c6f94dd7b4c4ad6f\", \"base_unittests\": \"266b9ede15248acb42d46ac36cd309fbfcf95346\", \"sync_integration_tests\": \"e641922136edf099c591a81df1cb6a39c88d6ae4\", \"sql_unittests\": \"a0f32be466a48bdbaf3661e76ed3a06791937e8d\", \"courgette_unittests\": \"84594d63303da2aeca829cd835df3e01c7ac02d1\", \"blink_fuzzer_unittests\": \"234d89685f959449613c9b6ab9fc97db9d890ca5\", \"gl_tests\": \"f9bd692f87ce4b5d611ee465699eb8eba85d56f4\", \"zucchini_unittests\": \"91678d829ad8738769e1ab599af82efc27ee5a7f\", \"ipc_tests\": \"d566dae71fba748914671e837b779fccdd00e3e8\", \"angle_end2end_tests\": \"69a7a3943f32b1766b1d801e87c789ba4f39b533\", \"blink_platform_unittests\": \"b47372d8d07d522ea14dd15df9b10800b6e2932d\", \"components_browsertests\": \"438325f8596d8a2cda664e9fa5893d685315fe5f\", \"views_perftests\": \"f42b804116e2a6edbec2da85538f5756f5e8ad04\", \"angle_perftests\": \"43cd05ca18e488b90fe20c43a2f953ae3eccb4c8\", \"display_unittests\": \"f9642f8b36e9b17bd6f8760c324a30bfbabd1646\", \"sbox_unittests\": \"3c1bcc1dd069df54dba29dd60e081b95c796f5ef\", \"service_manager_unittests\": \"444ea92fd29e68066149e48bbb1a482c2e28a9ce\", \"pdf_unittests\": \"5f2a5a0b00c78f9e69027ddd95cc2e7bc5dcf34f\", \"skia_unittests\": \"30d0d56fc49234785a408ff77524a1167bb75f93\", \"angle_white_box_tests\": \"31c3dc8e3aebc0a2a7bc35f23aa9063a72ec326e\", \"crypto_unittests\": \"b62212c594c6fbd4cfd836d32c977846bd85e8e3\", \"nacl_loader_unittests\": \"b6bdbf3323682f10106d607dcdcb9576b1dd919e\", \"unit_tests\": \"077146cbd92f5b9d120643299ce54acaed3a90c9\", \"content_browsertests\": \"9474823f34d615c0abfcf978fb33bfc044936ab2\", \"printing_unittests\": \"00c13ae6cb4f5cc1ee9f175b913962ad7bfe525f\", \"device_unittests\": \"754ec3661529a61a67ff2817316fd5fc12926c10\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1538903692314168",
"status": "COMPLETED",
"status_changed_ts": "1538909764865459",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10327",
"builder:Windows deterministic",
"buildset:commit/git/86ae786cf056f4465e0421f05d67da54559a96f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86ae786cf056f4465e0421f05d67da54559a96f7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9098766304793568848",
"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/10327",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8933336242585548928",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:1",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/86ae786cf056f4465e0421f05d67da54559a96f7",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86ae786cf056f4465e0421f05d67da54559a96f7",
"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/8933336242585548928/+/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:9098766304793568848",
"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:7a2c6a280a68ea7a8c9976d934a89c38009336b9",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4066a0585aaf8b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1538909764905641",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10327",
"utcnow_ts": "1538909765880152"
}
}
[10:56:05.891] Invocation finished in 1h41m19.54848337s with status SUCCEEDED