[06:02:29.820] New invocation is queued and will start shortly
[06:02:30.938] Starting the invocation (attempt 1)
[06:02:30.969] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:02:30.969] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:02:30.969] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021952174223128192",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"402f104a7491ff8fae324950facea4552646e7f3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9021952174223128192",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/402f104a7491ff8fae324950facea4552646e7f3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/402f104a7491ff8fae324950facea4552646e7f3",
"gitiles_ref:refs/heads/master"
]
}
[06:02:32.432] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612159351082145",
"id": "8856522112238492128",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"402f104a7491ff8fae324950facea4552646e7f3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612159352090384",
"tags": [
"build_address:luci.chromium.ci/win-official/1509",
"builder:win-official",
"buildset:commit/git/402f104a7491ff8fae324950facea4552646e7f3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/402f104a7491ff8fae324950facea4552646e7f3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021952174223128192",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856522112238492128/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1612159352090713",
"url": "https://ci.chromium.org/b/8856522112238492128",
"utcnow_ts": "1612159352412245"
}
}
[06:02:32.432] Task URL: https://ci.chromium.org/b/8856522112238492128
[06:02:32.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:2:0) after 1m0s
[06:03:16.201] Received PubSub notification, asking Buildbucket for the build status
[06:03:16.269] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:32.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:2:0)
[06:03:32.411] Timer tick, asking Buildbucket for the build status
[06:03:32.475] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:32.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:4:0) after 1m0s
[06:04:32.522] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:4:0)
[06:04:32.522] Timer tick, asking Buildbucket for the build status
[06:04:32.591] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:32.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:5:0) after 1m0s
[06:05:32.689] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:5:0)
[06:05:32.689] Timer tick, asking Buildbucket for the build status
[06:05:32.763] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:32.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:6:0) after 1m0s
[06:06:32.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:6:0)
[06:06:32.779] Timer tick, asking Buildbucket for the build status
[06:06:32.863] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:32.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:7:0) after 1m0s
[06:07:32.886] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:7:0)
[06:07:32.886] Timer tick, asking Buildbucket for the build status
[06:07:32.986] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:32.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:8:0) after 1m0s
[06:08:32.991] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:8:0)
[06:08:32.991] Timer tick, asking Buildbucket for the build status
[06:08:33.322] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:33.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:9:0) after 1m0s
[06:09:33.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:9:0)
[06:09:33.372] Timer tick, asking Buildbucket for the build status
[06:09:33.448] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:33.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:10:0) after 1m0s
[06:10:33.519] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:10:0)
[06:10:33.519] Timer tick, asking Buildbucket for the build status
[06:10:33.703] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:33.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:11:0) after 1m0s
[06:11:33.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:11:0)
[06:11:33.787] Timer tick, asking Buildbucket for the build status
[06:11:33.995] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:33.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:12:0) after 1m0s
[06:12:34.053] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:12:0)
[06:12:34.053] Timer tick, asking Buildbucket for the build status
[06:12:34.155] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:34.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:13:0) after 1m0s
[06:13:34.207] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:13:0)
[06:13:34.207] Timer tick, asking Buildbucket for the build status
[06:13:34.408] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:34.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:14:0) after 1m0s
[06:14:34.509] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:14:0)
[06:14:34.509] Timer tick, asking Buildbucket for the build status
[06:14:34.595] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:34.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:15:0) after 1m0s
[06:15:34.614] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:15:0)
[06:15:34.614] Timer tick, asking Buildbucket for the build status
[06:15:35.134] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:35.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:16:0) after 1m0s
[06:16:35.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:16:0)
[06:16:35.176] Timer tick, asking Buildbucket for the build status
[06:16:35.299] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:35.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:17:0) after 1m0s
[06:17:35.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:17:0)
[06:17:35.350] Timer tick, asking Buildbucket for the build status
[06:17:35.795] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:35.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:18:0) after 1m0s
[06:18:35.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:18:0)
[06:18:35.809] Timer tick, asking Buildbucket for the build status
[06:18:35.901] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:35.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:19:0) after 1m0s
[06:19:35.991] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:19:0)
[06:19:35.991] Timer tick, asking Buildbucket for the build status
[06:19:36.065] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:36.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:20:0) after 1m0s
[06:20:36.097] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:20:0)
[06:20:36.097] Timer tick, asking Buildbucket for the build status
[06:20:36.156] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:36.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:21:0) after 1m0s
[06:21:36.257] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:21:0)
[06:21:36.257] Timer tick, asking Buildbucket for the build status
[06:21:36.319] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:36.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:22:0) after 1m0s
[06:22:36.357] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:22:0)
[06:22:36.357] Timer tick, asking Buildbucket for the build status
[06:22:36.464] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:36.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:23:0) after 1m0s
[06:23:36.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:23:0)
[06:23:36.483] Timer tick, asking Buildbucket for the build status
[06:23:36.540] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:36.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:24:0) after 1m0s
[06:24:36.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:24:0)
[06:24:36.817] Timer tick, asking Buildbucket for the build status
[06:24:37.002] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:37.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:25:0) after 1m0s
[06:25:37.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:25:0)
[06:25:37.225] Timer tick, asking Buildbucket for the build status
[06:25:37.284] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:37.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:26:0) after 1m0s
[06:26:37.325] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:26:0)
[06:26:37.325] Timer tick, asking Buildbucket for the build status
[06:26:37.696] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:37.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:27:0) after 1m0s
[06:27:37.754] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:27:0)
[06:27:37.754] Timer tick, asking Buildbucket for the build status
[06:27:37.853] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:37.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:28:0) after 1m0s
[06:28:37.932] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:28:0)
[06:28:37.932] Timer tick, asking Buildbucket for the build status
[06:28:37.985] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:37.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:29:0) after 1m0s
[06:29:38.161] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:29:0)
[06:29:38.161] Timer tick, asking Buildbucket for the build status
[06:29:38.480] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:38.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:30:0) after 1m0s
[06:30:38.582] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:30:0)
[06:30:38.582] Timer tick, asking Buildbucket for the build status
[06:30:38.645] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:38.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:31:0) after 1m0s
[06:31:38.833] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:31:0)
[06:31:38.833] Timer tick, asking Buildbucket for the build status
[06:31:39.119] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:39.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:32:0) after 1m0s
[06:32:39.243] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:32:0)
[06:32:39.243] Timer tick, asking Buildbucket for the build status
[06:32:39.335] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:39.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:33:0) after 1m0s
[06:33:39.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:33:0)
[06:33:39.444] Timer tick, asking Buildbucket for the build status
[06:33:39.527] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:39.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:34:0) after 1m0s
[06:34:39.630] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:34:0)
[06:34:39.630] Timer tick, asking Buildbucket for the build status
[06:34:39.816] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:39.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:35:0) after 1m0s
[06:35:39.862] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:35:0)
[06:35:39.862] Timer tick, asking Buildbucket for the build status
[06:35:39.914] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:39.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:36:0) after 1m0s
[06:36:39.989] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:36:0)
[06:36:39.989] Timer tick, asking Buildbucket for the build status
[06:36:40.065] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:40.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:37:0) after 1m0s
[06:37:40.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:37:0)
[06:37:40.160] Timer tick, asking Buildbucket for the build status
[06:37:40.302] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:40.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:38:0) after 1m0s
[06:38:40.320] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:38:0)
[06:38:40.320] Timer tick, asking Buildbucket for the build status
[06:38:40.386] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:40.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:39:0) after 1m0s
[06:39:40.438] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:39:0)
[06:39:40.438] Timer tick, asking Buildbucket for the build status
[06:39:40.575] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:40.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:40:0) after 1m0s
[06:40:40.598] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:40:0)
[06:40:40.598] Timer tick, asking Buildbucket for the build status
[06:40:40.746] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:40.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:41:0) after 1m0s
[06:41:40.788] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:41:0)
[06:41:40.788] Timer tick, asking Buildbucket for the build status
[06:41:41.107] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:41.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:42:0) after 1m0s
[06:42:41.199] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:42:0)
[06:42:41.199] Timer tick, asking Buildbucket for the build status
[06:42:41.311] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:41.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:43:0) after 1m0s
[06:43:41.380] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:43:0)
[06:43:41.380] Timer tick, asking Buildbucket for the build status
[06:43:41.467] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:41.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:44:0) after 1m0s
[06:44:41.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:44:0)
[06:44:41.514] Timer tick, asking Buildbucket for the build status
[06:44:41.725] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:41.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:45:0) after 1m0s
[06:45:41.790] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:45:0)
[06:45:41.790] Timer tick, asking Buildbucket for the build status
[06:45:41.860] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:41.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:46:0) after 1m0s
[06:46:41.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:46:0)
[06:46:41.977] Timer tick, asking Buildbucket for the build status
[06:46:42.233] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:42.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:47:0) after 1m0s
[06:47:42.301] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:47:0)
[06:47:42.301] Timer tick, asking Buildbucket for the build status
[06:47:42.486] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:42.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:48:0) after 1m0s
[06:48:42.565] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:48:0)
[06:48:42.565] Timer tick, asking Buildbucket for the build status
[06:48:42.622] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:42.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:49:0) after 1m0s
[06:49:42.677] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:49:0)
[06:49:42.677] Timer tick, asking Buildbucket for the build status
[06:49:42.731] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:42.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:50:0) after 1m0s
[06:50:42.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:50:0)
[06:50:42.763] Timer tick, asking Buildbucket for the build status
[06:50:42.916] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:42.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:51:0) after 1m0s
[06:51:43.062] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:51:0)
[06:51:43.062] Timer tick, asking Buildbucket for the build status
[06:51:43.135] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:43.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:52:0) after 1m0s
[06:52:43.135] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:52:0)
[06:52:43.135] Timer tick, asking Buildbucket for the build status
[06:52:43.318] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:43.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:53:0) after 1m0s
[06:53:43.325] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:53:0)
[06:53:43.325] Timer tick, asking Buildbucket for the build status
[06:53:43.383] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:43.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:54:0) after 1m0s
[06:54:43.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:54:0)
[06:54:43.413] Timer tick, asking Buildbucket for the build status
[06:54:43.499] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:43.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:55:0) after 1m0s
[06:55:43.547] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:55:0)
[06:55:43.547] Timer tick, asking Buildbucket for the build status
[06:55:43.597] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:43.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:56:0) after 1m0s
[06:56:43.611] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:56:0)
[06:56:43.611] Timer tick, asking Buildbucket for the build status
[06:56:43.667] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:43.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:57:0) after 1m0s
[06:57:43.727] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:57:0)
[06:57:43.727] Timer tick, asking Buildbucket for the build status
[06:57:43.860] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:43.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:58:0) after 1m0s
[06:58:43.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:58:0)
[06:58:43.890] Timer tick, asking Buildbucket for the build status
[06:58:44.019] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:44.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:59:0) after 1m0s
[06:59:44.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:59:0)
[06:59:44.077] Timer tick, asking Buildbucket for the build status
[06:59:44.407] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:44.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:60:0) after 1m0s
[07:00:44.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:60:0)
[07:00:44.600] Timer tick, asking Buildbucket for the build status
[07:00:44.664] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:44.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:61:0) after 1m0s
[07:01:44.747] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:61:0)
[07:01:44.747] Timer tick, asking Buildbucket for the build status
[07:01:44.956] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:44.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:62:0) after 1m0s
[07:02:44.991] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:62:0)
[07:02:44.991] Timer tick, asking Buildbucket for the build status
[07:02:45.106] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:45.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:63:0) after 1m0s
[07:03:45.178] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:63:0)
[07:03:45.178] Timer tick, asking Buildbucket for the build status
[07:03:45.324] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:45.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:64:0) after 1m0s
[07:04:45.438] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:64:0)
[07:04:45.438] Timer tick, asking Buildbucket for the build status
[07:04:45.595] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:45.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:65:0) after 1m0s
[07:05:45.663] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:65:0)
[07:05:45.663] Timer tick, asking Buildbucket for the build status
[07:05:45.929] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:45.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:66:0) after 1m0s
[07:06:45.961] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:66:0)
[07:06:45.961] Timer tick, asking Buildbucket for the build status
[07:06:46.022] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:46.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:67:0) after 1m0s
[07:07:46.149] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:67:0)
[07:07:46.149] Timer tick, asking Buildbucket for the build status
[07:07:46.290] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:46.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:68:0) after 1m0s
[07:08:46.315] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:68:0)
[07:08:46.315] Timer tick, asking Buildbucket for the build status
[07:08:46.426] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:46.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:69:0) after 1m0s
[07:09:46.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:69:0)
[07:09:46.499] Timer tick, asking Buildbucket for the build status
[07:09:46.694] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:46.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:70:0) after 1m0s
[07:10:46.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:70:0)
[07:10:46.720] Timer tick, asking Buildbucket for the build status
[07:10:46.805] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:46.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:71:0) after 1m0s
[07:11:46.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:71:0)
[07:11:46.836] Timer tick, asking Buildbucket for the build status
[07:11:46.969] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:46.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:72:0) after 1m0s
[07:12:47.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:72:0)
[07:12:47.042] Timer tick, asking Buildbucket for the build status
[07:12:47.139] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:47.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:73:0) after 1m0s
[07:13:47.183] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:73:0)
[07:13:47.184] Timer tick, asking Buildbucket for the build status
[07:13:47.633] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:47.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:74:0) after 1m0s
[07:14:47.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:74:0)
[07:14:47.640] Timer tick, asking Buildbucket for the build status
[07:14:47.692] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:47.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:75:0) after 1m0s
[07:15:47.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:75:0)
[07:15:47.721] Timer tick, asking Buildbucket for the build status
[07:15:47.773] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:47.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:76:0) after 1m0s
[07:16:47.800] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:76:0)
[07:16:47.800] Timer tick, asking Buildbucket for the build status
[07:16:48.027] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:48.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:77:0) after 1m0s
[07:17:48.124] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:77:0)
[07:17:48.124] Timer tick, asking Buildbucket for the build status
[07:17:48.231] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:48.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:78:0) after 1m0s
[07:18:48.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:78:0)
[07:18:48.264] Timer tick, asking Buildbucket for the build status
[07:18:48.363] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:48.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:79:0) after 1m0s
[07:19:48.428] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:79:0)
[07:19:48.428] Timer tick, asking Buildbucket for the build status
[07:19:48.826] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:48.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:80:0) after 1m0s
[07:20:48.929] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:80:0)
[07:20:48.929] Timer tick, asking Buildbucket for the build status
[07:20:48.990] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:48.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:81:0) after 1m0s
[07:21:49.049] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:81:0)
[07:21:49.049] Timer tick, asking Buildbucket for the build status
[07:21:49.107] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:49.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:82:0) after 1m0s
[07:22:49.128] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:82:0)
[07:22:49.128] Timer tick, asking Buildbucket for the build status
[07:22:49.596] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:49.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:83:0) after 1m0s
[07:23:49.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:83:0)
[07:23:49.640] Timer tick, asking Buildbucket for the build status
[07:23:49.724] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:49.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:84:0) after 1m0s
[07:24:49.783] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:84:0)
[07:24:49.783] Timer tick, asking Buildbucket for the build status
[07:24:49.837] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:49.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:85:0) after 1m0s
[07:25:49.909] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:85:0)
[07:25:49.909] Timer tick, asking Buildbucket for the build status
[07:25:50.052] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:50.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:86:0) after 1m0s
[07:26:50.084] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:86:0)
[07:26:50.084] Timer tick, asking Buildbucket for the build status
[07:26:50.151] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:50.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:87:0) after 1m0s
[07:27:50.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:87:0)
[07:27:50.223] Timer tick, asking Buildbucket for the build status
[07:27:50.440] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:50.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:88:0) after 1m0s
[07:28:50.435] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:88:0)
[07:28:50.435] Timer tick, asking Buildbucket for the build status
[07:28:50.499] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:50.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:89:0) after 1m0s
[07:29:50.592] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:89:0)
[07:29:50.592] Timer tick, asking Buildbucket for the build status
[07:29:50.665] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:50.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:90:0) after 1m0s
[07:30:50.797] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:90:0)
[07:30:50.797] Timer tick, asking Buildbucket for the build status
[07:30:50.853] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:50.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:91:0) after 1m0s
[07:31:50.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:91:0)
[07:31:50.891] Timer tick, asking Buildbucket for the build status
[07:31:50.957] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:50.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:92:0) after 1m0s
[07:32:51.021] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:92:0)
[07:32:51.021] Timer tick, asking Buildbucket for the build status
[07:32:51.134] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:51.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:93:0) after 1m0s
[07:33:51.188] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:93:0)
[07:33:51.188] Timer tick, asking Buildbucket for the build status
[07:33:51.326] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:51.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:94:0) after 1m0s
[07:34:51.393] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:94:0)
[07:34:51.393] Timer tick, asking Buildbucket for the build status
[07:34:51.457] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:51.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:95:0) after 1m0s
[07:35:51.502] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:95:0)
[07:35:51.502] Timer tick, asking Buildbucket for the build status
[07:35:51.562] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:51.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:96:0) after 1m0s
[07:36:51.713] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:96:0)
[07:36:51.713] Timer tick, asking Buildbucket for the build status
[07:36:51.798] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:51.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:97:0) after 1m0s
[07:37:51.908] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:97:0)
[07:37:51.908] Timer tick, asking Buildbucket for the build status
[07:37:52.089] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:52.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:98:0) after 1m0s
[07:38:52.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:98:0)
[07:38:52.209] Timer tick, asking Buildbucket for the build status
[07:38:52.271] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:52.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:99:0) after 1m0s
[07:39:52.393] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:99:0)
[07:39:52.393] Timer tick, asking Buildbucket for the build status
[07:39:52.623] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:52.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:100:0) after 1m0s
[07:40:52.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:100:0)
[07:40:52.665] Timer tick, asking Buildbucket for the build status
[07:40:52.720] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:52.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:101:0) after 1m0s
[07:41:54.271] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:101:0)
[07:41:54.271] Timer tick, asking Buildbucket for the build status
[07:41:54.430] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:54.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:102:0) after 1m0s
[07:42:54.728] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:102:0)
[07:42:54.728] Timer tick, asking Buildbucket for the build status
[07:42:54.884] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:54.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:103:0) after 1m0s
[07:43:55.057] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:103:0)
[07:43:55.057] Timer tick, asking Buildbucket for the build status
[07:43:55.146] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:55.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:104:0) after 1m0s
[07:44:55.331] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:104:0)
[07:44:55.331] Timer tick, asking Buildbucket for the build status
[07:44:55.389] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:55.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:105:0) after 1m0s
[07:45:55.520] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:105:0)
[07:45:55.520] Timer tick, asking Buildbucket for the build status
[07:45:55.589] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:55.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:106:0) after 1m0s
[07:46:55.807] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:106:0)
[07:46:55.807] Timer tick, asking Buildbucket for the build status
[07:46:56.133] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:56.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:107:0) after 1m0s
[07:47:56.196] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:107:0)
[07:47:56.196] Timer tick, asking Buildbucket for the build status
[07:47:56.642] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:56.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:108:0) after 1m0s
[07:48:56.716] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:108:0)
[07:48:56.716] Timer tick, asking Buildbucket for the build status
[07:48:56.779] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:56.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:109:0) after 1m0s
[07:49:56.851] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:109:0)
[07:49:56.851] Timer tick, asking Buildbucket for the build status
[07:49:57.046] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:57.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:110:0) after 1m0s
[07:50:57.059] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:110:0)
[07:50:57.059] Timer tick, asking Buildbucket for the build status
[07:50:57.522] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:57.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:111:0) after 1m0s
[07:51:57.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:111:0)
[07:51:57.601] Timer tick, asking Buildbucket for the build status
[07:51:57.677] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:57.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:112:0) after 1m0s
[07:52:57.697] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:112:0)
[07:52:57.697] Timer tick, asking Buildbucket for the build status
[07:52:57.812] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:57.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:113:0) after 1m0s
[07:53:57.833] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:113:0)
[07:53:57.833] Timer tick, asking Buildbucket for the build status
[07:53:58.209] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:58.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:114:0) after 1m0s
[07:54:58.270] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:114:0)
[07:54:58.270] Timer tick, asking Buildbucket for the build status
[07:54:58.421] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:58.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:115:0) after 1m0s
[07:55:58.424] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:115:0)
[07:55:58.424] Timer tick, asking Buildbucket for the build status
[07:55:58.476] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:58.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:116:0) after 1m0s
[07:56:58.473] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:116:0)
[07:56:58.473] Timer tick, asking Buildbucket for the build status
[07:56:58.525] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:58.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:117:0) after 1m0s
[07:57:58.570] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:117:0)
[07:57:58.570] Timer tick, asking Buildbucket for the build status
[07:57:58.700] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:58.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:118:0) after 1m0s
[07:58:58.806] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:118:0)
[07:58:58.807] Timer tick, asking Buildbucket for the build status
[07:58:58.872] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:58.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:119:0) after 1m0s
[07:59:58.946] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:119:0)
[07:59:58.946] Timer tick, asking Buildbucket for the build status
[07:59:59.152] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:59.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:120:0) after 1m0s
[08:00:59.287] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:120:0)
[08:00:59.287] Timer tick, asking Buildbucket for the build status
[08:00:59.383] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:59.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:121:0) after 1m0s
[08:01:59.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:121:0)
[08:01:59.500] Timer tick, asking Buildbucket for the build status
[08:01:59.571] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:59.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:122:0) after 1m0s
[08:02:59.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:122:0)
[08:02:59.597] Timer tick, asking Buildbucket for the build status
[08:02:59.811] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:59.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:123:0) after 1m0s
[08:03:59.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:123:0)
[08:03:59.920] Timer tick, asking Buildbucket for the build status
[08:04:00.221] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:00.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:124:0) after 1m0s
[08:05:00.591] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:124:0)
[08:05:00.591] Timer tick, asking Buildbucket for the build status
[08:05:00.651] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:00.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:125:0) after 1m0s
[08:06:00.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:125:0)
[08:06:00.927] Timer tick, asking Buildbucket for the build status
[08:06:00.986] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:00.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:126:0) after 1m0s
[08:07:01.054] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:126:0)
[08:07:01.054] Timer tick, asking Buildbucket for the build status
[08:07:01.142] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:01.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:127:0) after 1m0s
[08:08:01.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:127:0)
[08:08:01.337] Timer tick, asking Buildbucket for the build status
[08:08:01.457] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:01.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:128:0) after 1m0s
[08:09:01.522] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:128:0)
[08:09:01.522] Timer tick, asking Buildbucket for the build status
[08:09:01.592] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:01.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:129:0) after 1m0s
[08:10:01.637] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:129:0)
[08:10:01.637] Timer tick, asking Buildbucket for the build status
[08:10:01.715] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:01.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:130:0) after 1m0s
[08:11:01.746] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:130:0)
[08:11:01.746] Timer tick, asking Buildbucket for the build status
[08:11:01.975] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:01.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:131:0) after 1m0s
[08:12:02.037] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:131:0)
[08:12:02.037] Timer tick, asking Buildbucket for the build status
[08:12:02.234] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:02.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:132:0) after 1m0s
[08:13:02.332] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:132:0)
[08:13:02.332] Timer tick, asking Buildbucket for the build status
[08:13:02.953] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:02.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:133:0) after 1m0s
[08:14:02.997] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:133:0)
[08:14:02.997] Timer tick, asking Buildbucket for the build status
[08:14:03.077] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:03.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:134:0) after 1m0s
[08:15:03.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:134:0)
[08:15:03.102] Timer tick, asking Buildbucket for the build status
[08:15:03.158] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:03.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:135:0) after 1m0s
[08:16:03.181] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:135:0)
[08:16:03.181] Timer tick, asking Buildbucket for the build status
[08:16:03.331] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:03.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:136:0) after 1m0s
[08:17:03.390] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:136:0)
[08:17:03.391] Timer tick, asking Buildbucket for the build status
[08:17:03.507] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:03.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:137:0) after 1m0s
[08:18:03.598] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:137:0)
[08:18:03.598] Timer tick, asking Buildbucket for the build status
[08:18:04.033] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:04.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:138:0) after 1m0s
[08:19:04.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:138:0)
[08:19:04.145] Timer tick, asking Buildbucket for the build status
[08:19:04.553] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:04.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:139:0) after 1m0s
[08:20:04.617] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:139:0)
[08:20:04.617] Timer tick, asking Buildbucket for the build status
[08:20:04.674] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:04.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:140:0) after 1m0s
[08:21:04.762] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:140:0)
[08:21:04.762] Timer tick, asking Buildbucket for the build status
[08:21:04.863] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:04.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:141:0) after 1m0s
[08:22:04.997] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:141:0)
[08:22:04.997] Timer tick, asking Buildbucket for the build status
[08:22:05.060] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:05.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:142:0) after 1m0s
[08:23:05.232] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:142:0)
[08:23:05.232] Timer tick, asking Buildbucket for the build status
[08:23:05.350] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:05.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:143:0) after 1m0s
[08:24:05.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:143:0)
[08:24:05.411] Timer tick, asking Buildbucket for the build status
[08:24:05.476] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:05.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:144:0) after 1m0s
[08:25:05.592] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:144:0)
[08:25:05.592] Timer tick, asking Buildbucket for the build status
[08:25:05.667] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:05.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:145:0) after 1m0s
[08:26:05.829] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:145:0)
[08:26:05.829] Timer tick, asking Buildbucket for the build status
[08:26:05.887] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:05.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:146:0) after 1m0s
[08:27:06.108] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:146:0)
[08:27:06.108] Timer tick, asking Buildbucket for the build status
[08:27:06.278] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:06.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:147:0) after 1m0s
[08:28:06.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:147:0)
[08:28:06.316] Timer tick, asking Buildbucket for the build status
[08:28:06.520] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:06.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:148:0) after 1m0s
[08:29:06.552] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:148:0)
[08:29:06.552] Timer tick, asking Buildbucket for the build status
[08:29:06.806] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:06.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:149:0) after 1m0s
[08:30:06.840] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:149:0)
[08:30:06.840] Timer tick, asking Buildbucket for the build status
[08:30:06.926] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:06.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:150:0) after 1m0s
[08:31:07.017] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:150:0)
[08:31:07.017] Timer tick, asking Buildbucket for the build status
[08:31:07.420] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:07.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:151:0) after 1m0s
[08:32:07.450] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:151:0)
[08:32:07.450] Timer tick, asking Buildbucket for the build status
[08:32:07.509] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:07.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:152:0) after 1m0s
[08:33:07.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:152:0)
[08:33:07.621] Timer tick, asking Buildbucket for the build status
[08:33:07.760] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:07.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:153:0) after 1m0s
[08:34:07.782] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:153:0)
[08:34:07.782] Timer tick, asking Buildbucket for the build status
[08:34:07.895] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:07.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:154:0) after 1m0s
[08:35:07.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:154:0)
[08:35:07.925] Timer tick, asking Buildbucket for the build status
[08:35:07.988] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:07.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:155:0) after 1m0s
[08:36:08.049] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:155:0)
[08:36:08.049] Timer tick, asking Buildbucket for the build status
[08:36:08.125] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:08.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:156:0) after 1m0s
[08:37:08.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:156:0)
[08:37:08.298] Timer tick, asking Buildbucket for the build status
[08:37:08.364] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:08.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:157:0) after 1m0s
[08:38:08.536] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:157:0)
[08:38:08.536] Timer tick, asking Buildbucket for the build status
[08:38:08.653] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:08.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:158:0) after 1m0s
[08:39:08.752] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:158:0)
[08:39:08.752] Timer tick, asking Buildbucket for the build status
[08:39:09.034] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:09.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:159:0) after 1m0s
[08:40:09.121] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:159:0)
[08:40:09.121] Timer tick, asking Buildbucket for the build status
[08:40:09.450] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:09.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:160:0) after 1m0s
[08:41:09.631] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:160:0)
[08:41:09.631] Timer tick, asking Buildbucket for the build status
[08:41:09.730] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:09.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:161:0) after 1m0s
[08:42:09.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:161:0)
[08:42:09.809] Timer tick, asking Buildbucket for the build status
[08:42:10.105] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:10.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:162:0) after 1m0s
[08:43:10.108] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:162:0)
[08:43:10.108] Timer tick, asking Buildbucket for the build status
[08:43:10.257] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:10.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:163:0) after 1m0s
[08:44:10.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:163:0)
[08:44:10.314] Timer tick, asking Buildbucket for the build status
[08:44:10.434] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:10.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:164:0) after 1m0s
[08:45:10.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:164:0)
[08:45:10.475] Timer tick, asking Buildbucket for the build status
[08:45:10.641] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:10.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:165:0) after 1m0s
[08:46:10.647] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:165:0)
[08:46:10.647] Timer tick, asking Buildbucket for the build status
[08:46:10.752] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:10.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:166:0) after 1m0s
[08:47:10.791] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:166:0)
[08:47:10.791] Timer tick, asking Buildbucket for the build status
[08:47:10.900] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:10.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:167:0) after 1m0s
[08:48:10.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:167:0)
[08:48:10.949] Timer tick, asking Buildbucket for the build status
[08:48:11.072] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:11.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:168:0) after 1m0s
[08:49:11.114] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:168:0)
[08:49:11.114] Timer tick, asking Buildbucket for the build status
[08:49:11.431] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:11.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:169:0) after 1m0s
[08:50:11.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:169:0)
[08:50:11.590] Timer tick, asking Buildbucket for the build status
[08:50:11.733] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:11.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:170:0) after 1m0s
[08:51:11.800] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:170:0)
[08:51:11.800] Timer tick, asking Buildbucket for the build status
[08:51:11.875] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:11.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:171:0) after 1m0s
[08:52:11.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:171:0)
[08:52:11.873] Timer tick, asking Buildbucket for the build status
[08:52:11.954] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:11.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:172:0) after 1m0s
[08:53:11.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:172:0)
[08:53:11.976] Timer tick, asking Buildbucket for the build status
[08:53:12.321] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:12.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:173:0) after 1m0s
[08:54:12.393] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:173:0)
[08:54:12.393] Timer tick, asking Buildbucket for the build status
[08:54:12.501] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:12.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:174:0) after 1m0s
[08:55:12.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:174:0)
[08:55:12.537] Timer tick, asking Buildbucket for the build status
[08:55:12.656] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:12.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:175:0) after 1m0s
[08:56:12.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:175:0)
[08:56:12.671] Timer tick, asking Buildbucket for the build status
[08:56:12.849] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:12.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:176:0) after 1m0s
[08:57:12.911] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:176:0)
[08:57:12.911] Timer tick, asking Buildbucket for the build status
[08:57:13.228] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:13.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:177:0) after 1m0s
[08:58:13.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:177:0)
[08:58:13.361] Timer tick, asking Buildbucket for the build status
[08:58:13.467] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:13.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:178:0) after 1m0s
[08:59:13.531] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:178:0)
[08:59:13.531] Timer tick, asking Buildbucket for the build status
[08:59:13.590] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:13.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:179:0) after 1m0s
[09:00:13.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:179:0)
[09:00:13.590] Timer tick, asking Buildbucket for the build status
[09:00:13.712] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:13.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:180:0) after 1m0s
[09:01:13.771] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:180:0)
[09:01:13.771] Timer tick, asking Buildbucket for the build status
[09:01:13.830] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:13.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:181:0) after 1m0s
[09:02:13.931] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:181:0)
[09:02:13.931] Timer tick, asking Buildbucket for the build status
[09:02:13.987] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:13.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:182:0) after 1m0s
[09:03:13.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:182:0)
[09:03:13.978] Timer tick, asking Buildbucket for the build status
[09:03:14.102] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:14.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:183:0) after 1m0s
[09:04:14.110] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:183:0)
[09:04:14.110] Timer tick, asking Buildbucket for the build status
[09:04:14.653] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:14.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:184:0) after 1m0s
[09:05:14.770] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:184:0)
[09:05:14.770] Timer tick, asking Buildbucket for the build status
[09:05:14.911] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:14.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:185:0) after 1m0s
[09:06:14.937] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:185:0)
[09:06:14.937] Timer tick, asking Buildbucket for the build status
[09:06:15.212] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:15.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:186:0) after 1m0s
[09:07:15.236] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:186:0)
[09:07:15.236] Timer tick, asking Buildbucket for the build status
[09:07:15.432] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:15.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:187:0) after 1m0s
[09:08:15.469] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:187:0)
[09:08:15.469] Timer tick, asking Buildbucket for the build status
[09:08:15.850] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:15.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:188:0) after 1m0s
[09:09:15.872] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:188:0)
[09:09:15.872] Timer tick, asking Buildbucket for the build status
[09:09:16.042] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:16.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:189:0) after 1m0s
[09:10:16.085] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:189:0)
[09:10:16.085] Timer tick, asking Buildbucket for the build status
[09:10:16.227] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:16.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:190:0) after 1m0s
[09:11:16.255] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:190:0)
[09:11:16.255] Timer tick, asking Buildbucket for the build status
[09:11:16.427] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:16.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:191:0) after 1m0s
[09:12:16.456] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:191:0)
[09:12:16.456] Timer tick, asking Buildbucket for the build status
[09:12:16.514] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:16.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:192:0) after 1m0s
[09:13:16.548] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:192:0)
[09:13:16.548] Timer tick, asking Buildbucket for the build status
[09:13:16.658] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:16.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:193:0) after 1m0s
[09:14:16.697] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:193:0)
[09:14:16.697] Timer tick, asking Buildbucket for the build status
[09:14:16.752] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:16.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:194:0) after 1m0s
[09:15:16.788] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:194:0)
[09:15:16.788] Timer tick, asking Buildbucket for the build status
[09:15:16.931] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:16.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:195:0) after 1m0s
[09:16:16.964] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:195:0)
[09:16:16.964] Timer tick, asking Buildbucket for the build status
[09:16:17.027] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:17.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:196:0) after 1m0s
[09:17:17.051] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:196:0)
[09:17:17.051] Timer tick, asking Buildbucket for the build status
[09:17:17.154] Build 8856522112238492128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:17.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021952174223128192:197:0) after 1m0s
[09:17:37.132] Received PubSub notification, asking Buildbucket for the build status
[09:17:37.269] Build 8856522112238492128: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:17:37.269] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612171056361758",
"created_by": "project:chromium",
"created_ts": "1612159351082145",
"id": "8856522112238492128",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"402f104a7491ff8fae324950facea4552646e7f3\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"402f104a7491ff8fae324950facea4552646e7f3\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-2-ksg6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1509, \"got_angle_revision\": \"0be050a44a4308ace01229b89bd4560457356007\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"402f104a7491ff8fae324950facea4552646e7f3\", \"got_revision_cp\": \"refs/heads/master@{#849004}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"8b4cf1c2491d56cf9eda153b5455e4b295732b7c\", \"got_v8_revision\": \"6069442cab5b767e9722d20cabda6d4864fb7210\", \"got_v8_revision_cp\": \"refs/heads/9.0.102@{#1}\", \"got_webrtc_revision\": \"41bfcf4a63611409220fcd458a03deaa2cd23619\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33116}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"402f104a7491ff8fae324950facea4552646e7f3\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-2-ksg6\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612159395494135",
"status": "COMPLETED",
"status_changed_ts": "1612171056687994",
"tags": [
"build_address:luci.chromium.ci/win-official/1509",
"builder:win-official",
"buildset:commit/git/402f104a7491ff8fae324950facea4552646e7f3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/402f104a7491ff8fae324950facea4552646e7f3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021952174223128192",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856522112238492128/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:5175010622917e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612171056695973",
"url": "https://ci.chromium.org/b/8856522112238492128",
"utcnow_ts": "1612171057253900"
}
}
[09:17:37.269] Invocation finished in 3h15m7.464425349s with status SUCCEEDED