[22:10:52.296] New invocation is queued and will start shortly
[22:10:53.368] Starting the invocation (attempt 1)
[22:10:53.406] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:10:53.406] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:10:53.406] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9037292733655098432",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a46e74ce552260fe64b36ecc65ad01c92e03dbf6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9037292733655098432",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/a46e74ce552260fe64b36ecc65ad01c92e03dbf6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a46e74ce552260fe64b36ecc65ad01c92e03dbf6",
"gitiles_ref:refs/heads/master"
]
}
[22:10:54.256] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1597529453496198",
"id": "8871862671729037488",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a46e74ce552260fe64b36ecc65ad01c92e03dbf6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1597529454006127",
"tags": [
"build_address:luci.chromium.ci/win32-official/320",
"builder:win32-official",
"buildset:commit/git/a46e74ce552260fe64b36ecc65ad01c92e03dbf6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a46e74ce552260fe64b36ecc65ad01c92e03dbf6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037292733655098432",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8871862671729037488/+/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": "1597529454006255",
"url": "https://ci.chromium.org/b/8871862671729037488",
"utcnow_ts": "1597529454248012"
}
}
[22:10:54.257] Task URL: https://ci.chromium.org/b/8871862671729037488
[22:10:54.257] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:2:0) after 1m0s
[22:11:01.359] Received PubSub notification, asking Buildbucket for the build status
[22:11:01.433] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:54.249] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:2:0)
[22:11:54.249] Timer tick, asking Buildbucket for the build status
[22:11:54.432] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:54.432] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:4:0) after 1m0s
[22:12:54.498] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:4:0)
[22:12:54.498] Timer tick, asking Buildbucket for the build status
[22:12:54.568] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:54.568] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:5:0) after 1m0s
[22:13:54.670] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:5:0)
[22:13:54.670] Timer tick, asking Buildbucket for the build status
[22:13:55.401] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:55.401] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:6:0) after 1m0s
[22:14:55.488] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:6:0)
[22:14:55.488] Timer tick, asking Buildbucket for the build status
[22:14:55.579] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:55.579] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:7:0) after 1m0s
[22:15:55.681] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:7:0)
[22:15:55.681] Timer tick, asking Buildbucket for the build status
[22:15:55.752] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:55.752] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:8:0) after 1m0s
[22:16:55.799] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:8:0)
[22:16:55.799] Timer tick, asking Buildbucket for the build status
[22:16:55.913] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:55.913] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:9:0) after 1m0s
[22:17:55.993] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:9:0)
[22:17:55.993] Timer tick, asking Buildbucket for the build status
[22:17:56.306] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:56.306] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:10:0) after 1m0s
[22:18:56.491] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:10:0)
[22:18:56.491] Timer tick, asking Buildbucket for the build status
[22:18:56.934] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:56.934] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:11:0) after 1m0s
[22:19:56.979] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:11:0)
[22:19:56.979] Timer tick, asking Buildbucket for the build status
[22:19:57.219] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:57.219] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:12:0) after 1m0s
[22:20:57.310] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:12:0)
[22:20:57.310] Timer tick, asking Buildbucket for the build status
[22:20:57.432] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:57.432] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:13:0) after 1m0s
[22:21:57.480] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:13:0)
[22:21:57.480] Timer tick, asking Buildbucket for the build status
[22:21:57.926] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:57.926] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:14:0) after 1m0s
[22:22:57.928] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:14:0)
[22:22:57.928] Timer tick, asking Buildbucket for the build status
[22:22:57.991] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:57.991] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:15:0) after 1m0s
[22:23:58.087] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:15:0)
[22:23:58.087] Timer tick, asking Buildbucket for the build status
[22:23:58.159] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:58.159] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:16:0) after 1m0s
[22:24:58.299] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:16:0)
[22:24:58.299] Timer tick, asking Buildbucket for the build status
[22:24:58.501] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:58.501] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:17:0) after 1m0s
[22:25:58.604] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:17:0)
[22:25:58.605] Timer tick, asking Buildbucket for the build status
[22:25:58.778] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:58.778] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:18:0) after 1m0s
[22:26:58.825] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:18:0)
[22:26:58.825] Timer tick, asking Buildbucket for the build status
[22:26:58.896] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:58.896] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:19:0) after 1m0s
[22:27:58.958] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:19:0)
[22:27:58.958] Timer tick, asking Buildbucket for the build status
[22:27:59.094] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:59.094] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:20:0) after 1m0s
[22:28:59.209] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:20:0)
[22:28:59.209] Timer tick, asking Buildbucket for the build status
[22:28:59.420] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:59.420] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:21:0) after 1m0s
[22:29:59.477] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:21:0)
[22:29:59.477] Timer tick, asking Buildbucket for the build status
[22:29:59.616] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:59.616] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:22:0) after 1m0s
[22:30:59.656] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:22:0)
[22:30:59.656] Timer tick, asking Buildbucket for the build status
[22:30:59.837] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:59.837] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:23:0) after 1m0s
[22:31:59.852] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:23:0)
[22:31:59.852] Timer tick, asking Buildbucket for the build status
[22:31:59.916] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:59.916] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:24:0) after 1m0s
[22:32:59.899] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:24:0)
[22:32:59.899] Timer tick, asking Buildbucket for the build status
[22:33:00.097] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:00.097] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:25:0) after 1m0s
[22:34:00.187] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:25:0)
[22:34:00.187] Timer tick, asking Buildbucket for the build status
[22:34:00.255] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:00.255] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:26:0) after 1m0s
[22:35:00.330] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:26:0)
[22:35:00.330] Timer tick, asking Buildbucket for the build status
[22:35:00.526] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:00.526] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:27:0) after 1m0s
[22:36:01.781] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:27:0)
[22:36:01.781] Timer tick, asking Buildbucket for the build status
[22:36:01.908] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:01.908] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:28:0) after 1m0s
[22:37:02.064] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:28:0)
[22:37:02.064] Timer tick, asking Buildbucket for the build status
[22:37:02.150] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:02.150] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:29:0) after 1m0s
[22:38:02.305] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:29:0)
[22:38:02.305] Timer tick, asking Buildbucket for the build status
[22:38:02.514] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:02.514] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:30:0) after 1m0s
[22:39:02.627] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:30:0)
[22:39:02.627] Timer tick, asking Buildbucket for the build status
[22:39:02.741] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:02.741] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:31:0) after 1m0s
[22:40:02.741] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:31:0)
[22:40:02.741] Timer tick, asking Buildbucket for the build status
[22:40:02.813] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:02.813] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:32:0) after 1m0s
[22:41:02.852] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:32:0)
[22:41:02.852] Timer tick, asking Buildbucket for the build status
[22:41:04.022] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:04.022] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:33:0) after 1m0s
[22:42:04.038] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:33:0)
[22:42:04.038] Timer tick, asking Buildbucket for the build status
[22:42:04.136] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:04.136] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:34:0) after 1m0s
[22:43:04.167] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:34:0)
[22:43:04.167] Timer tick, asking Buildbucket for the build status
[22:43:04.362] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:04.362] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:35:0) after 1m0s
[22:44:04.383] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:35:0)
[22:44:04.383] Timer tick, asking Buildbucket for the build status
[22:44:04.825] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:04.825] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:36:0) after 1m0s
[22:45:04.892] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:36:0)
[22:45:04.892] Timer tick, asking Buildbucket for the build status
[22:45:05.010] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:05.010] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:37:0) after 1m0s
[22:46:05.188] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:37:0)
[22:46:05.188] Timer tick, asking Buildbucket for the build status
[22:46:05.451] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:05.451] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:38:0) after 1m0s
[22:47:05.553] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:38:0)
[22:47:05.553] Timer tick, asking Buildbucket for the build status
[22:47:05.642] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:05.642] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:39:0) after 1m0s
[22:48:05.666] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:39:0)
[22:48:05.666] Timer tick, asking Buildbucket for the build status
[22:48:05.909] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:05.909] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:40:0) after 1m0s
[22:49:06.032] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:40:0)
[22:49:06.032] Timer tick, asking Buildbucket for the build status
[22:49:06.159] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:06.160] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:41:0) after 1m0s
[22:50:06.187] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:41:0)
[22:50:06.187] Timer tick, asking Buildbucket for the build status
[22:50:06.263] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:06.263] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:42:0) after 1m0s
[22:51:06.456] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:42:0)
[22:51:06.456] Timer tick, asking Buildbucket for the build status
[22:51:06.617] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:06.617] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:43:0) after 1m0s
[22:52:06.734] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:43:0)
[22:52:06.734] Timer tick, asking Buildbucket for the build status
[22:52:06.827] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:06.827] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:44:0) after 1m0s
[22:53:06.991] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:44:0)
[22:53:06.991] Timer tick, asking Buildbucket for the build status
[22:53:07.059] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:07.059] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:45:0) after 1m0s
[22:54:07.199] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:45:0)
[22:54:07.199] Timer tick, asking Buildbucket for the build status
[22:54:07.423] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:07.423] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:46:0) after 1m0s
[22:55:07.544] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:46:0)
[22:55:07.544] Timer tick, asking Buildbucket for the build status
[22:55:07.668] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:07.668] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:47:0) after 1m0s
[22:56:07.773] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:47:0)
[22:56:07.773] Timer tick, asking Buildbucket for the build status
[22:56:08.061] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:08.061] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:48:0) after 1m0s
[22:57:08.354] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:48:0)
[22:57:08.354] Timer tick, asking Buildbucket for the build status
[22:57:08.421] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:08.421] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:49:0) after 1m0s
[22:58:08.535] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:49:0)
[22:58:08.535] Timer tick, asking Buildbucket for the build status
[22:58:08.878] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:08.878] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:50:0) after 1m0s
[22:59:08.913] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:50:0)
[22:59:08.913] Timer tick, asking Buildbucket for the build status
[22:59:09.026] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:09.026] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:51:0) after 1m0s
[23:00:09.064] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:51:0)
[23:00:09.064] Timer tick, asking Buildbucket for the build status
[23:00:09.136] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:09.136] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:52:0) after 1m0s
[23:01:09.437] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:52:0)
[23:01:09.437] Timer tick, asking Buildbucket for the build status
[23:01:09.749] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:09.749] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:53:0) after 1m0s
[23:02:10.057] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:53:0)
[23:02:10.057] Timer tick, asking Buildbucket for the build status
[23:02:10.143] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:10.143] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:54:0) after 1m0s
[23:03:10.219] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:54:0)
[23:03:10.219] Timer tick, asking Buildbucket for the build status
[23:03:10.294] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:10.294] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:55:0) after 1m0s
[23:04:10.305] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:55:0)
[23:04:10.305] Timer tick, asking Buildbucket for the build status
[23:04:10.418] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:10.418] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:56:0) after 1m0s
[23:05:10.501] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:56:0)
[23:05:10.501] Timer tick, asking Buildbucket for the build status
[23:05:10.608] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:10.608] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:57:0) after 1m0s
[23:06:10.612] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:57:0)
[23:06:10.612] Timer tick, asking Buildbucket for the build status
[23:06:10.730] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:10.730] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:58:0) after 1m0s
[23:07:10.776] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:58:0)
[23:07:10.776] Timer tick, asking Buildbucket for the build status
[23:07:10.867] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:10.867] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:59:0) after 1m0s
[23:08:11.039] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:59:0)
[23:08:11.040] Timer tick, asking Buildbucket for the build status
[23:08:11.139] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:11.139] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:60:0) after 1m0s
[23:09:11.281] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:60:0)
[23:09:11.281] Timer tick, asking Buildbucket for the build status
[23:09:11.500] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:11.500] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:61:0) after 1m0s
[23:10:11.676] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:61:0)
[23:10:11.676] Timer tick, asking Buildbucket for the build status
[23:10:11.897] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:11.897] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:62:0) after 1m0s
[23:11:11.908] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:62:0)
[23:11:11.908] Timer tick, asking Buildbucket for the build status
[23:11:12.231] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:12.231] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:63:0) after 1m0s
[23:12:12.277] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:63:0)
[23:12:12.277] Timer tick, asking Buildbucket for the build status
[23:12:12.352] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:12.352] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:64:0) after 1m0s
[23:13:12.403] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:64:0)
[23:13:12.403] Timer tick, asking Buildbucket for the build status
[23:13:12.467] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:12.467] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:65:0) after 1m0s
[23:14:12.562] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:65:0)
[23:14:12.562] Timer tick, asking Buildbucket for the build status
[23:14:12.700] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:12.700] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:66:0) after 1m0s
[23:15:12.719] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:66:0)
[23:15:12.719] Timer tick, asking Buildbucket for the build status
[23:15:12.930] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:12.930] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:67:0) after 1m0s
[23:16:13.054] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:67:0)
[23:16:13.054] Timer tick, asking Buildbucket for the build status
[23:16:13.337] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:13.337] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:68:0) after 1m0s
[23:17:13.408] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:68:0)
[23:17:13.408] Timer tick, asking Buildbucket for the build status
[23:17:13.619] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:13.619] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:69:0) after 1m0s
[23:18:13.739] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:69:0)
[23:18:13.739] Timer tick, asking Buildbucket for the build status
[23:18:13.815] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:13.815] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:70:0) after 1m0s
[23:19:13.846] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:70:0)
[23:19:13.846] Timer tick, asking Buildbucket for the build status
[23:19:13.936] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:13.936] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:71:0) after 1m0s
[23:20:13.974] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:71:0)
[23:20:13.974] Timer tick, asking Buildbucket for the build status
[23:20:14.118] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:14.118] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:72:0) after 1m0s
[23:21:14.147] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:72:0)
[23:21:14.147] Timer tick, asking Buildbucket for the build status
[23:21:14.280] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:14.280] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:73:0) after 1m0s
[23:22:14.428] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:73:0)
[23:22:14.428] Timer tick, asking Buildbucket for the build status
[23:22:14.534] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:14.534] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:74:0) after 1m0s
[23:23:14.661] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:74:0)
[23:23:14.661] Timer tick, asking Buildbucket for the build status
[23:23:14.811] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:14.811] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:75:0) after 1m0s
[23:24:14.840] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:75:0)
[23:24:14.840] Timer tick, asking Buildbucket for the build status
[23:24:14.976] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:14.976] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:76:0) after 1m0s
[23:25:15.005] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:76:0)
[23:25:15.005] Timer tick, asking Buildbucket for the build status
[23:25:15.098] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:15.098] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:77:0) after 1m0s
[23:26:15.156] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:77:0)
[23:26:15.156] Timer tick, asking Buildbucket for the build status
[23:26:15.303] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:15.303] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:78:0) after 1m0s
[23:27:15.422] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:78:0)
[23:27:15.422] Timer tick, asking Buildbucket for the build status
[23:27:15.717] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:15.717] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:79:0) after 1m0s
[23:28:15.817] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:79:0)
[23:28:15.817] Timer tick, asking Buildbucket for the build status
[23:28:15.898] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:15.898] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:80:0) after 1m0s
[23:29:15.934] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:80:0)
[23:29:15.934] Timer tick, asking Buildbucket for the build status
[23:29:16.014] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:16.014] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:81:0) after 1m0s
[23:30:16.043] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:81:0)
[23:30:16.043] Timer tick, asking Buildbucket for the build status
[23:30:17.065] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:17.065] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:82:0) after 1m0s
[23:31:17.095] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:82:0)
[23:31:17.095] Timer tick, asking Buildbucket for the build status
[23:31:17.296] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:17.296] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:83:0) after 1m0s
[23:32:17.372] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:83:0)
[23:32:17.372] Timer tick, asking Buildbucket for the build status
[23:32:17.467] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:17.467] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:84:0) after 1m0s
[23:33:17.539] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:84:0)
[23:33:17.539] Timer tick, asking Buildbucket for the build status
[23:33:17.913] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:17.913] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:85:0) after 1m0s
[23:34:17.969] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:85:0)
[23:34:17.969] Timer tick, asking Buildbucket for the build status
[23:34:18.050] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:18.050] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:86:0) after 1m0s
[23:35:18.153] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:86:0)
[23:35:18.153] Timer tick, asking Buildbucket for the build status
[23:35:18.239] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:18.239] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:87:0) after 1m0s
[23:36:18.363] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:87:0)
[23:36:18.363] Timer tick, asking Buildbucket for the build status
[23:36:18.729] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:18.729] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:88:0) after 1m0s
[23:37:18.755] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:88:0)
[23:37:18.755] Timer tick, asking Buildbucket for the build status
[23:37:20.513] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:20.513] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:89:0) after 1m0s
[23:38:20.594] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:89:0)
[23:38:20.594] Timer tick, asking Buildbucket for the build status
[23:38:20.665] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:20.665] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:90:0) after 1m0s
[23:39:20.713] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:90:0)
[23:39:20.713] Timer tick, asking Buildbucket for the build status
[23:39:20.912] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:20.912] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:91:0) after 1m0s
[23:40:20.951] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:91:0)
[23:40:20.951] Timer tick, asking Buildbucket for the build status
[23:40:22.331] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:22.331] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:92:0) after 1m0s
[23:41:22.429] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:92:0)
[23:41:22.429] Timer tick, asking Buildbucket for the build status
[23:41:22.734] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:22.734] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:93:0) after 1m0s
[23:42:22.767] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:93:0)
[23:42:22.767] Timer tick, asking Buildbucket for the build status
[23:42:22.864] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:22.864] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:94:0) after 1m0s
[23:43:22.888] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:94:0)
[23:43:22.888] Timer tick, asking Buildbucket for the build status
[23:43:22.966] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:22.966] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:95:0) after 1m0s
[23:44:23.014] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:95:0)
[23:44:23.014] Timer tick, asking Buildbucket for the build status
[23:44:23.151] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:23.151] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:96:0) after 1m0s
[23:45:23.251] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:96:0)
[23:45:23.251] Timer tick, asking Buildbucket for the build status
[23:45:23.341] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:23.341] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:97:0) after 1m0s
[23:46:23.421] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:97:0)
[23:46:23.421] Timer tick, asking Buildbucket for the build status
[23:46:23.544] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:23.544] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:98:0) after 1m0s
[23:47:23.532] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:98:0)
[23:47:23.532] Timer tick, asking Buildbucket for the build status
[23:47:23.594] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:23.594] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:99:0) after 1m0s
[23:48:23.636] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:99:0)
[23:48:23.636] Timer tick, asking Buildbucket for the build status
[23:48:23.727] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:23.727] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:100:0) after 1m0s
[23:49:23.763] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:100:0)
[23:49:23.763] Timer tick, asking Buildbucket for the build status
[23:49:24.096] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:24.097] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:101:0) after 1m0s
[23:50:24.228] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:101:0)
[23:50:24.228] Timer tick, asking Buildbucket for the build status
[23:50:24.293] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:24.293] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:102:0) after 1m0s
[23:51:24.344] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:102:0)
[23:51:24.344] Timer tick, asking Buildbucket for the build status
[23:51:24.606] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:24.606] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:103:0) after 1m0s
[23:52:24.647] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:103:0)
[23:52:24.647] Timer tick, asking Buildbucket for the build status
[23:52:24.714] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:24.714] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:104:0) after 1m0s
[23:53:24.746] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:104:0)
[23:53:24.746] Timer tick, asking Buildbucket for the build status
[23:53:24.841] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:24.841] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:105:0) after 1m0s
[23:54:24.912] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:105:0)
[23:54:24.912] Timer tick, asking Buildbucket for the build status
[23:54:24.979] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:24.979] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:106:0) after 1m0s
[23:55:25.143] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:106:0)
[23:55:25.143] Timer tick, asking Buildbucket for the build status
[23:55:25.241] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:25.241] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:107:0) after 1m0s
[23:56:25.458] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:107:0)
[23:56:25.458] Timer tick, asking Buildbucket for the build status
[23:56:25.573] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:25.573] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:108:0) after 1m0s
[23:57:25.687] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:108:0)
[23:57:25.687] Timer tick, asking Buildbucket for the build status
[23:57:25.787] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:25.787] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:109:0) after 1m0s
[23:58:26.180] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:109:0)
[23:58:26.180] Timer tick, asking Buildbucket for the build status
[23:58:26.302] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:26.302] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:110:0) after 1m0s
[23:59:26.798] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:110:0)
[23:59:26.798] Timer tick, asking Buildbucket for the build status
[23:59:26.882] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:26.882] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:111:0) after 1m0s
[00:00:27.092] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:111:0)
[00:00:27.092] Timer tick, asking Buildbucket for the build status
[00:00:27.192] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:27.192] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:112:0) after 1m0s
[00:01:27.510] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:112:0)
[00:01:27.510] Timer tick, asking Buildbucket for the build status
[00:01:27.660] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:27.660] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:113:0) after 1m0s
[00:02:27.686] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:113:0)
[00:02:27.686] Timer tick, asking Buildbucket for the build status
[00:02:27.877] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:27.877] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:114:0) after 1m0s
[00:03:27.925] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:114:0)
[00:03:27.925] Timer tick, asking Buildbucket for the build status
[00:03:28.022] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:28.022] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:115:0) after 1m0s
[00:04:28.182] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:115:0)
[00:04:28.182] Timer tick, asking Buildbucket for the build status
[00:04:28.274] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:28.274] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:116:0) after 1m0s
[00:05:28.317] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:116:0)
[00:05:28.317] Timer tick, asking Buildbucket for the build status
[00:05:28.440] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:28.440] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:117:0) after 1m0s
[00:06:28.567] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:117:0)
[00:06:28.567] Timer tick, asking Buildbucket for the build status
[00:06:28.982] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:28.982] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:118:0) after 1m0s
[00:07:29.039] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:118:0)
[00:07:29.039] Timer tick, asking Buildbucket for the build status
[00:07:29.119] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:29.119] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:119:0) after 1m0s
[00:08:29.331] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:119:0)
[00:08:29.331] Timer tick, asking Buildbucket for the build status
[00:08:29.410] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:29.410] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:120:0) after 1m0s
[00:09:29.718] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:120:0)
[00:09:29.718] Timer tick, asking Buildbucket for the build status
[00:09:29.792] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:29.792] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:121:0) after 1m0s
[00:10:29.967] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:121:0)
[00:10:29.967] Timer tick, asking Buildbucket for the build status
[00:10:30.276] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:30.276] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:122:0) after 1m0s
[00:11:30.482] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:122:0)
[00:11:30.482] Timer tick, asking Buildbucket for the build status
[00:11:31.056] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:31.056] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:123:0) after 1m0s
[00:12:31.780] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:123:0)
[00:12:31.780] Timer tick, asking Buildbucket for the build status
[00:12:31.909] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:31.909] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:124:0) after 1m0s
[00:13:31.938] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:124:0)
[00:13:31.938] Timer tick, asking Buildbucket for the build status
[00:13:32.029] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:32.029] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:125:0) after 1m0s
[00:14:32.079] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:125:0)
[00:14:32.079] Timer tick, asking Buildbucket for the build status
[00:14:32.150] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:32.150] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:126:0) after 1m0s
[00:15:32.264] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:126:0)
[00:15:32.264] Timer tick, asking Buildbucket for the build status
[00:15:32.395] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:32.395] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:127:0) after 1m0s
[00:16:32.421] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:127:0)
[00:16:32.421] Timer tick, asking Buildbucket for the build status
[00:16:32.539] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:32.539] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:128:0) after 1m0s
[00:17:32.570] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:128:0)
[00:17:32.570] Timer tick, asking Buildbucket for the build status
[00:17:32.643] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:32.643] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:129:0) after 1m0s
[00:18:32.629] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:129:0)
[00:18:32.629] Timer tick, asking Buildbucket for the build status
[00:18:32.689] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:32.689] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:130:0) after 1m0s
[00:19:32.720] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:130:0)
[00:19:32.720] Timer tick, asking Buildbucket for the build status
[00:19:32.843] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:32.843] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:131:0) after 1m0s
[00:20:33.077] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:131:0)
[00:20:33.077] Timer tick, asking Buildbucket for the build status
[00:20:33.269] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:33.269] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:132:0) after 1m0s
[00:21:33.390] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:132:0)
[00:21:33.390] Timer tick, asking Buildbucket for the build status
[00:21:33.921] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:33.921] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:133:0) after 1m0s
[00:22:33.941] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:133:0)
[00:22:33.941] Timer tick, asking Buildbucket for the build status
[00:22:35.106] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:35.106] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:134:0) after 1m0s
[00:23:35.300] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:134:0)
[00:23:35.300] Timer tick, asking Buildbucket for the build status
[00:23:35.440] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:35.440] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:135:0) after 1m0s
[00:24:35.609] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:135:0)
[00:24:35.609] Timer tick, asking Buildbucket for the build status
[00:24:35.705] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:35.705] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:136:0) after 1m0s
[00:25:35.733] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:136:0)
[00:25:35.733] Timer tick, asking Buildbucket for the build status
[00:25:35.922] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:35.922] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:137:0) after 1m0s
[00:26:35.959] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:137:0)
[00:26:35.959] Timer tick, asking Buildbucket for the build status
[00:26:36.136] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:36.136] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:138:0) after 1m0s
[00:27:36.389] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:138:0)
[00:27:36.389] Timer tick, asking Buildbucket for the build status
[00:27:36.456] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:36.456] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:139:0) after 1m0s
[00:28:36.663] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:139:0)
[00:28:36.663] Timer tick, asking Buildbucket for the build status
[00:28:36.811] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:36.811] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:140:0) after 1m0s
[00:29:36.846] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:140:0)
[00:29:36.846] Timer tick, asking Buildbucket for the build status
[00:29:36.921] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:36.921] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:141:0) after 1m0s
[00:30:36.952] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:141:0)
[00:30:36.952] Timer tick, asking Buildbucket for the build status
[00:30:37.090] Build 8871862671729037488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:37.090] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037292733655098432:142:0) after 1m0s
[00:31:04.147] Received PubSub notification, asking Buildbucket for the build status
[00:31:04.283] Build 8871862671729037488: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:31:04.283] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1597537861991974",
"created_by": "project:chromium",
"created_ts": "1597529453496198",
"id": "8871862671729037488",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a46e74ce552260fe64b36ecc65ad01c92e03dbf6\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-7-vpul\", \"branch\": \"refs/heads/master\", \"buildername\": \"win32-official\", \"buildnumber\": 320, \"got_angle_revision\": \"9ae6d7f2ee9e12b707483dc9cadd7c3529595016\", \"got_dawn_revision\": \"5e9b29fab92d6d79125555ea0d84afde9ace908b\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"a46e74ce552260fe64b36ecc65ad01c92e03dbf6\", \"got_revision_cp\": \"refs/heads/master@{#798463}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"abe07b9438553f741e776dedb59a2d4b502a9983\", \"got_v8_revision\": \"26d2954fbe8d1d68f3daf3fc59f1826dd91d6383\", \"got_v8_revision_cp\": \"refs/heads/8.6.384@{#1}\", \"got_webrtc_revision\": \"383f2cfca4a1ebd9626134101fe70d5a716b2d92\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31921}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a46e74ce552260fe64b36ecc65ad01c92e03dbf6\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f6b49936a2965b3cdad8f63ce13313d0c4b82119a9acfffbfe199e91c26d0c88_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-7-vpul\"], \"image\": [\"chrome-win10-1703-20073100-2eb11ddbc6e\"], \"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\": [\"5488-fc78d20\"], \"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": "1597529457377262",
"status": "COMPLETED",
"status_changed_ts": "1597537862724929",
"tags": [
"build_address:luci.chromium.ci/win32-official/320",
"builder:win32-official",
"buildset:commit/git/a46e74ce552260fe64b36ecc65ad01c92e03dbf6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a46e74ce552260fe64b36ecc65ad01c92e03dbf6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037292733655098432",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8871862671729037488/+/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:4e0cfe82598fcf10",
"user_agent:luci-scheduler"
],
"updated_ts": "1597537862811000",
"url": "https://ci.chromium.org/b/8871862671729037488",
"utcnow_ts": "1597537864272392"
}
}
[00:31:04.283] Invocation finished in 2h20m12.006467973s with status SUCCEEDED