[22:40:24.360] New invocation is queued and will start shortly
[22:40:25.465] Starting the invocation (attempt 1)
[22:40:25.500] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:40:25.500] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:40:25.500] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9032851624153663344",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7af6cfd5967dd6528556c0024c467d91f5e5798d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9032851624153663344",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/7af6cfd5967dd6528556c0024c467d91f5e5798d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7af6cfd5967dd6528556c0024c467d91f5e5798d",
"gitiles_ref:refs/heads/master"
]
}
[22:40:26.846] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1601764825608571",
"id": "8867421562181675696",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7af6cfd5967dd6528556c0024c467d91f5e5798d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1601764826708032",
"tags": [
"build_address:luci.chromium.ci/win-official/726",
"builder:win-official",
"buildset:commit/git/7af6cfd5967dd6528556c0024c467d91f5e5798d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7af6cfd5967dd6528556c0024c467d91f5e5798d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032851624153663344",
"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/8867421562181675696/+/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": "1601764826708157",
"url": "https://ci.chromium.org/b/8867421562181675696",
"utcnow_ts": "1601764826833857"
}
}
[22:40:26.846] Task URL: https://ci.chromium.org/b/8867421562181675696
[22:40:26.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:2:0) after 1m0s
[22:40:53.247] Received PubSub notification, asking Buildbucket for the build status
[22:40:53.464] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:26.917] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:2:0)
[22:41:26.917] Timer tick, asking Buildbucket for the build status
[22:41:27.001] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:27.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:4:0) after 1m0s
[22:42:27.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:4:0)
[22:42:27.077] Timer tick, asking Buildbucket for the build status
[22:42:27.171] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:27.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:5:0) after 1m0s
[22:43:27.252] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:5:0)
[22:43:27.252] Timer tick, asking Buildbucket for the build status
[22:43:28.042] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:28.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:6:0) after 1m0s
[22:44:28.063] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:6:0)
[22:44:28.063] Timer tick, asking Buildbucket for the build status
[22:44:28.181] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:28.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:7:0) after 1m0s
[22:45:28.215] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:7:0)
[22:45:28.215] Timer tick, asking Buildbucket for the build status
[22:45:28.373] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:28.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:8:0) after 1m0s
[22:46:28.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:8:0)
[22:46:28.402] Timer tick, asking Buildbucket for the build status
[22:46:28.633] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:28.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:9:0) after 1m0s
[22:47:28.655] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:9:0)
[22:47:28.655] Timer tick, asking Buildbucket for the build status
[22:47:28.755] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:28.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:10:0) after 1m0s
[22:48:28.774] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:10:0)
[22:48:28.774] Timer tick, asking Buildbucket for the build status
[22:48:28.883] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:28.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:11:0) after 1m0s
[22:49:28.911] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:11:0)
[22:49:28.911] Timer tick, asking Buildbucket for the build status
[22:49:29.018] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:29.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:12:0) after 1m0s
[22:50:29.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:12:0)
[22:50:29.005] Timer tick, asking Buildbucket for the build status
[22:50:29.158] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:29.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:13:0) after 1m0s
[22:51:29.243] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:13:0)
[22:51:29.243] Timer tick, asking Buildbucket for the build status
[22:51:29.680] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:29.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:14:0) after 1m0s
[22:52:29.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:14:0)
[22:52:29.726] Timer tick, asking Buildbucket for the build status
[22:52:29.857] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:29.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:15:0) after 1m0s
[22:53:29.880] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:15:0)
[22:53:29.880] Timer tick, asking Buildbucket for the build status
[22:53:29.976] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:29.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:16:0) after 1m0s
[22:54:30.081] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:16:0)
[22:54:30.081] Timer tick, asking Buildbucket for the build status
[22:54:30.175] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:30.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:17:0) after 1m0s
[22:55:30.220] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:17:0)
[22:55:30.220] Timer tick, asking Buildbucket for the build status
[22:55:30.337] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:30.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:18:0) after 1m0s
[22:56:30.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:18:0)
[22:56:30.362] Timer tick, asking Buildbucket for the build status
[22:56:30.623] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:30.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:19:0) after 1m0s
[22:57:30.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:19:0)
[22:57:30.645] Timer tick, asking Buildbucket for the build status
[22:57:30.767] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:30.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:20:0) after 1m0s
[22:58:30.789] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:20:0)
[22:58:30.789] Timer tick, asking Buildbucket for the build status
[22:58:30.854] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:30.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:21:0) after 1m0s
[22:59:30.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:21:0)
[22:59:30.845] Timer tick, asking Buildbucket for the build status
[22:59:31.049] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:31.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:22:0) after 1m0s
[23:00:31.082] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:22:0)
[23:00:31.082] Timer tick, asking Buildbucket for the build status
[23:00:31.791] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:31.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:23:0) after 1m0s
[23:01:31.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:23:0)
[23:01:31.818] Timer tick, asking Buildbucket for the build status
[23:01:32.207] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:32.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:24:0) after 1m0s
[23:02:32.231] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:24:0)
[23:02:32.231] Timer tick, asking Buildbucket for the build status
[23:02:32.459] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:32.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:25:0) after 1m0s
[23:03:32.488] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:25:0)
[23:03:32.488] Timer tick, asking Buildbucket for the build status
[23:03:32.598] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:32.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:26:0) after 1m0s
[23:04:32.628] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:26:0)
[23:04:32.628] Timer tick, asking Buildbucket for the build status
[23:04:32.701] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:32.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:27:0) after 1m0s
[23:05:32.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:27:0)
[23:05:32.726] Timer tick, asking Buildbucket for the build status
[23:05:32.933] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:32.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:28:0) after 1m0s
[23:06:33.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:28:0)
[23:06:33.005] Timer tick, asking Buildbucket for the build status
[23:06:33.152] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:33.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:29:0) after 1m0s
[23:07:33.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:29:0)
[23:07:33.237] Timer tick, asking Buildbucket for the build status
[23:07:33.323] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:33.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:30:0) after 1m0s
[23:08:33.331] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:30:0)
[23:08:33.331] Timer tick, asking Buildbucket for the build status
[23:08:33.401] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:33.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:31:0) after 1m0s
[23:09:33.477] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:31:0)
[23:09:33.477] Timer tick, asking Buildbucket for the build status
[23:09:33.548] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:33.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:32:0) after 1m0s
[23:10:33.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:32:0)
[23:10:33.571] Timer tick, asking Buildbucket for the build status
[23:10:33.897] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:33.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:33:0) after 1m0s
[23:11:33.906] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:33:0)
[23:11:33.906] Timer tick, asking Buildbucket for the build status
[23:11:33.994] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:33.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:34:0) after 1m0s
[23:12:34.014] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:34:0)
[23:12:34.014] Timer tick, asking Buildbucket for the build status
[23:12:34.098] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:34.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:35:0) after 1m0s
[23:13:34.124] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:35:0)
[23:13:34.124] Timer tick, asking Buildbucket for the build status
[23:13:34.228] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:34.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:36:0) after 1m0s
[23:14:34.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:36:0)
[23:14:34.304] Timer tick, asking Buildbucket for the build status
[23:14:34.377] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:34.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:37:0) after 1m0s
[23:15:34.408] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:37:0)
[23:15:34.408] Timer tick, asking Buildbucket for the build status
[23:15:34.618] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:34.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:38:0) after 1m0s
[23:16:34.688] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:38:0)
[23:16:34.688] Timer tick, asking Buildbucket for the build status
[23:16:35.071] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:35.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:39:0) after 1m0s
[23:17:35.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:39:0)
[23:17:35.116] Timer tick, asking Buildbucket for the build status
[23:17:35.234] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:35.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:40:0) after 1m0s
[23:18:35.266] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:40:0)
[23:18:35.266] Timer tick, asking Buildbucket for the build status
[23:18:35.441] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:35.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:41:0) after 1m0s
[23:19:35.454] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:41:0)
[23:19:35.454] Timer tick, asking Buildbucket for the build status
[23:19:35.516] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:35.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:42:0) after 1m0s
[23:20:35.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:42:0)
[23:20:35.539] Timer tick, asking Buildbucket for the build status
[23:20:35.671] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:35.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:43:0) after 1m0s
[23:21:35.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:43:0)
[23:21:35.699] Timer tick, asking Buildbucket for the build status
[23:21:35.944] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:35.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:44:0) after 1m0s
[23:22:36.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:44:0)
[23:22:36.118] Timer tick, asking Buildbucket for the build status
[23:22:36.317] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:36.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:45:0) after 1m0s
[23:23:36.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:45:0)
[23:23:36.309] Timer tick, asking Buildbucket for the build status
[23:23:36.418] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:36.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:46:0) after 1m0s
[23:24:36.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:46:0)
[23:24:36.470] Timer tick, asking Buildbucket for the build status
[23:24:36.638] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:36.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:47:0) after 1m0s
[23:25:36.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:47:0)
[23:25:36.639] Timer tick, asking Buildbucket for the build status
[23:25:36.755] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:36.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:48:0) after 1m0s
[23:26:36.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:48:0)
[23:26:36.781] Timer tick, asking Buildbucket for the build status
[23:26:36.875] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:36.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:49:0) after 1m0s
[23:27:37.053] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:49:0)
[23:27:37.053] Timer tick, asking Buildbucket for the build status
[23:27:37.139] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:37.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:50:0) after 1m0s
[23:28:37.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:50:0)
[23:28:37.254] Timer tick, asking Buildbucket for the build status
[23:28:37.410] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:37.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:51:0) after 1m0s
[23:29:37.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:51:0)
[23:29:37.447] Timer tick, asking Buildbucket for the build status
[23:29:37.726] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:37.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:52:0) after 1m0s
[23:30:37.796] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:52:0)
[23:30:37.796] Timer tick, asking Buildbucket for the build status
[23:30:37.885] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:37.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:53:0) after 1m0s
[23:31:37.907] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:53:0)
[23:31:37.907] Timer tick, asking Buildbucket for the build status
[23:31:38.236] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:38.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:54:0) after 1m0s
[23:32:38.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:54:0)
[23:32:38.302] Timer tick, asking Buildbucket for the build status
[23:32:38.878] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:38.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:55:0) after 1m0s
[23:33:38.900] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:55:0)
[23:33:38.900] Timer tick, asking Buildbucket for the build status
[23:33:38.975] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:38.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:56:0) after 1m0s
[23:34:39.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:56:0)
[23:34:39.043] Timer tick, asking Buildbucket for the build status
[23:34:39.117] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:39.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:57:0) after 1m0s
[23:35:39.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:57:0)
[23:35:39.160] Timer tick, asking Buildbucket for the build status
[23:35:39.269] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:39.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:58:0) after 1m0s
[23:36:39.300] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:58:0)
[23:36:39.300] Timer tick, asking Buildbucket for the build status
[23:36:39.373] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:39.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:59:0) after 1m0s
[23:37:39.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:59:0)
[23:37:39.447] Timer tick, asking Buildbucket for the build status
[23:37:39.528] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:39.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:60:0) after 1m0s
[23:38:44.129] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:60:0)
[23:38:44.129] Timer tick, asking Buildbucket for the build status
[23:38:44.195] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:44.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:61:0) after 1m0s
[23:39:44.171] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:61:0)
[23:39:44.171] Timer tick, asking Buildbucket for the build status
[23:39:44.234] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:44.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:62:0) after 1m0s
[23:40:44.221] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:62:0)
[23:40:44.221] Timer tick, asking Buildbucket for the build status
[23:40:44.362] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:44.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:63:0) after 1m0s
[23:41:44.349] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:63:0)
[23:41:44.349] Timer tick, asking Buildbucket for the build status
[23:41:44.419] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:44.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:64:0) after 1m0s
[23:42:44.476] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:64:0)
[23:42:44.476] Timer tick, asking Buildbucket for the build status
[23:42:44.645] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:44.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:65:0) after 1m0s
[23:43:44.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:65:0)
[23:43:44.671] Timer tick, asking Buildbucket for the build status
[23:43:45.009] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:45.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:66:0) after 1m0s
[23:44:45.051] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:66:0)
[23:44:45.051] Timer tick, asking Buildbucket for the build status
[23:44:45.207] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:45.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:67:0) after 1m0s
[23:45:45.287] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:67:0)
[23:45:45.287] Timer tick, asking Buildbucket for the build status
[23:45:45.426] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:45.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:68:0) after 1m0s
[23:46:45.593] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:68:0)
[23:46:45.593] Timer tick, asking Buildbucket for the build status
[23:46:45.673] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:45.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:69:0) after 1m0s
[23:47:45.714] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:69:0)
[23:47:45.714] Timer tick, asking Buildbucket for the build status
[23:47:45.779] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:45.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:70:0) after 1m0s
[23:48:45.772] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:70:0)
[23:48:45.772] Timer tick, asking Buildbucket for the build status
[23:48:45.845] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:45.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:71:0) after 1m0s
[23:49:45.935] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:71:0)
[23:49:45.935] Timer tick, asking Buildbucket for the build status
[23:49:45.999] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:45.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:72:0) after 1m0s
[23:50:46.036] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:72:0)
[23:50:46.036] Timer tick, asking Buildbucket for the build status
[23:50:46.164] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:46.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:73:0) after 1m0s
[23:51:46.186] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:73:0)
[23:51:46.186] Timer tick, asking Buildbucket for the build status
[23:51:46.247] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:46.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:74:0) after 1m0s
[23:52:46.303] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:74:0)
[23:52:46.303] Timer tick, asking Buildbucket for the build status
[23:52:46.361] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:46.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:75:0) after 1m0s
[23:53:46.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:75:0)
[23:53:46.383] Timer tick, asking Buildbucket for the build status
[23:53:46.454] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:46.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:76:0) after 1m0s
[23:54:46.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:76:0)
[23:54:46.482] Timer tick, asking Buildbucket for the build status
[23:54:46.581] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:46.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:77:0) after 1m0s
[23:55:46.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:77:0)
[23:55:46.603] Timer tick, asking Buildbucket for the build status
[23:55:46.704] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:46.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:78:0) after 1m0s
[23:56:46.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:78:0)
[23:56:46.729] Timer tick, asking Buildbucket for the build status
[23:56:46.798] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:46.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:79:0) after 1m0s
[23:57:46.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:79:0)
[23:57:46.845] Timer tick, asking Buildbucket for the build status
[23:57:46.941] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:46.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:80:0) after 1m0s
[23:58:47.352] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:80:0)
[23:58:47.352] Timer tick, asking Buildbucket for the build status
[23:58:47.500] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:47.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:81:0) after 1m0s
[23:59:47.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:81:0)
[23:59:47.618] Timer tick, asking Buildbucket for the build status
[23:59:47.839] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:47.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:82:0) after 1m0s
[00:00:47.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:82:0)
[00:00:47.962] Timer tick, asking Buildbucket for the build status
[00:00:48.190] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:48.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:83:0) after 1m0s
[00:01:48.354] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:83:0)
[00:01:48.354] Timer tick, asking Buildbucket for the build status
[00:01:48.955] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:48.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:84:0) after 1m0s
[00:02:48.958] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:84:0)
[00:02:48.958] Timer tick, asking Buildbucket for the build status
[00:02:49.018] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:49.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:85:0) after 1m0s
[00:03:49.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:85:0)
[00:03:49.174] Timer tick, asking Buildbucket for the build status
[00:03:49.360] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:49.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:86:0) after 1m0s
[00:04:49.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:86:0)
[00:04:49.361] Timer tick, asking Buildbucket for the build status
[00:04:49.570] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:49.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:87:0) after 1m0s
[00:05:49.649] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:87:0)
[00:05:49.649] Timer tick, asking Buildbucket for the build status
[00:05:49.758] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:49.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:88:0) after 1m0s
[00:06:49.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:88:0)
[00:06:49.920] Timer tick, asking Buildbucket for the build status
[00:06:50.005] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:50.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:89:0) after 1m0s
[00:07:50.011] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:89:0)
[00:07:50.011] Timer tick, asking Buildbucket for the build status
[00:07:50.118] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:50.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:90:0) after 1m0s
[00:08:50.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:90:0)
[00:08:50.817] Timer tick, asking Buildbucket for the build status
[00:08:50.951] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:50.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:91:0) after 1m0s
[00:09:51.057] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:91:0)
[00:09:51.057] Timer tick, asking Buildbucket for the build status
[00:09:51.124] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:51.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:92:0) after 1m0s
[00:10:51.215] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:92:0)
[00:10:51.215] Timer tick, asking Buildbucket for the build status
[00:10:51.644] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:51.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:93:0) after 1m0s
[00:11:51.745] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:93:0)
[00:11:51.745] Timer tick, asking Buildbucket for the build status
[00:11:51.981] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:51.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:94:0) after 1m0s
[00:12:52.109] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:94:0)
[00:12:52.109] Timer tick, asking Buildbucket for the build status
[00:12:52.316] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:52.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:95:0) after 1m0s
[00:13:52.397] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:95:0)
[00:13:52.397] Timer tick, asking Buildbucket for the build status
[00:13:52.536] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:52.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:96:0) after 1m0s
[00:14:52.736] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:96:0)
[00:14:52.736] Timer tick, asking Buildbucket for the build status
[00:14:52.862] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:52.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:97:0) after 1m0s
[00:15:52.894] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:97:0)
[00:15:52.894] Timer tick, asking Buildbucket for the build status
[00:15:53.032] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:53.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:98:0) after 1m0s
[00:16:53.079] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:98:0)
[00:16:53.079] Timer tick, asking Buildbucket for the build status
[00:16:53.277] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:53.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:99:0) after 1m0s
[00:17:53.318] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:99:0)
[00:17:53.318] Timer tick, asking Buildbucket for the build status
[00:17:53.488] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:53.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:100:0) after 1m0s
[00:18:53.551] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:100:0)
[00:18:53.551] Timer tick, asking Buildbucket for the build status
[00:18:53.608] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:53.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:101:0) after 1m0s
[00:19:53.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:101:0)
[00:19:53.756] Timer tick, asking Buildbucket for the build status
[00:19:53.893] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:53.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:102:0) after 1m0s
[00:20:53.996] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:102:0)
[00:20:53.996] Timer tick, asking Buildbucket for the build status
[00:20:54.144] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:54.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:103:0) after 1m0s
[00:21:54.202] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:103:0)
[00:21:54.202] Timer tick, asking Buildbucket for the build status
[00:21:54.503] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:54.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:104:0) after 1m0s
[00:22:54.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:104:0)
[00:22:54.528] Timer tick, asking Buildbucket for the build status
[00:22:54.606] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:54.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:105:0) after 1m0s
[00:23:54.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:105:0)
[00:23:54.732] Timer tick, asking Buildbucket for the build status
[00:23:54.805] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:54.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:106:0) after 1m0s
[00:24:54.918] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:106:0)
[00:24:54.918] Timer tick, asking Buildbucket for the build status
[00:24:54.991] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:54.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:107:0) after 1m0s
[00:25:55.011] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:107:0)
[00:25:55.011] Timer tick, asking Buildbucket for the build status
[00:25:55.172] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:55.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:108:0) after 1m0s
[00:26:55.222] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:108:0)
[00:26:55.222] Timer tick, asking Buildbucket for the build status
[00:26:55.281] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:55.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:109:0) after 1m0s
[00:27:55.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:109:0)
[00:27:55.304] Timer tick, asking Buildbucket for the build status
[00:27:55.363] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:55.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:110:0) after 1m0s
[00:28:55.599] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:110:0)
[00:28:55.599] Timer tick, asking Buildbucket for the build status
[00:28:55.664] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:55.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:111:0) after 1m0s
[00:29:55.828] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:111:0)
[00:29:55.828] Timer tick, asking Buildbucket for the build status
[00:29:55.895] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:55.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:112:0) after 1m0s
[00:30:57.977] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:112:0)
[00:30:57.977] Timer tick, asking Buildbucket for the build status
[00:30:58.119] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:58.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:113:0) after 1m0s
[00:31:58.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:113:0)
[00:31:58.302] Timer tick, asking Buildbucket for the build status
[00:31:58.611] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:58.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:114:0) after 1m0s
[00:32:58.706] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:114:0)
[00:32:58.706] Timer tick, asking Buildbucket for the build status
[00:32:58.767] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:58.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:115:0) after 1m0s
[00:33:58.934] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:115:0)
[00:33:58.934] Timer tick, asking Buildbucket for the build status
[00:33:59.028] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:59.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:116:0) after 1m0s
[00:34:59.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:116:0)
[00:34:59.173] Timer tick, asking Buildbucket for the build status
[00:34:59.267] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:59.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:117:0) after 1m0s
[00:35:59.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:117:0)
[00:35:59.471] Timer tick, asking Buildbucket for the build status
[00:35:59.777] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:59.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:118:0) after 1m0s
[00:36:59.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:118:0)
[00:36:59.817] Timer tick, asking Buildbucket for the build status
[00:37:00.105] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:00.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:119:0) after 1m0s
[00:38:00.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:119:0)
[00:38:00.145] Timer tick, asking Buildbucket for the build status
[00:38:00.409] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:00.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:120:0) after 1m0s
[00:39:00.459] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:120:0)
[00:39:00.459] Timer tick, asking Buildbucket for the build status
[00:39:00.683] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:00.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:121:0) after 1m0s
[00:40:02.091] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:121:0)
[00:40:02.091] Timer tick, asking Buildbucket for the build status
[00:40:02.206] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:02.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:122:0) after 1m0s
[00:41:03.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:122:0)
[00:41:03.249] Timer tick, asking Buildbucket for the build status
[00:41:03.369] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:03.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:123:0) after 1m0s
[00:42:03.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:123:0)
[00:42:03.401] Timer tick, asking Buildbucket for the build status
[00:42:03.546] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:03.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:124:0) after 1m0s
[00:43:03.623] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:124:0)
[00:43:03.623] Timer tick, asking Buildbucket for the build status
[00:43:03.736] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:03.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:125:0) after 1m0s
[00:44:03.876] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:125:0)
[00:44:03.876] Timer tick, asking Buildbucket for the build status
[00:44:04.163] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:04.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:126:0) after 1m0s
[00:45:04.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:126:0)
[00:45:04.237] Timer tick, asking Buildbucket for the build status
[00:45:04.620] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:04.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:127:0) after 1m0s
[00:46:04.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:127:0)
[00:46:04.699] Timer tick, asking Buildbucket for the build status
[00:46:04.813] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:04.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:128:0) after 1m0s
[00:47:04.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:128:0)
[00:47:04.933] Timer tick, asking Buildbucket for the build status
[00:47:04.991] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:04.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:129:0) after 1m0s
[00:48:05.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:129:0)
[00:48:05.006] Timer tick, asking Buildbucket for the build status
[00:48:05.133] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:05.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:130:0) after 1m0s
[00:49:05.201] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:130:0)
[00:49:05.201] Timer tick, asking Buildbucket for the build status
[00:49:05.290] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:05.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:131:0) after 1m0s
[00:50:05.310] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:131:0)
[00:50:05.310] Timer tick, asking Buildbucket for the build status
[00:50:05.384] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:05.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:132:0) after 1m0s
[00:51:05.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:132:0)
[00:51:05.413] Timer tick, asking Buildbucket for the build status
[00:51:05.528] Build 8867421562181675696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:05.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032851624153663344:133:0) after 1m0s
[00:51:23.986] Received PubSub notification, asking Buildbucket for the build status
[00:51:24.207] Build 8867421562181675696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:51:24.207] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1601772682431309",
"created_by": "project:chromium",
"created_ts": "1601764825608571",
"id": "8867421562181675696",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7af6cfd5967dd6528556c0024c467d91f5e5798d\"}}",
"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\": \"7af6cfd5967dd6528556c0024c467d91f5e5798d\", \"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-xqx6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 726, \"got_angle_revision\": \"500704812409749c6a6bd0e4fbddf03a60a07cb9\", \"got_dawn_revision\": \"ed3a93f690dedae1a0659d16b260e78cb4229fa1\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"7af6cfd5967dd6528556c0024c467d91f5e5798d\", \"got_revision_cp\": \"refs/heads/master@{#813539}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"b3c1e71eb1cb55ba78a3f884ab4e68ef1bd396da\", \"got_v8_revision\": \"5310c080792f473a43fbade25e0be422fdb7cdde\", \"got_v8_revision_cp\": \"refs/heads/8.8.4@{#1}\", \"got_webrtc_revision\": \"765d6c19b78942756927575481b8bbb6fbd835da\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32302}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7af6cfd5967dd6528556c0024c467d91f5e5798d\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-2-xqx6\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5564-e41b668\"], \"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": "1601764849637052",
"status": "COMPLETED",
"status_changed_ts": "1601772682812197",
"tags": [
"build_address:luci.chromium.ci/win-official/726",
"builder:win-official",
"buildset:commit/git/7af6cfd5967dd6528556c0024c467d91f5e5798d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7af6cfd5967dd6528556c0024c467d91f5e5798d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032851624153663344",
"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/8867421562181675696/+/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:4f097128a4d16410",
"user_agent:luci-scheduler"
],
"updated_ts": "1601772682816288",
"url": "https://ci.chromium.org/b/8867421562181675696",
"utcnow_ts": "1601772684195759"
}
}
[00:51:24.207] Invocation finished in 2h10m59.8632796s with status SUCCEEDED