[14:34:29.006] New invocation is queued and will start shortly
[14:34:30.544] Starting the invocation (attempt 1)
[14:34:30.578] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:34:30.578] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:34:30.578] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9100105144828103344",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"42ccf211a3e1143b904f03a514d5f3fb0d93b072\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9100105144828103344",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/42ccf211a3e1143b904f03a514d5f3fb0d93b072",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/42ccf211a3e1143b904f03a514d5f3fb0d93b072",
"gitiles_ref:refs/heads/master"
]
}
[14:34:33.090] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1537626870708339",
"id": "8934675082378985536",
"lease_expiration_ts": "1537770872867610",
"lease_key": "751732915",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"42ccf211a3e1143b904f03a514d5f3fb0d93b072\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1537626872971452",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10168",
"builder:Windows deterministic",
"buildset:commit/git/42ccf211a3e1143b904f03a514d5f3fb0d93b072",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/42ccf211a3e1143b904f03a514d5f3fb0d93b072",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9100105144828103344",
"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/10168",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8934675082378985536",
"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/42ccf211a3e1143b904f03a514d5f3fb0d93b072",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/42ccf211a3e1143b904f03a514d5f3fb0d93b072",
"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/8934675082378985536/+/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:9100105144828103344",
"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:401a85a2c200e310",
"user_agent:luci-scheduler"
],
"updated_ts": "1537626872971524",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10168",
"utcnow_ts": "1537626873081324"
}
}
[14:34:33.090] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10168
[14:34:33.090] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:2:0) after 1m0s
[14:35:26.421] Received PubSub notification, asking Buildbucket for the build status
[14:35:26.485] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:33.116] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:2:0)
[14:35:33.116] Timer tick, asking Buildbucket for the build status
[14:35:33.155] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:33.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:4:0) after 1m0s
[14:36:33.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:4:0)
[14:36:33.183] Timer tick, asking Buildbucket for the build status
[14:36:33.243] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:33.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:5:0) after 1m0s
[14:37:33.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:5:0)
[14:37:33.265] Timer tick, asking Buildbucket for the build status
[14:37:33.360] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:33.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:6:0) after 1m0s
[14:38:33.449] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:6:0)
[14:38:33.449] Timer tick, asking Buildbucket for the build status
[14:38:33.528] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:33.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:7:0) after 1m0s
[14:39:33.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:7:0)
[14:39:33.544] Timer tick, asking Buildbucket for the build status
[14:39:33.597] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:33.597] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:8:0) after 1m0s
[14:40:33.612] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:8:0)
[14:40:33.612] Timer tick, asking Buildbucket for the build status
[14:40:33.685] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:33.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:9:0) after 1m0s
[14:41:33.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:9:0)
[14:41:33.702] Timer tick, asking Buildbucket for the build status
[14:41:33.746] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:33.746] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:10:0) after 1m0s
[14:42:33.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:10:0)
[14:42:33.761] Timer tick, asking Buildbucket for the build status
[14:42:33.827] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:33.827] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:11:0) after 1m0s
[14:43:33.842] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:11:0)
[14:43:33.842] Timer tick, asking Buildbucket for the build status
[14:43:33.912] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:33.912] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:12:0) after 1m0s
[14:44:34.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:12:0)
[14:44:34.002] Timer tick, asking Buildbucket for the build status
[14:44:34.041] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:34.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:13:0) after 1m0s
[14:45:34.065] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:13:0)
[14:45:34.065] Timer tick, asking Buildbucket for the build status
[14:45:34.135] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:34.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:14:0) after 1m0s
[14:46:34.152] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:14:0)
[14:46:34.152] Timer tick, asking Buildbucket for the build status
[14:46:34.205] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:34.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:15:0) after 1m0s
[14:47:34.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:15:0)
[14:47:34.225] Timer tick, asking Buildbucket for the build status
[14:47:34.278] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:34.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:16:0) after 1m0s
[14:48:34.304] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:16:0)
[14:48:34.304] Timer tick, asking Buildbucket for the build status
[14:48:34.373] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:34.373] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:17:0) after 1m0s
[14:49:34.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:17:0)
[14:49:34.389] Timer tick, asking Buildbucket for the build status
[14:49:34.467] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:34.467] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:18:0) after 1m0s
[14:50:34.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:18:0)
[14:50:34.501] Timer tick, asking Buildbucket for the build status
[14:50:34.547] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:34.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:19:0) after 1m0s
[14:51:34.561] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:19:0)
[14:51:34.561] Timer tick, asking Buildbucket for the build status
[14:51:34.758] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:34.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:20:0) after 1m0s
[14:52:34.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:20:0)
[14:52:34.779] Timer tick, asking Buildbucket for the build status
[14:52:34.923] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:34.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:21:0) after 1m0s
[14:53:35.062] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:21:0)
[14:53:35.062] Timer tick, asking Buildbucket for the build status
[14:53:35.223] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:35.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:22:0) after 1m0s
[14:54:35.241] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:22:0)
[14:54:35.241] Timer tick, asking Buildbucket for the build status
[14:54:35.301] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:35.301] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:23:0) after 1m0s
[14:55:35.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:23:0)
[14:55:35.318] Timer tick, asking Buildbucket for the build status
[14:55:35.485] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:35.485] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:24:0) after 1m0s
[14:56:35.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:24:0)
[14:56:35.505] Timer tick, asking Buildbucket for the build status
[14:56:35.566] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:35.566] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:25:0) after 1m0s
[14:57:35.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:25:0)
[14:57:35.581] Timer tick, asking Buildbucket for the build status
[14:57:35.809] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:35.809] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:26:0) after 1m0s
[14:58:35.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:26:0)
[14:58:35.826] Timer tick, asking Buildbucket for the build status
[14:58:35.940] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:35.940] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:27:0) after 1m0s
[14:59:35.960] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:27:0)
[14:59:35.960] Timer tick, asking Buildbucket for the build status
[14:59:36.013] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:36.013] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:28:0) after 1m0s
[15:00:36.031] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:28:0)
[15:00:36.031] Timer tick, asking Buildbucket for the build status
[15:00:36.107] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:36.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:29:0) after 1m0s
[15:01:36.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:29:0)
[15:01:36.125] Timer tick, asking Buildbucket for the build status
[15:01:36.190] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:36.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:30:0) after 1m0s
[15:02:36.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:30:0)
[15:02:36.229] Timer tick, asking Buildbucket for the build status
[15:02:36.321] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:36.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:31:0) after 1m0s
[15:03:36.338] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:31:0)
[15:03:36.338] Timer tick, asking Buildbucket for the build status
[15:03:36.396] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:36.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:32:0) after 1m0s
[15:04:36.416] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:32:0)
[15:04:36.416] Timer tick, asking Buildbucket for the build status
[15:04:36.448] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:36.448] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:33:0) after 1m0s
[15:05:36.466] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:33:0)
[15:05:36.466] Timer tick, asking Buildbucket for the build status
[15:05:36.533] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:36.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:34:0) after 1m0s
[15:06:36.568] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:34:0)
[15:06:36.568] Timer tick, asking Buildbucket for the build status
[15:06:36.635] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:36.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:35:0) after 1m0s
[15:07:36.656] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:35:0)
[15:07:36.656] Timer tick, asking Buildbucket for the build status
[15:07:36.714] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:36.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:36:0) after 1m0s
[15:08:36.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:36:0)
[15:08:36.742] Timer tick, asking Buildbucket for the build status
[15:08:36.815] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:36.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:37:0) after 1m0s
[15:09:36.851] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:37:0)
[15:09:36.851] Timer tick, asking Buildbucket for the build status
[15:09:36.908] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:36.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:38:0) after 1m0s
[15:10:36.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:38:0)
[15:10:36.927] Timer tick, asking Buildbucket for the build status
[15:10:36.997] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:36.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:39:0) after 1m0s
[15:11:37.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:39:0)
[15:11:37.056] Timer tick, asking Buildbucket for the build status
[15:11:37.218] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:37.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:40:0) after 1m0s
[15:12:37.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:40:0)
[15:12:37.236] Timer tick, asking Buildbucket for the build status
[15:12:37.347] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:37.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:41:0) after 1m0s
[15:13:37.369] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:41:0)
[15:13:37.369] Timer tick, asking Buildbucket for the build status
[15:13:37.419] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:37.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:42:0) after 1m0s
[15:14:37.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:42:0)
[15:14:37.440] Timer tick, asking Buildbucket for the build status
[15:14:37.764] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:37.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:43:0) after 1m0s
[15:15:37.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:43:0)
[15:15:37.781] Timer tick, asking Buildbucket for the build status
[15:15:37.905] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:37.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:44:0) after 1m0s
[15:16:37.922] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:44:0)
[15:16:37.922] Timer tick, asking Buildbucket for the build status
[15:16:37.985] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:37.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:45:0) after 1m0s
[15:17:38.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:45:0)
[15:17:38.003] Timer tick, asking Buildbucket for the build status
[15:17:38.065] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:38.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:46:0) after 1m0s
[15:18:38.082] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:46:0)
[15:18:38.082] Timer tick, asking Buildbucket for the build status
[15:18:38.171] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:38.171] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:47:0) after 1m0s
[15:19:38.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:47:0)
[15:19:38.232] Timer tick, asking Buildbucket for the build status
[15:19:38.305] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:38.305] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:48:0) after 1m0s
[15:20:38.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:48:0)
[15:20:38.335] Timer tick, asking Buildbucket for the build status
[15:20:38.401] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:38.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:49:0) after 1m0s
[15:21:38.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:49:0)
[15:21:38.433] Timer tick, asking Buildbucket for the build status
[15:21:38.496] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:38.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:50:0) after 1m0s
[15:22:38.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:50:0)
[15:22:38.521] Timer tick, asking Buildbucket for the build status
[15:22:38.572] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:38.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:51:0) after 1m0s
[15:23:38.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:51:0)
[15:23:38.588] Timer tick, asking Buildbucket for the build status
[15:23:38.622] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:38.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:52:0) after 1m0s
[15:24:38.688] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:52:0)
[15:24:38.688] Timer tick, asking Buildbucket for the build status
[15:24:38.809] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:38.809] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:53:0) after 1m0s
[15:25:38.840] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:53:0)
[15:25:38.840] Timer tick, asking Buildbucket for the build status
[15:25:38.912] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:38.912] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:54:0) after 1m0s
[15:26:38.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:54:0)
[15:26:38.930] Timer tick, asking Buildbucket for the build status
[15:26:38.982] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:38.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:55:0) after 1m0s
[15:27:39.000] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:55:0)
[15:27:39.000] Timer tick, asking Buildbucket for the build status
[15:27:39.078] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:39.078] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:56:0) after 1m0s
[15:28:39.095] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:56:0)
[15:28:39.095] Timer tick, asking Buildbucket for the build status
[15:28:39.196] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:39.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:57:0) after 1m0s
[15:29:39.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:57:0)
[15:29:39.221] Timer tick, asking Buildbucket for the build status
[15:29:39.281] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:39.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:58:0) after 1m0s
[15:30:39.309] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:58:0)
[15:30:39.309] Timer tick, asking Buildbucket for the build status
[15:30:39.598] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:39.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:59:0) after 1m0s
[15:31:39.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:59:0)
[15:31:39.617] Timer tick, asking Buildbucket for the build status
[15:31:39.671] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:39.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:60:0) after 1m0s
[15:32:39.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:60:0)
[15:32:39.733] Timer tick, asking Buildbucket for the build status
[15:32:39.910] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:39.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:61:0) after 1m0s
[15:33:39.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:61:0)
[15:33:39.929] Timer tick, asking Buildbucket for the build status
[15:33:39.987] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:39.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:62:0) after 1m0s
[15:34:40.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:62:0)
[15:34:40.015] Timer tick, asking Buildbucket for the build status
[15:34:40.227] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:40.227] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:63:0) after 1m0s
[15:35:40.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:63:0)
[15:35:40.315] Timer tick, asking Buildbucket for the build status
[15:35:40.386] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:40.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:64:0) after 1m0s
[15:36:40.418] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:64:0)
[15:36:40.418] Timer tick, asking Buildbucket for the build status
[15:36:40.527] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:40.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:65:0) after 1m0s
[15:37:40.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:65:0)
[15:37:40.545] Timer tick, asking Buildbucket for the build status
[15:37:40.619] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:40.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:66:0) after 1m0s
[15:38:40.650] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:66:0)
[15:38:40.650] Timer tick, asking Buildbucket for the build status
[15:38:40.713] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:40.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:67:0) after 1m0s
[15:39:40.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:67:0)
[15:39:40.733] Timer tick, asking Buildbucket for the build status
[15:39:41.026] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:41.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:68:0) after 1m0s
[15:40:41.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:68:0)
[15:40:41.056] Timer tick, asking Buildbucket for the build status
[15:40:41.215] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:41.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:69:0) after 1m0s
[15:41:41.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:69:0)
[15:41:41.236] Timer tick, asking Buildbucket for the build status
[15:41:41.320] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:41.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:70:0) after 1m0s
[15:42:41.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:70:0)
[15:42:41.343] Timer tick, asking Buildbucket for the build status
[15:42:41.403] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:41.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:71:0) after 1m0s
[15:43:41.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:71:0)
[15:43:41.421] Timer tick, asking Buildbucket for the build status
[15:43:41.508] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:41.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:72:0) after 1m0s
[15:44:41.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:72:0)
[15:44:41.532] Timer tick, asking Buildbucket for the build status
[15:44:41.654] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:41.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:73:0) after 1m0s
[15:45:41.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:73:0)
[15:45:41.763] Timer tick, asking Buildbucket for the build status
[15:45:41.829] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:41.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:74:0) after 1m0s
[15:46:41.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:74:0)
[15:46:41.871] Timer tick, asking Buildbucket for the build status
[15:46:41.942] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:41.942] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:75:0) after 1m0s
[15:47:41.960] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:75:0)
[15:47:41.960] Timer tick, asking Buildbucket for the build status
[15:47:42.089] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:42.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:76:0) after 1m0s
[15:48:42.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:76:0)
[15:48:42.168] Timer tick, asking Buildbucket for the build status
[15:48:42.215] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:42.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:77:0) after 1m0s
[15:49:42.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:77:0)
[15:49:42.269] Timer tick, asking Buildbucket for the build status
[15:49:42.356] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:42.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:78:0) after 1m0s
[15:50:42.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:78:0)
[15:50:42.376] Timer tick, asking Buildbucket for the build status
[15:50:42.434] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:42.434] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:79:0) after 1m0s
[15:51:42.472] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:79:0)
[15:51:42.472] Timer tick, asking Buildbucket for the build status
[15:51:42.534] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:42.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:80:0) after 1m0s
[15:52:42.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:80:0)
[15:52:42.638] Timer tick, asking Buildbucket for the build status
[15:52:42.708] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:42.708] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:81:0) after 1m0s
[15:53:42.919] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:81:0)
[15:53:42.919] Timer tick, asking Buildbucket for the build status
[15:53:42.976] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:42.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:82:0) after 1m0s
[15:54:43.053] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:82:0)
[15:54:43.053] Timer tick, asking Buildbucket for the build status
[15:54:43.101] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:43.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:83:0) after 1m0s
[15:55:43.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:83:0)
[15:55:43.119] Timer tick, asking Buildbucket for the build status
[15:55:43.208] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:43.208] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:84:0) after 1m0s
[15:56:43.292] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:84:0)
[15:56:43.292] Timer tick, asking Buildbucket for the build status
[15:56:43.361] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:43.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:85:0) after 1m0s
[15:57:43.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:85:0)
[15:57:43.391] Timer tick, asking Buildbucket for the build status
[15:57:43.462] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:43.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:86:0) after 1m0s
[15:58:43.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:86:0)
[15:58:43.579] Timer tick, asking Buildbucket for the build status
[15:58:43.750] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:43.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:87:0) after 1m0s
[15:59:43.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:87:0)
[15:59:43.770] Timer tick, asking Buildbucket for the build status
[15:59:43.829] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:43.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:88:0) after 1m0s
[16:00:43.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:88:0)
[16:00:43.936] Timer tick, asking Buildbucket for the build status
[16:00:44.040] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:44.040] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:89:0) after 1m0s
[16:01:44.066] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:89:0)
[16:01:44.066] Timer tick, asking Buildbucket for the build status
[16:01:44.132] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:44.132] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:90:0) after 1m0s
[16:02:44.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:90:0)
[16:02:44.188] Timer tick, asking Buildbucket for the build status
[16:02:44.295] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:44.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:91:0) after 1m0s
[16:03:44.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:91:0)
[16:03:44.322] Timer tick, asking Buildbucket for the build status
[16:03:44.399] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:44.399] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:92:0) after 1m0s
[16:04:44.527] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:92:0)
[16:04:44.527] Timer tick, asking Buildbucket for the build status
[16:04:44.589] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:44.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:93:0) after 1m0s
[16:05:44.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:93:0)
[16:05:44.611] Timer tick, asking Buildbucket for the build status
[16:05:44.669] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:44.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:94:0) after 1m0s
[16:06:44.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:94:0)
[16:06:44.690] Timer tick, asking Buildbucket for the build status
[16:06:44.787] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:44.787] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:95:0) after 1m0s
[16:07:44.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:95:0)
[16:07:44.923] Timer tick, asking Buildbucket for the build status
[16:07:44.998] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:44.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:96:0) after 1m0s
[16:08:45.063] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:96:0)
[16:08:45.063] Timer tick, asking Buildbucket for the build status
[16:08:45.181] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:45.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:97:0) after 1m0s
[16:09:45.262] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:97:0)
[16:09:45.262] Timer tick, asking Buildbucket for the build status
[16:09:45.386] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:45.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:98:0) after 1m0s
[16:10:45.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:98:0)
[16:10:45.417] Timer tick, asking Buildbucket for the build status
[16:10:45.517] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:45.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:99:0) after 1m0s
[16:11:45.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:99:0)
[16:11:45.553] Timer tick, asking Buildbucket for the build status
[16:11:45.607] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:45.607] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:100:0) after 1m0s
[16:12:45.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:100:0)
[16:12:45.665] Timer tick, asking Buildbucket for the build status
[16:12:45.699] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:45.699] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:101:0) after 1m0s
[16:13:45.819] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:101:0)
[16:13:45.819] Timer tick, asking Buildbucket for the build status
[16:13:45.871] Build 8934675082378985536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:45.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100105144828103344:102:0) after 1m0s
[16:14:24.303] Received PubSub notification, asking Buildbucket for the build status
[16:14:24.402] Build 8934675082378985536: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:14:24.402] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1537632862891754",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1537626870708339",
"id": "8934675082378985536",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"42ccf211a3e1143b904f03a514d5f3fb0d93b072\"}}",
"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/8934675082378985536/+/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\": \"42ccf211a3e1143b904f03a514d5f3fb0d93b072\", \"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@{#593442}\", \"branch\": \"refs/heads/master\", \"revision\": \"42ccf211a3e1143b904f03a514d5f3fb0d93b072\", \"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\": 1537626870708339, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/42ccf211a3e1143b904f03a514d5f3fb0d93b072\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/42ccf211a3e1143b904f03a514d5f3fb0d93b072\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9100105144828103344\", \"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\": \"8934675082378985536\"}}, \"got_v8_revision\": \"2f22d11156ccb1f88587896c27151944cad48cba\", \"got_v8_revision_cp\": \"refs/heads/7.1.163@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10168, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"314761de097e67d00ada5f4aabf483290a4098d9\", \"notification_helper_unittests\": \"47a0fc8120fb03124edb0818cc94691dc0cad1b4\", \"gpu_perftests\": \"75887b9f09125b71b6706cf008b1414929b89a39\", \"vr_common_unittests\": \"541d6a95c6f1b5562b5e95704cdc7ff9f7ebc92d\", \"telemetry_unittests\": \"b25fe70f01e61d4cd8b0b72acd1abfe9a430f4e3\", \"load_library_perf_tests\": \"8d4762b7a4a084b6f986a705050f6849dcce96e3\", \"devtools_closure_compile\": \"4c14287de95e3ba0fa6108ba6cb04b37f0119f15\", \"chromedriver_py_tests\": \"1baaf6a1ba75bc243e4fb77ba74d1cac3b28d0de\", \"mojo_core_unittests\": \"02df1ba071024bcd3fe54c557a7cfa47b55953a0\", \"install_static_unittests\": \"db30dd750715021e0d3ad0427f8c3c0e883f1ba2\", \"leveldb_service_unittests\": \"60d2d8ebd93e6a89baf85c42902c7ef909d5e9a4\", \"mini_installer_tests\": \"f5748a480b750dae902193a4e26ed730f2a7ca09\", \"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\": \"a956f148a8b281a87c5a0ba6f5c20b69bdd38179\", \"metrics_python_tests\": \"e95e6ad6e22e16e8380bffda0940094e78415b5f\", \"message_center_unittests\": \"090b5077cde1f7b6578b53c65c3894241dfaf3fd\", \"ppapi_unittests\": \"cc8dcfb37a6a92c3e495d4033e88e30ef516691f\", \"extensions_unittests\": \"e7161260ff9a8d3a64eb34354e67fbe9876dd615\", \"gl_unittests_ozone\": \"cb33148ad4c6dfa98676664abc7a76028a7bdb44\", \"app_shell_unittests\": \"cb181490be05c8f316bb47458cd3f14c203b39d9\", \"chrome_app_unittests\": \"c50b9dcb8e1a6b1809e0641f439370c9879948ef\", \"gl_unittests\": \"cb33148ad4c6dfa98676664abc7a76028a7bdb44\", \"gcm_unit_tests\": \"43ee812707947794de0fcc11f101d502c43340e8\", \"views_unittests\": \"ac2461553201709a275d2f78a2be5f3cf2267cdf\", \"content_shell_crash_test\": \"67d591ed97ab42aae26636391614c8df02f83af4\", \"ui_base_unittests\": \"571ebd402575382f175e706dd6fd2362fd7920c0\", \"gles2_conform_test\": \"44e5605930fc986aa3d998038c43777ce4594430\", \"cronet_unittests\": \"bca59d50526c2b272553e57d3c9c64f066f561ef\", \"performance_test_suite\": \"00498642e6f37c0958d42fa7801a814f4af4daa4\", \"wm_unittests\": \"6d81ccef56cbe8fc628870ba2ccbacb62a745030\", \"boringssl_crypto_tests\": \"42f0af3f82979a9893eac3c3643f76b84529b018\", \"gin_unittests\": \"011188c1c0c40646075b89ef530e6f8eae039bfc\", \"performance_browser_tests\": \"de758b23bc79f9abb127d0c10e6861b994b0114c\", \"extensions_browsertests\": \"fb99ed8f5f4b05b88db56ec2a7106a18a79964ed\", \"webkit_python_tests\": \"d158224fa80fea9494613a4e2cc83c3710e1a2b2\", \"headless_unittests\": \"553551198a3402aa81238d20dfb0c4cc4f63c10f\", \"webkit_layout_tests\": \"0f0dfa69d36355e54344bd436777287664f89e64\", \"vr_pixeltests\": \"1377c24380e865cd2aa1537af6696ed5dc69881c\", \"setup_unittests\": \"56b710e3858f41e578d21cafc12288fe8cede364\", \"webkit_unit_tests\": \"75c86794135bbbd16d6ab421827a198f0b32100f\", \"content_unittests\": \"bc26ad8e5ec0d290d97954594df575fb2d358300\", \"performance_webview_test_suite\": \"d889f18eb2e5bd87f6ea7abd30c5de80a3bed21d\", \"wtf_unittests\": \"6330a5dd1e366167efe69357e7de6901969305ce\", \"webkit_layout_tests_exparchive\": \"e1c1c86148f0fd70650ac7b7b05f1b266d8411a9\", \"chrome_cleaner_unittests\": \"dfcef3a33ab89e0e979d35d80fa0f8a1bc38bc41\", \"traffic_annotation_auditor_unittests\": \"84f8f55a7ce53e8d25db995da87ee68e7f3a085e\", \"libjingle_xmpp_unittests\": \"c49e03c8a53c9da5f9ca94a7107852ee8b304bf9\", \"remoting_unittests\": \"a54924dde541ae5d616ed113b8ad0f8fa0738c02\", \"vr_common_perftests\": \"ede7ff9a354529237e04fe4557fa9932067c6098\", \"filesystem_service_unittests\": \"610cb3d5b1811147f3b61b816795fecc7356feb7\", \"chrome_elf_import_unittests\": \"dd0780b586fcf2d1000b4e91959dbec2c97c0000\", \"installer_util_unittests\": \"edb6f35770ec7c2ddf7021223bcde428a3bb33b5\", \"gfx_unittests\": \"7fd8dd90526223ea5785a35af0b15416cdb84d12\", \"headless_browsertests\": \"ee9d71ab68b28daed0907a0544a350a0dd12385a\", \"capture_unittests\": \"518d4b9475a17da1cde25e23e318b02a66eb4858\", \"chrome_elf_unittests\": \"41a1d6b00979f5724657000673023e4fa48531d4\", \"telemetry_gpu_unittests\": \"4ac9381e6a8d96a78b0c3b3c7c9fa029d5f40814\", \"command_buffer_perftests\": \"b97d04e37c33e2ae8740760b1e34f1de506c567e\", \"media_unittests\": \"92ad1c5fb53859e8a622d3f3ac8bc976a04969a1\", \"chromedriver_unittests\": \"c76be0ae283236f1142490c013a795146fb97653\", \"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\": \"99513cc949153c4c4cbf42e04458e42a4a78b172\", \"media_blink_unittests\": \"0c5e1733d68003407f423ffd12437749b3cabc7a\", \"views_mus_unittests\": \"3c2be05d1baf6e09bcfb6842ef2bdb664efb3c60\", \"components_unittests\": \"ca613f00189c5b58958e8bf2056ca8a0a6a17e82\", \"snapshot_unittests\": \"0c39568ec9a82d1c59378202eb8c11557f4e7f2b\", \"accessibility_unittests\": \"8de3a8d37183aaae9838af4fc583c72b05c691d7\", \"net_perftests\": \"40ec6344ecd55d2db0b2cea9826770b5078170e2\", \"aura_unittests\": \"92c031e2a5bb760fb96150861f23e257ac994d70\", \"services_unittests\": \"d227d47851a2a02a5149d563beefa98b6e666f0b\", \"components_perftests\": \"e57836042c944857b8aaddedd56cf3a3d793902e\", \"events_unittests\": \"61478936914c00e63f29d019719fa99c2af95c9d\", \"tracing_perftests\": \"2e0057526c5b177d2e565b17036ddb960a1e0416\", \"chromedriver_replay_unittests\": \"3d75567c4cb5be67d5cf025731258bcd9f3d4b8a\", \"ui_touch_selection_unittests\": \"45db612be15175e731ce5d4e37e20b838e63b5ed\", \"cacheinvalidation_unittests\": \"470ce1496e645fb24338f72c0841750602a6619d\", \"viz_unittests\": \"19c20fc044f39caf1d775769d2832a01915bae0e\", \"browser_tests\": \"e15ff9d25b67db157ab5677738f92069728767a0\", \"net_unittests\": \"4bbeec85fd7ae0ed2f8f7d9da683b137dc70fcd5\", \"midi_unittests\": \"9aae1a581541c8ef687cb66dae0e1ee7181debc8\", \"boringssl_ssl_tests\": \"0ba0fcaff6f265a48cc1c1f2b1babea798fea781\", \"sbox_validation_tests\": \"d15adb290a5bce94bc16392b15c5393ca62e6f1e\", \"elevation_service_unittests\": \"644c1e9d8d1426d6ff086e24025f111d44c2f9a4\", \"gpu_unittests\": \"ea28aee3e71ff16fab6e13a05beb3c6c50f6dbcb\", \"native_theme_unittests\": \"253f2428c006be9f266ff2fa78e9e7485a3f8b36\", \"interactive_ui_tests\": \"bab394fd9f79606f5f4c75bda1b8e7d99a631cde\", \"mojo_unittests\": \"33620517a98a1b536e7e1fc928570830aa9e3d2b\", \"url_unittests\": \"09a06ab47fc2e88ae8de2230f772ff9ed5349be5\", \"media_perftests\": \"474fa4269211c0945306d30ce60507726c858dd0\", \"cronet_tests\": \"e7929eb7e48e0083193b7b9e67369af246b59f45\", \"google_apis_unittests\": \"5c675c442c67c89f956b23552d1b63cd9cd6c58d\", \"telemetry_gpu_integration_test\": \"0adda09cd367503c819c8fff5f39a72f70cc5145\", \"blink_heap_unittests\": \"8f2c732a2986c586dc041a99e1577916aece47f7\", \"base_unittests\": \"cf3bad3f121be6c336ab44a35009fc651d1661b1\", \"sync_integration_tests\": \"b53146c5e01c56a1318045a2571c898e08d3bcda\", \"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\": \"4173f7d3ea7526ad76923110363c8e2d82d308b7\", \"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\": \"526de99b48b142c754c2bf94093f9e695b9a86d8\", \"content_browsertests\": \"8301becd4a98ec014cc578700633cd7ecab80229\", \"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": "1537626925568001",
"status": "COMPLETED",
"status_changed_ts": "1537632863755944",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10168",
"builder:Windows deterministic",
"buildset:commit/git/42ccf211a3e1143b904f03a514d5f3fb0d93b072",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/42ccf211a3e1143b904f03a514d5f3fb0d93b072",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9100105144828103344",
"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/10168",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8934675082378985536",
"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/42ccf211a3e1143b904f03a514d5f3fb0d93b072",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/42ccf211a3e1143b904f03a514d5f3fb0d93b072",
"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/8934675082378985536/+/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:9100105144828103344",
"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:401a85a2c200e310",
"user_agent:luci-scheduler"
],
"updated_ts": "1537632863757695",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10168",
"utcnow_ts": "1537632864333802"
}
}
[16:14:24.403] Invocation finished in 1h39m55.413252072s with status SUCCEEDED