[11:01:12.171] New invocation is queued and will start shortly
[11:01:13.500] Starting the invocation (attempt 1)
[11:01:13.579] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:01:13.579] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:01:13.579] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9100118563279146160",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"3c95a62e5ce5ef41e263593b51e82ed8c6be8f99\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9100118563279146160",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/3c95a62e5ce5ef41e263593b51e82ed8c6be8f99",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c95a62e5ce5ef41e263593b51e82ed8c6be8f99",
"gitiles_ref:refs/heads/master"
]
}
[11:01:16.154] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1537614073752446",
"id": "8934688500960109680",
"lease_expiration_ts": "1537758075756769",
"lease_key": "322601609",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3c95a62e5ce5ef41e263593b51e82ed8c6be8f99\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1537614076055930",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10167",
"builder:Windows deterministic",
"buildset:commit/git/3c95a62e5ce5ef41e263593b51e82ed8c6be8f99",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c95a62e5ce5ef41e263593b51e82ed8c6be8f99",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9100118563279146160",
"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/10167",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8934688500960109680",
"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/3c95a62e5ce5ef41e263593b51e82ed8c6be8f99",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c95a62e5ce5ef41e263593b51e82ed8c6be8f99",
"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/8934688500960109680/+/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:9100118563279146160",
"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:4019c25ce2abe810",
"user_agent:luci-scheduler"
],
"updated_ts": "1537614076056034",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10167",
"utcnow_ts": "1537614076141278"
}
}
[11:01:16.154] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10167
[11:01:16.154] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:2:0) after 1m0s
[11:01:19.039] Received PubSub notification, asking Buildbucket for the build status
[11:01:19.086] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:16.199] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:2:0)
[11:02:16.199] Timer tick, asking Buildbucket for the build status
[11:02:16.236] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:16.236] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:4:0) after 1m0s
[11:03:16.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:4:0)
[11:03:16.251] Timer tick, asking Buildbucket for the build status
[11:03:16.325] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:16.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:5:0) after 1m0s
[11:04:16.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:5:0)
[11:04:16.352] Timer tick, asking Buildbucket for the build status
[11:04:16.514] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:16.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:6:0) after 1m0s
[11:05:16.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:6:0)
[11:05:16.530] Timer tick, asking Buildbucket for the build status
[11:05:16.580] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:16.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:7:0) after 1m0s
[11:06:16.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:7:0)
[11:06:16.599] Timer tick, asking Buildbucket for the build status
[11:06:16.635] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:16.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:8:0) after 1m0s
[11:07:16.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:8:0)
[11:07:16.654] Timer tick, asking Buildbucket for the build status
[11:07:16.824] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:16.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:9:0) after 1m0s
[11:08:16.842] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:9:0)
[11:08:16.842] Timer tick, asking Buildbucket for the build status
[11:08:16.882] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:16.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:10:0) after 1m0s
[11:09:16.898] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:10:0)
[11:09:16.898] Timer tick, asking Buildbucket for the build status
[11:09:17.015] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:17.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:11:0) after 1m0s
[11:10:17.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:11:0)
[11:10:17.033] Timer tick, asking Buildbucket for the build status
[11:10:17.185] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:17.186] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:12:0) after 1m0s
[11:11:17.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:12:0)
[11:11:17.207] Timer tick, asking Buildbucket for the build status
[11:11:17.380] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:17.380] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:13:0) after 1m0s
[11:12:17.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:13:0)
[11:12:17.397] Timer tick, asking Buildbucket for the build status
[11:12:17.434] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:17.434] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:14:0) after 1m0s
[11:13:17.452] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:14:0)
[11:13:17.452] Timer tick, asking Buildbucket for the build status
[11:13:17.530] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:17.530] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:15:0) after 1m0s
[11:14:17.567] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:15:0)
[11:14:17.567] Timer tick, asking Buildbucket for the build status
[11:14:17.602] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:17.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:16:0) after 1m0s
[11:15:17.622] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:16:0)
[11:15:17.622] Timer tick, asking Buildbucket for the build status
[11:15:17.685] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:17.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:17:0) after 1m0s
[11:16:17.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:17:0)
[11:16:17.705] Timer tick, asking Buildbucket for the build status
[11:16:17.750] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:17.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:18:0) after 1m0s
[11:17:17.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:18:0)
[11:17:17.777] Timer tick, asking Buildbucket for the build status
[11:17:17.855] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:17.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:19:0) after 1m0s
[11:18:17.876] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:19:0)
[11:18:17.876] Timer tick, asking Buildbucket for the build status
[11:18:17.964] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:17.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:20:0) after 1m0s
[11:19:17.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:20:0)
[11:19:17.982] Timer tick, asking Buildbucket for the build status
[11:19:18.067] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:18.067] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:21:0) after 1m0s
[11:20:18.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:21:0)
[11:20:18.146] Timer tick, asking Buildbucket for the build status
[11:20:18.308] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:18.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:22:0) after 1m0s
[11:21:18.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:22:0)
[11:21:18.373] Timer tick, asking Buildbucket for the build status
[11:21:18.424] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:18.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:23:0) after 1m0s
[11:22:18.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:23:0)
[11:22:18.444] Timer tick, asking Buildbucket for the build status
[11:22:18.505] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:18.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:24:0) after 1m0s
[11:23:18.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:24:0)
[11:23:18.565] Timer tick, asking Buildbucket for the build status
[11:23:18.603] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:18.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:25:0) after 1m0s
[11:24:18.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:25:0)
[11:24:18.654] Timer tick, asking Buildbucket for the build status
[11:24:18.757] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:18.757] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:26:0) after 1m0s
[11:25:18.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:26:0)
[11:25:18.778] Timer tick, asking Buildbucket for the build status
[11:25:18.892] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:18.892] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:27:0) after 1m0s
[11:26:18.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:27:0)
[11:26:18.910] Timer tick, asking Buildbucket for the build status
[11:26:18.971] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:18.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:28:0) after 1m0s
[11:27:18.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:28:0)
[11:27:18.995] Timer tick, asking Buildbucket for the build status
[11:27:19.067] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:19.067] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:29:0) after 1m0s
[11:28:19.098] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:29:0)
[11:28:19.098] Timer tick, asking Buildbucket for the build status
[11:28:19.156] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:19.156] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:30:0) after 1m0s
[11:29:19.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:30:0)
[11:29:19.289] Timer tick, asking Buildbucket for the build status
[11:29:19.484] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:19.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:31:0) after 1m0s
[11:30:19.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:31:0)
[11:30:19.503] Timer tick, asking Buildbucket for the build status
[11:30:19.623] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:19.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:32:0) after 1m0s
[11:31:19.765] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:32:0)
[11:31:19.765] Timer tick, asking Buildbucket for the build status
[11:31:19.900] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:19.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:33:0) after 1m0s
[11:32:20.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:33:0)
[11:32:20.022] Timer tick, asking Buildbucket for the build status
[11:32:20.067] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:20.067] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:34:0) after 1m0s
[11:33:20.116] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:34:0)
[11:33:20.116] Timer tick, asking Buildbucket for the build status
[11:33:20.195] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:20.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:35:0) after 1m0s
[11:34:20.320] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:35:0)
[11:34:20.320] Timer tick, asking Buildbucket for the build status
[11:34:20.493] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:20.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:36:0) after 1m0s
[11:35:20.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:36:0)
[11:35:20.640] Timer tick, asking Buildbucket for the build status
[11:35:20.712] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:20.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:37:0) after 1m0s
[11:36:20.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:37:0)
[11:36:20.756] Timer tick, asking Buildbucket for the build status
[11:36:20.990] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:20.990] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:38:0) after 1m0s
[11:37:21.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:38:0)
[11:37:21.048] Timer tick, asking Buildbucket for the build status
[11:37:21.109] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:21.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:39:0) after 1m0s
[11:38:21.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:39:0)
[11:38:21.224] Timer tick, asking Buildbucket for the build status
[11:38:21.378] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:21.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:40:0) after 1m0s
[11:39:21.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:40:0)
[11:39:21.398] Timer tick, asking Buildbucket for the build status
[11:39:21.450] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:21.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:41:0) after 1m0s
[11:40:21.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:41:0)
[11:40:21.549] Timer tick, asking Buildbucket for the build status
[11:40:21.627] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:21.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:42:0) after 1m0s
[11:41:21.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:42:0)
[11:41:21.738] Timer tick, asking Buildbucket for the build status
[11:41:21.825] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:21.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:43:0) after 1m0s
[11:42:21.849] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:43:0)
[11:42:21.849] Timer tick, asking Buildbucket for the build status
[11:42:21.990] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:21.990] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:44:0) after 1m0s
[11:43:22.158] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:44:0)
[11:43:22.158] Timer tick, asking Buildbucket for the build status
[11:43:22.357] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:22.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:45:0) after 1m0s
[11:44:22.450] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:45:0)
[11:44:22.450] Timer tick, asking Buildbucket for the build status
[11:44:22.708] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:22.708] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:46:0) after 1m0s
[11:45:22.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:46:0)
[11:45:22.736] Timer tick, asking Buildbucket for the build status
[11:45:22.793] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:22.793] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:47:0) after 1m0s
[11:46:22.810] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:47:0)
[11:46:22.810] Timer tick, asking Buildbucket for the build status
[11:46:22.847] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:22.847] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:48:0) after 1m0s
[11:47:22.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:48:0)
[11:47:22.866] Timer tick, asking Buildbucket for the build status
[11:47:23.131] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:23.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:49:0) after 1m0s
[11:48:23.152] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:49:0)
[11:48:23.152] Timer tick, asking Buildbucket for the build status
[11:48:23.224] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:23.224] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:50:0) after 1m0s
[11:49:23.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:50:0)
[11:49:23.247] Timer tick, asking Buildbucket for the build status
[11:49:23.298] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:23.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:51:0) after 1m0s
[11:50:23.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:51:0)
[11:50:23.329] Timer tick, asking Buildbucket for the build status
[11:50:23.493] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:23.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:52:0) after 1m0s
[11:51:23.512] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:52:0)
[11:51:23.512] Timer tick, asking Buildbucket for the build status
[11:51:23.553] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:23.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:53:0) after 1m0s
[11:52:23.576] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:53:0)
[11:52:23.576] Timer tick, asking Buildbucket for the build status
[11:52:23.641] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:23.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:54:0) after 1m0s
[11:53:23.661] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:54:0)
[11:53:23.661] Timer tick, asking Buildbucket for the build status
[11:53:23.718] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:23.718] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:55:0) after 1m0s
[11:54:23.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:55:0)
[11:54:23.737] Timer tick, asking Buildbucket for the build status
[11:54:23.791] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:23.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:56:0) after 1m0s
[11:55:23.812] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:56:0)
[11:55:23.812] Timer tick, asking Buildbucket for the build status
[11:55:24.001] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:24.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:57:0) after 1m0s
[11:56:24.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:57:0)
[11:56:24.025] Timer tick, asking Buildbucket for the build status
[11:56:24.357] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:24.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:58:0) after 1m0s
[11:57:24.450] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:58:0)
[11:57:24.450] Timer tick, asking Buildbucket for the build status
[11:57:24.638] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:24.638] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:59:0) after 1m0s
[11:58:24.765] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:59:0)
[11:58:24.765] Timer tick, asking Buildbucket for the build status
[11:58:24.832] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:24.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:60:0) after 1m0s
[11:59:24.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:60:0)
[11:59:24.930] Timer tick, asking Buildbucket for the build status
[11:59:24.998] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:24.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:61:0) after 1m0s
[12:00:25.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:61:0)
[12:00:25.057] Timer tick, asking Buildbucket for the build status
[12:00:25.237] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:25.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:62:0) after 1m0s
[12:01:25.257] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:62:0)
[12:01:25.257] Timer tick, asking Buildbucket for the build status
[12:01:25.338] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:25.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:63:0) after 1m0s
[12:02:25.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:63:0)
[12:02:25.480] Timer tick, asking Buildbucket for the build status
[12:02:25.550] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:25.550] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:64:0) after 1m0s
[12:03:25.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:64:0)
[12:03:25.747] Timer tick, asking Buildbucket for the build status
[12:03:25.934] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:25.934] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:65:0) after 1m0s
[12:04:25.955] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:65:0)
[12:04:25.955] Timer tick, asking Buildbucket for the build status
[12:04:26.105] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:26.105] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:66:0) after 1m0s
[12:05:26.151] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:66:0)
[12:05:26.151] Timer tick, asking Buildbucket for the build status
[12:05:26.195] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:26.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:67:0) after 1m0s
[12:06:26.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:67:0)
[12:06:26.252] Timer tick, asking Buildbucket for the build status
[12:06:26.328] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:26.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:68:0) after 1m0s
[12:07:26.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:68:0)
[12:07:26.346] Timer tick, asking Buildbucket for the build status
[12:07:26.496] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:26.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:69:0) after 1m0s
[12:08:26.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:69:0)
[12:08:26.521] Timer tick, asking Buildbucket for the build status
[12:08:26.623] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:26.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:70:0) after 1m0s
[12:09:26.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:70:0)
[12:09:26.644] Timer tick, asking Buildbucket for the build status
[12:09:26.736] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:26.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:71:0) after 1m0s
[12:10:26.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:71:0)
[12:10:26.834] Timer tick, asking Buildbucket for the build status
[12:10:26.973] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:26.973] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:72:0) after 1m0s
[12:11:27.047] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:72:0)
[12:11:27.047] Timer tick, asking Buildbucket for the build status
[12:11:27.115] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:27.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:73:0) after 1m0s
[12:12:27.170] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:73:0)
[12:12:27.170] Timer tick, asking Buildbucket for the build status
[12:12:27.253] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:27.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:74:0) after 1m0s
[12:13:27.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:74:0)
[12:13:27.384] Timer tick, asking Buildbucket for the build status
[12:13:27.447] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:27.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:75:0) after 1m0s
[12:14:27.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:75:0)
[12:14:27.557] Timer tick, asking Buildbucket for the build status
[12:14:27.631] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:27.631] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:76:0) after 1m0s
[12:15:27.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:76:0)
[12:15:27.783] Timer tick, asking Buildbucket for the build status
[12:15:27.852] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:27.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:77:0) after 1m0s
[12:16:27.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:77:0)
[12:16:27.883] Timer tick, asking Buildbucket for the build status
[12:16:27.972] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:27.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:78:0) after 1m0s
[12:17:28.036] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:78:0)
[12:17:28.036] Timer tick, asking Buildbucket for the build status
[12:17:28.106] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:28.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:79:0) after 1m0s
[12:18:28.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:79:0)
[12:18:28.242] Timer tick, asking Buildbucket for the build status
[12:18:28.328] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:28.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:80:0) after 1m0s
[12:19:28.363] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:80:0)
[12:19:28.363] Timer tick, asking Buildbucket for the build status
[12:19:28.433] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:28.433] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:81:0) after 1m0s
[12:20:28.454] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:81:0)
[12:20:28.454] Timer tick, asking Buildbucket for the build status
[12:20:28.581] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:28.581] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:82:0) after 1m0s
[12:21:28.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:82:0)
[12:21:28.648] Timer tick, asking Buildbucket for the build status
[12:21:28.728] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:28.728] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:83:0) after 1m0s
[12:22:28.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:83:0)
[12:22:28.750] Timer tick, asking Buildbucket for the build status
[12:22:28.881] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:28.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:84:0) after 1m0s
[12:23:28.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:84:0)
[12:23:28.900] Timer tick, asking Buildbucket for the build status
[12:23:28.961] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:28.961] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:85:0) after 1m0s
[12:24:29.158] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:85:0)
[12:24:29.158] Timer tick, asking Buildbucket for the build status
[12:24:29.224] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:29.224] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:86:0) after 1m0s
[12:25:29.309] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:86:0)
[12:25:29.309] Timer tick, asking Buildbucket for the build status
[12:25:29.364] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:29.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:87:0) after 1m0s
[12:26:29.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:87:0)
[12:26:29.385] Timer tick, asking Buildbucket for the build status
[12:26:29.503] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:29.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:88:0) after 1m0s
[12:27:29.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:88:0)
[12:27:29.617] Timer tick, asking Buildbucket for the build status
[12:27:29.690] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:29.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:89:0) after 1m0s
[12:28:29.749] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:89:0)
[12:28:29.749] Timer tick, asking Buildbucket for the build status
[12:28:29.805] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:29.806] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:90:0) after 1m0s
[12:29:29.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:90:0)
[12:29:29.830] Timer tick, asking Buildbucket for the build status
[12:29:29.897] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:29.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:91:0) after 1m0s
[12:30:29.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:91:0)
[12:30:29.920] Timer tick, asking Buildbucket for the build status
[12:30:29.987] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:29.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:92:0) after 1m0s
[12:31:30.019] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:92:0)
[12:31:30.019] Timer tick, asking Buildbucket for the build status
[12:31:30.122] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:30.122] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:93:0) after 1m0s
[12:32:30.152] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:93:0)
[12:32:30.152] Timer tick, asking Buildbucket for the build status
[12:32:30.218] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:30.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:94:0) after 1m0s
[12:33:30.256] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:94:0)
[12:33:30.256] Timer tick, asking Buildbucket for the build status
[12:33:30.415] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:30.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:95:0) after 1m0s
[12:34:30.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:95:0)
[12:34:30.456] Timer tick, asking Buildbucket for the build status
[12:34:30.534] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:30.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:96:0) after 1m0s
[12:35:30.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:96:0)
[12:35:30.566] Timer tick, asking Buildbucket for the build status
[12:35:30.646] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:30.646] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:97:0) after 1m0s
[12:36:30.668] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:97:0)
[12:36:30.668] Timer tick, asking Buildbucket for the build status
[12:36:30.752] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:30.752] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:98:0) after 1m0s
[12:37:30.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:98:0)
[12:37:30.773] Timer tick, asking Buildbucket for the build status
[12:37:30.869] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:30.869] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:99:0) after 1m0s
[12:38:30.952] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:99:0)
[12:38:30.952] Timer tick, asking Buildbucket for the build status
[12:38:31.138] Build 8934688500960109680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:31.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100118563279146160:100:0) after 1m0s
[12:38:52.505] Received PubSub notification, asking Buildbucket for the build status
[12:38:52.569] Build 8934688500960109680: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:38:52.569] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1537619930719898",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1537614073752446",
"id": "8934688500960109680",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3c95a62e5ce5ef41e263593b51e82ed8c6be8f99\"}}",
"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/8934688500960109680/+/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\": \"3c95a62e5ce5ef41e263593b51e82ed8c6be8f99\", \"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@{#593441}\", \"branch\": \"refs/heads/master\", \"revision\": \"3c95a62e5ce5ef41e263593b51e82ed8c6be8f99\", \"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\": 1537614073752446, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/3c95a62e5ce5ef41e263593b51e82ed8c6be8f99\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c95a62e5ce5ef41e263593b51e82ed8c6be8f99\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9100118563279146160\", \"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\": \"8934688500960109680\"}}, \"got_v8_revision\": \"2f22d11156ccb1f88587896c27151944cad48cba\", \"got_v8_revision_cp\": \"refs/heads/7.1.163@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10167, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"314761de097e67d00ada5f4aabf483290a4098d9\", \"notification_helper_unittests\": \"1ce160ae66abc0d6b18c1e605f9c0f14085e7f27\", \"gpu_perftests\": \"75887b9f09125b71b6706cf008b1414929b89a39\", \"vr_common_unittests\": \"eca759fff8f4139bd50f34ec812d8e401481a5cb\", \"telemetry_unittests\": \"324bb53bde7cec2d1b1b9a3d891c6193dcd78757\", \"load_library_perf_tests\": \"8d4762b7a4a084b6f986a705050f6849dcce96e3\", \"devtools_closure_compile\": \"4c14287de95e3ba0fa6108ba6cb04b37f0119f15\", \"chromedriver_py_tests\": \"016c4bd2adc1a83b08b72a6ae193e72d825d5a44\", \"mojo_core_unittests\": \"02df1ba071024bcd3fe54c557a7cfa47b55953a0\", \"install_static_unittests\": \"db30dd750715021e0d3ad0427f8c3c0e883f1ba2\", \"leveldb_service_unittests\": \"60d2d8ebd93e6a89baf85c42902c7ef909d5e9a4\", \"mini_installer_tests\": \"44a77a7e2e1e0bab64f609355d5c9991be07898b\", \"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\": \"9e64d44251b5eb8ed5a9f465339fe1ba27be2cf3\", \"metrics_python_tests\": \"e95e6ad6e22e16e8380bffda0940094e78415b5f\", \"message_center_unittests\": \"090b5077cde1f7b6578b53c65c3894241dfaf3fd\", \"ppapi_unittests\": \"cc8dcfb37a6a92c3e495d4033e88e30ef516691f\", \"extensions_unittests\": \"ec0bb40f1bc5a41abc922bc082101a66253794de\", \"gl_unittests_ozone\": \"cb33148ad4c6dfa98676664abc7a76028a7bdb44\", \"app_shell_unittests\": \"ff9137d470ca58cb79626fb9ea075d904e63c606\", \"chrome_app_unittests\": \"e0d702c739448ac426fd9f4344251462be0f20cd\", \"gl_unittests\": \"cb33148ad4c6dfa98676664abc7a76028a7bdb44\", \"gcm_unit_tests\": \"43ee812707947794de0fcc11f101d502c43340e8\", \"views_unittests\": \"ac2461553201709a275d2f78a2be5f3cf2267cdf\", \"content_shell_crash_test\": \"ebb2f8700c8fad4132ef23bb6754eddbcd7e2fea\", \"ui_base_unittests\": \"571ebd402575382f175e706dd6fd2362fd7920c0\", \"gles2_conform_test\": \"44e5605930fc986aa3d998038c43777ce4594430\", \"cronet_unittests\": \"bca59d50526c2b272553e57d3c9c64f066f561ef\", \"performance_test_suite\": \"bb16b72f53a25fc88a8e259edd20cded095ec468\", \"wm_unittests\": \"6d81ccef56cbe8fc628870ba2ccbacb62a745030\", \"boringssl_crypto_tests\": \"42f0af3f82979a9893eac3c3643f76b84529b018\", \"gin_unittests\": \"202130960ce3672761f9f5b6a55a489103a8a7d8\", \"performance_browser_tests\": \"42ad6caea20489c0a0628892d904d813f071a46c\", \"extensions_browsertests\": \"b6a960c834790f72d5c3f1bf0d11daef950de675\", \"webkit_python_tests\": \"d158224fa80fea9494613a4e2cc83c3710e1a2b2\", \"headless_unittests\": \"553551198a3402aa81238d20dfb0c4cc4f63c10f\", \"webkit_layout_tests\": \"09dbf09c4990b81ed493104a975f85eb90bea477\", \"vr_pixeltests\": \"7df1b6283538bb28ac107cc743a8203bdf6102af\", \"setup_unittests\": \"56b710e3858f41e578d21cafc12288fe8cede364\", \"webkit_unit_tests\": \"b0a934beac638876f67edfedce7775f68106e054\", \"content_unittests\": \"e71bec76e676f2271fedc78c7f835de6ca44cda3\", \"performance_webview_test_suite\": \"f3b2c3f6d6b67660d35c2fbd3a3f08ee8bd74780\", \"wtf_unittests\": \"6330a5dd1e366167efe69357e7de6901969305ce\", \"webkit_layout_tests_exparchive\": \"f997a1a00f125892f62604be1b3951be6b0f71e6\", \"chrome_cleaner_unittests\": \"74478644ad5162c75bdba3e527be41b29227d5a8\", \"traffic_annotation_auditor_unittests\": \"84f8f55a7ce53e8d25db995da87ee68e7f3a085e\", \"libjingle_xmpp_unittests\": \"c49e03c8a53c9da5f9ca94a7107852ee8b304bf9\", \"remoting_unittests\": \"a54924dde541ae5d616ed113b8ad0f8fa0738c02\", \"vr_common_perftests\": \"2838a435dc4431023a7a175552e6dd77451465d8\", \"filesystem_service_unittests\": \"610cb3d5b1811147f3b61b816795fecc7356feb7\", \"chrome_elf_import_unittests\": \"c13543b00f169d58382414ff26fd609641198927\", \"installer_util_unittests\": \"3e32685079ea22ed99aed4af1aec0bd58a90b463\", \"gfx_unittests\": \"7fd8dd90526223ea5785a35af0b15416cdb84d12\", \"headless_browsertests\": \"3619790ffb92b41ef1f432d530441a01f8426c1c\", \"capture_unittests\": \"518d4b9475a17da1cde25e23e318b02a66eb4858\", \"chrome_elf_unittests\": \"782a6a0b95326842b1e593da4521cb2bbc7932de\", \"telemetry_gpu_unittests\": \"4ac9381e6a8d96a78b0c3b3c7c9fa029d5f40814\", \"command_buffer_perftests\": \"b97d04e37c33e2ae8740760b1e34f1de506c567e\", \"media_unittests\": \"92ad1c5fb53859e8a622d3f3ac8bc976a04969a1\", \"chromedriver_unittests\": \"6de77464382b0bd8e3d4729cc97cae1c01a7c142\", \"views_mus_interactive_ui_tests\": \"8e23eda652cb335c6e7bae536e5bc27fd6e7b523\", \"storage_unittests\": \"76b0f740408ff5af8eaf808a2993fcefb8180b6c\", \"media_service_unittests\": \"50f178e9d9b1242c24f9b6ccb84115fe5a70b37d\", \"compositor_unittests\": \"097352350326634427c0b03fc5b1abe842e9abf5\", \"latency_unittests\": \"69e025be9e309fddee93bf6d3b8c749fd19823ff\", \"video_decode_accelerator_unittest\": \"dc6a0e16949c563d8ebaf3df4a8609e283512761\", \"swiftshader_unittests\": \"878de22b3275f0ccb373e993ceeba1231407f3dd\", \"telemetry_perf_unittests\": \"7abe269ee5d4cbd96a080cc72057bddc78746224\", \"media_blink_unittests\": \"5d9ea5d85c1c24b43a75c16adcdae65573042009\", \"views_mus_unittests\": \"3c2be05d1baf6e09bcfb6842ef2bdb664efb3c60\", \"components_unittests\": \"7946807292bae6eec8137eb826b636670ec2aa8c\", \"snapshot_unittests\": \"0c39568ec9a82d1c59378202eb8c11557f4e7f2b\", \"accessibility_unittests\": \"8de3a8d37183aaae9838af4fc583c72b05c691d7\", \"net_perftests\": \"40ec6344ecd55d2db0b2cea9826770b5078170e2\", \"aura_unittests\": \"92c031e2a5bb760fb96150861f23e257ac994d70\", \"services_unittests\": \"f1402376fa828f3d66c9f97e0ff8d6365dc2e57b\", \"components_perftests\": \"7e589d849795b60fe5fa617c47dc4337d46dd989\", \"events_unittests\": \"61478936914c00e63f29d019719fa99c2af95c9d\", \"tracing_perftests\": \"2e0057526c5b177d2e565b17036ddb960a1e0416\", \"chromedriver_replay_unittests\": \"3d75567c4cb5be67d5cf025731258bcd9f3d4b8a\", \"ui_touch_selection_unittests\": \"45db612be15175e731ce5d4e37e20b838e63b5ed\", \"cacheinvalidation_unittests\": \"470ce1496e645fb24338f72c0841750602a6619d\", \"viz_unittests\": \"19c20fc044f39caf1d775769d2832a01915bae0e\", \"browser_tests\": \"1ccade09ee731c7515d2ccaf2453f763f57ba45d\", \"net_unittests\": \"4bbeec85fd7ae0ed2f8f7d9da683b137dc70fcd5\", \"midi_unittests\": \"9aae1a581541c8ef687cb66dae0e1ee7181debc8\", \"boringssl_ssl_tests\": \"0ba0fcaff6f265a48cc1c1f2b1babea798fea781\", \"sbox_validation_tests\": \"d15adb290a5bce94bc16392b15c5393ca62e6f1e\", \"elevation_service_unittests\": \"9427df6fdf953a56a091810c12796691aa0e2257\", \"gpu_unittests\": \"ea28aee3e71ff16fab6e13a05beb3c6c50f6dbcb\", \"native_theme_unittests\": \"253f2428c006be9f266ff2fa78e9e7485a3f8b36\", \"interactive_ui_tests\": \"a13bbdfb4d07edebcb45b5d78d415580b4ef8f2e\", \"mojo_unittests\": \"33620517a98a1b536e7e1fc928570830aa9e3d2b\", \"url_unittests\": \"09a06ab47fc2e88ae8de2230f772ff9ed5349be5\", \"media_perftests\": \"474fa4269211c0945306d30ce60507726c858dd0\", \"cronet_tests\": \"e7929eb7e48e0083193b7b9e67369af246b59f45\", \"google_apis_unittests\": \"5c675c442c67c89f956b23552d1b63cd9cd6c58d\", \"telemetry_gpu_integration_test\": \"0e12af3011ba5ba3fde542551fe2355d1f20f3f8\", \"blink_heap_unittests\": \"ea77b43e9e84568d65e7311a85e08f056269838c\", \"base_unittests\": \"cf3bad3f121be6c336ab44a35009fc651d1661b1\", \"sync_integration_tests\": \"92648a2f1ed1f3a2243123a859d5727736acaedf\", \"sql_unittests\": \"0c82cf8097d8ff4c73c58d453cda44d80ed15df7\", \"courgette_unittests\": \"82303ce9819980655b6189049f61d94926da0782\", \"blink_fuzzer_unittests\": \"52c3eba263d413c6a6f18bc82ed604578ebaae80\", \"gl_tests\": \"aad1fc63e0028fc93a81356594b16b19522e17cf\", \"zucchini_unittests\": \"5da8593b94100e441824f2ae1a55abfdbc07b9e4\", \"ipc_tests\": \"de87119a47f2b478e95081b6880e8a2e279c43a8\", \"angle_end2end_tests\": \"a8119e8ea4d599c672434baccf7f71381995a99a\", \"blink_platform_unittests\": \"d0e555f1863ba3052d8752e558b87487bd1684c3\", \"components_browsertests\": \"2fdf37090b4b4192ac25a81df4ea447e6650ff4a\", \"views_perftests\": \"1543fe993137261dd7325927e2b7e2b4c925866f\", \"angle_perftests\": \"55ec85e31e7aee4b59de1628a993aa9eff53b09a\", \"display_unittests\": \"5359341a8c6fa449ef4401f9abfb449039549f3b\", \"sbox_unittests\": \"258c2a9ba87697429803db4247c29be882a5b0d1\", \"service_manager_unittests\": \"7651c1a43b63d0f33b26cf2293b86a03c97b407a\", \"pdf_unittests\": \"f4952687970300711de95ad67916b95e2a7de426\", \"skia_unittests\": \"053a3987484fb4c0e54ec550e44662a99f84edd5\", \"angle_white_box_tests\": \"e167a97d329013427fd9085d2394caf7ef166b8d\", \"crypto_unittests\": \"6426c53042791fd08bea24507d020c953d5a5c62\", \"nacl_loader_unittests\": \"4a91c3965588d56cb7f51161228a6201aa412157\", \"unit_tests\": \"c60d0ed86b7996913cc7d1090c1077c3003f2278\", \"content_browsertests\": \"0cd2b73b14b2bdb533fb5b64e433971913f5dcb6\", \"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": "1537614078162733",
"status": "COMPLETED",
"status_changed_ts": "1537619931671391",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10167",
"builder:Windows deterministic",
"buildset:commit/git/3c95a62e5ce5ef41e263593b51e82ed8c6be8f99",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c95a62e5ce5ef41e263593b51e82ed8c6be8f99",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9100118563279146160",
"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/10167",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8934688500960109680",
"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/3c95a62e5ce5ef41e263593b51e82ed8c6be8f99",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c95a62e5ce5ef41e263593b51e82ed8c6be8f99",
"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/8934688500960109680/+/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:9100118563279146160",
"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:4019c25ce2abe810",
"user_agent:luci-scheduler"
],
"updated_ts": "1537619931673460",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10167",
"utcnow_ts": "1537619932555672"
}
}
[12:38:52.569] Invocation finished in 1h37m40.41297337s with status SUCCEEDED