[23:34:34.601] New invocation is queued and will start shortly
[23:34:35.669] Starting the invocation (attempt 1)
[23:34:35.709] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:34:35.709] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:34:35.709] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9039280600644251776",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"24eb259522000da909bf9e65c761d472abe925b9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9039280600644251776",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/24eb259522000da909bf9e65c761d472abe925b9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/24eb259522000da909bf9e65c761d472abe925b9",
"gitiles_ref:refs/heads/master"
]
}
[23:34:37.154] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1595633676320791",
"id": "8873850538177347904",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"24eb259522000da909bf9e65c761d472abe925b9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1595633676859964",
"tags": [
"build_address:luci.chromium.ci/win-official/165",
"builder:win-official",
"buildset:commit/git/24eb259522000da909bf9e65c761d472abe925b9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/24eb259522000da909bf9e65c761d472abe925b9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039280600644251776",
"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/8873850538177347904/+/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": "1595633676860116",
"url": "https://ci.chromium.org/b/8873850538177347904",
"utcnow_ts": "1595633677141252"
}
}
[23:34:37.154] Task URL: https://ci.chromium.org/b/8873850538177347904
[23:34:37.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:2:0) after 1m0s
[23:34:59.184] Received PubSub notification, asking Buildbucket for the build status
[23:34:59.302] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:37.776] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:2:0)
[23:35:37.776] Timer tick, asking Buildbucket for the build status
[23:35:37.856] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:37.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:4:0) after 1m0s
[23:36:38.177] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:4:0)
[23:36:38.177] Timer tick, asking Buildbucket for the build status
[23:36:38.301] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:38.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:5:0) after 1m0s
[23:37:38.428] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:5:0)
[23:37:38.428] Timer tick, asking Buildbucket for the build status
[23:37:38.782] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:38.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:6:0) after 1m0s
[23:38:38.866] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:6:0)
[23:38:38.866] Timer tick, asking Buildbucket for the build status
[23:38:39.015] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:39.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:7:0) after 1m0s
[23:39:39.178] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:7:0)
[23:39:39.178] Timer tick, asking Buildbucket for the build status
[23:39:39.292] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:39.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:8:0) after 1m0s
[23:40:39.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:8:0)
[23:40:39.371] Timer tick, asking Buildbucket for the build status
[23:40:39.575] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:39.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:9:0) after 1m0s
[23:41:39.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:9:0)
[23:41:39.720] Timer tick, asking Buildbucket for the build status
[23:41:39.851] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:39.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:10:0) after 1m0s
[23:42:39.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:10:0)
[23:42:39.873] Timer tick, asking Buildbucket for the build status
[23:42:39.976] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:39.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:11:0) after 1m0s
[23:43:39.995] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:11:0)
[23:43:39.995] Timer tick, asking Buildbucket for the build status
[23:43:40.191] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:40.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:12:0) after 1m0s
[23:44:40.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:12:0)
[23:44:40.338] Timer tick, asking Buildbucket for the build status
[23:44:40.642] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:40.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:13:0) after 1m0s
[23:45:41.265] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:13:0)
[23:45:41.265] Timer tick, asking Buildbucket for the build status
[23:45:41.362] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:41.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:14:0) after 1m0s
[23:46:43.773] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:14:0)
[23:46:43.773] Timer tick, asking Buildbucket for the build status
[23:46:43.927] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:43.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:15:0) after 1m0s
[23:47:43.948] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:15:0)
[23:47:43.948] Timer tick, asking Buildbucket for the build status
[23:47:44.079] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:44.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:16:0) after 1m0s
[23:48:44.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:16:0)
[23:48:44.104] Timer tick, asking Buildbucket for the build status
[23:48:44.171] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:44.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:17:0) after 1m0s
[23:49:44.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:17:0)
[23:49:44.274] Timer tick, asking Buildbucket for the build status
[23:49:44.804] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:44.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:18:0) after 1m0s
[23:50:44.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:18:0)
[23:50:44.921] Timer tick, asking Buildbucket for the build status
[23:50:46.170] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:46.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:19:0) after 1m0s
[23:51:46.191] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:19:0)
[23:51:46.191] Timer tick, asking Buildbucket for the build status
[23:51:46.505] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:46.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:20:0) after 1m0s
[23:52:46.527] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:20:0)
[23:52:46.527] Timer tick, asking Buildbucket for the build status
[23:52:46.627] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:46.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:21:0) after 1m0s
[23:53:46.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:21:0)
[23:53:46.645] Timer tick, asking Buildbucket for the build status
[23:53:46.811] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:46.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:22:0) after 1m0s
[23:54:46.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:22:0)
[23:54:46.896] Timer tick, asking Buildbucket for the build status
[23:54:48.433] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:48.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:23:0) after 1m0s
[23:55:48.500] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:23:0)
[23:55:48.500] Timer tick, asking Buildbucket for the build status
[23:55:48.575] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:48.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:24:0) after 1m0s
[23:56:48.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:24:0)
[23:56:48.666] Timer tick, asking Buildbucket for the build status
[23:56:48.760] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:48.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:25:0) after 1m0s
[23:57:48.791] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:25:0)
[23:57:48.791] Timer tick, asking Buildbucket for the build status
[23:57:48.913] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:48.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:26:0) after 1m0s
[23:58:49.017] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:26:0)
[23:58:49.017] Timer tick, asking Buildbucket for the build status
[23:58:49.255] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:49.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:27:0) after 1m0s
[23:59:49.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:27:0)
[23:59:49.268] Timer tick, asking Buildbucket for the build status
[23:59:49.378] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:49.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:28:0) after 1m0s
[00:00:49.479] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:28:0)
[00:00:49.479] Timer tick, asking Buildbucket for the build status
[00:00:49.549] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:49.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:29:0) after 1m0s
[00:01:49.609] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:29:0)
[00:01:49.609] Timer tick, asking Buildbucket for the build status
[00:01:49.662] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:49.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:30:0) after 1m0s
[00:02:49.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:30:0)
[00:02:49.687] Timer tick, asking Buildbucket for the build status
[00:02:49.755] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:49.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:31:0) after 1m0s
[00:03:49.800] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:31:0)
[00:03:49.800] Timer tick, asking Buildbucket for the build status
[00:03:51.116] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:51.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:32:0) after 1m0s
[00:04:51.194] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:32:0)
[00:04:51.194] Timer tick, asking Buildbucket for the build status
[00:04:51.300] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:51.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:33:0) after 1m0s
[00:05:51.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:33:0)
[00:05:51.298] Timer tick, asking Buildbucket for the build status
[00:05:51.371] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:51.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:34:0) after 1m0s
[00:06:51.428] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:34:0)
[00:06:51.428] Timer tick, asking Buildbucket for the build status
[00:06:51.564] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:51.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:35:0) after 1m0s
[00:07:51.587] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:35:0)
[00:07:51.587] Timer tick, asking Buildbucket for the build status
[00:07:51.647] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:51.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:36:0) after 1m0s
[00:08:51.748] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:36:0)
[00:08:51.748] Timer tick, asking Buildbucket for the build status
[00:08:52.295] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:52.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:37:0) after 1m0s
[00:09:52.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:37:0)
[00:09:52.327] Timer tick, asking Buildbucket for the build status
[00:09:53.382] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:53.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:38:0) after 1m0s
[00:10:53.487] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:38:0)
[00:10:53.487] Timer tick, asking Buildbucket for the build status
[00:10:53.576] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:53.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:39:0) after 1m0s
[00:11:53.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:39:0)
[00:11:53.687] Timer tick, asking Buildbucket for the build status
[00:11:53.965] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:53.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:40:0) after 1m0s
[00:12:53.970] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:40:0)
[00:12:53.970] Timer tick, asking Buildbucket for the build status
[00:12:54.331] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:54.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:41:0) after 1m0s
[00:13:54.440] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:41:0)
[00:13:54.440] Timer tick, asking Buildbucket for the build status
[00:13:54.599] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:54.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:42:0) after 1m0s
[00:14:54.653] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:42:0)
[00:14:54.653] Timer tick, asking Buildbucket for the build status
[00:14:54.799] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:54.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:43:0) after 1m0s
[00:15:54.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:43:0)
[00:15:54.925] Timer tick, asking Buildbucket for the build status
[00:15:55.033] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:55.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:44:0) after 1m0s
[00:16:55.183] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:44:0)
[00:16:55.184] Timer tick, asking Buildbucket for the build status
[00:16:55.314] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:55.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:45:0) after 1m0s
[00:17:55.375] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:45:0)
[00:17:55.375] Timer tick, asking Buildbucket for the build status
[00:17:55.505] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:55.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:46:0) after 1m0s
[00:18:55.536] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:46:0)
[00:18:55.536] Timer tick, asking Buildbucket for the build status
[00:18:55.612] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:55.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:47:0) after 1m0s
[00:19:55.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:47:0)
[00:19:55.619] Timer tick, asking Buildbucket for the build status
[00:19:55.735] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:55.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:48:0) after 1m0s
[00:20:55.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:48:0)
[00:20:55.863] Timer tick, asking Buildbucket for the build status
[00:20:55.942] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:55.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:49:0) after 1m0s
[00:21:56.044] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:49:0)
[00:21:56.044] Timer tick, asking Buildbucket for the build status
[00:21:56.668] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:56.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:50:0) after 1m0s
[00:22:56.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:50:0)
[00:22:56.734] Timer tick, asking Buildbucket for the build status
[00:22:58.851] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:58.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:51:0) after 1m0s
[00:23:58.943] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:51:0)
[00:23:58.943] Timer tick, asking Buildbucket for the build status
[00:23:59.267] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:59.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:52:0) after 1m0s
[00:24:59.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:52:0)
[00:24:59.361] Timer tick, asking Buildbucket for the build status
[00:24:59.541] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:59.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:53:0) after 1m0s
[00:25:59.611] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:53:0)
[00:25:59.611] Timer tick, asking Buildbucket for the build status
[00:25:59.830] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:59.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:54:0) after 1m0s
[00:26:59.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:54:0)
[00:26:59.864] Timer tick, asking Buildbucket for the build status
[00:27:00.132] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:00.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:55:0) after 1m0s
[00:28:00.168] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:55:0)
[00:28:00.168] Timer tick, asking Buildbucket for the build status
[00:28:02.975] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:02.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:56:0) after 1m0s
[00:29:03.221] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:56:0)
[00:29:03.221] Timer tick, asking Buildbucket for the build status
[00:29:03.373] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:03.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:57:0) after 1m0s
[00:30:03.467] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:57:0)
[00:30:03.467] Timer tick, asking Buildbucket for the build status
[00:30:03.779] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:03.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:58:0) after 1m0s
[00:31:03.879] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:58:0)
[00:31:03.879] Timer tick, asking Buildbucket for the build status
[00:31:03.963] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:03.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:59:0) after 1m0s
[00:32:04.027] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:59:0)
[00:32:04.027] Timer tick, asking Buildbucket for the build status
[00:32:04.227] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:04.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:60:0) after 1m0s
[00:33:04.294] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:60:0)
[00:33:04.294] Timer tick, asking Buildbucket for the build status
[00:33:04.391] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:04.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:61:0) after 1m0s
[00:34:04.422] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:61:0)
[00:34:04.422] Timer tick, asking Buildbucket for the build status
[00:34:04.738] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:04.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:62:0) after 1m0s
[00:35:04.795] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:62:0)
[00:35:04.795] Timer tick, asking Buildbucket for the build status
[00:35:04.955] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:04.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:63:0) after 1m0s
[00:36:05.028] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:63:0)
[00:36:05.028] Timer tick, asking Buildbucket for the build status
[00:36:05.543] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:05.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:64:0) after 1m0s
[00:37:05.631] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:64:0)
[00:37:05.631] Timer tick, asking Buildbucket for the build status
[00:37:05.717] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:05.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:65:0) after 1m0s
[00:38:05.762] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:65:0)
[00:38:05.762] Timer tick, asking Buildbucket for the build status
[00:38:07.439] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:07.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:66:0) after 1m0s
[00:39:07.644] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:66:0)
[00:39:07.644] Timer tick, asking Buildbucket for the build status
[00:39:07.748] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:07.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:67:0) after 1m0s
[00:40:07.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:67:0)
[00:40:07.803] Timer tick, asking Buildbucket for the build status
[00:40:08.004] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:08.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:68:0) after 1m0s
[00:41:08.097] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:68:0)
[00:41:08.097] Timer tick, asking Buildbucket for the build status
[00:41:08.204] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:08.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:69:0) after 1m0s
[00:42:08.315] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:69:0)
[00:42:08.315] Timer tick, asking Buildbucket for the build status
[00:42:08.431] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:08.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:70:0) after 1m0s
[00:43:08.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:70:0)
[00:43:08.429] Timer tick, asking Buildbucket for the build status
[00:43:08.627] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:08.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:71:0) after 1m0s
[00:44:08.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:71:0)
[00:44:08.621] Timer tick, asking Buildbucket for the build status
[00:44:08.708] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:08.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:72:0) after 1m0s
[00:45:08.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:72:0)
[00:45:08.784] Timer tick, asking Buildbucket for the build status
[00:45:09.415] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:09.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:73:0) after 1m0s
[00:46:09.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:73:0)
[00:46:09.483] Timer tick, asking Buildbucket for the build status
[00:46:09.674] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:09.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:74:0) after 1m0s
[00:47:09.708] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:74:0)
[00:47:09.708] Timer tick, asking Buildbucket for the build status
[00:47:09.770] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:09.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:75:0) after 1m0s
[00:48:09.798] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:75:0)
[00:48:09.798] Timer tick, asking Buildbucket for the build status
[00:48:09.884] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:09.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:76:0) after 1m0s
[00:49:09.910] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:76:0)
[00:49:09.910] Timer tick, asking Buildbucket for the build status
[00:49:10.017] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:10.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:77:0) after 1m0s
[00:50:10.109] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:77:0)
[00:50:10.109] Timer tick, asking Buildbucket for the build status
[00:50:10.839] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:10.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:78:0) after 1m0s
[00:51:10.885] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:78:0)
[00:51:10.885] Timer tick, asking Buildbucket for the build status
[00:51:10.976] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:10.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:79:0) after 1m0s
[00:52:11.062] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:79:0)
[00:52:11.062] Timer tick, asking Buildbucket for the build status
[00:52:11.438] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:11.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:80:0) after 1m0s
[00:53:11.493] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:80:0)
[00:53:11.493] Timer tick, asking Buildbucket for the build status
[00:53:11.828] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:11.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:81:0) after 1m0s
[00:54:11.886] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:81:0)
[00:54:11.886] Timer tick, asking Buildbucket for the build status
[00:54:12.186] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:12.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:82:0) after 1m0s
[00:55:12.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:82:0)
[00:55:12.249] Timer tick, asking Buildbucket for the build status
[00:55:12.366] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:12.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:83:0) after 1m0s
[00:56:12.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:83:0)
[00:56:12.383] Timer tick, asking Buildbucket for the build status
[00:56:12.514] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:12.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:84:0) after 1m0s
[00:57:12.598] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:84:0)
[00:57:12.598] Timer tick, asking Buildbucket for the build status
[00:57:12.767] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:12.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:85:0) after 1m0s
[00:58:12.869] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:85:0)
[00:58:12.869] Timer tick, asking Buildbucket for the build status
[00:58:12.935] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:12.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:86:0) after 1m0s
[00:59:13.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:86:0)
[00:59:13.038] Timer tick, asking Buildbucket for the build status
[00:59:13.265] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:13.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:87:0) after 1m0s
[01:00:13.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:87:0)
[01:00:13.328] Timer tick, asking Buildbucket for the build status
[01:00:13.508] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:13.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:88:0) after 1m0s
[01:01:14.198] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:88:0)
[01:01:14.198] Timer tick, asking Buildbucket for the build status
[01:01:14.333] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:14.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:89:0) after 1m0s
[01:02:15.882] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:89:0)
[01:02:15.882] Timer tick, asking Buildbucket for the build status
[01:02:15.957] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:15.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:90:0) after 1m0s
[01:03:17.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:90:0)
[01:03:17.102] Timer tick, asking Buildbucket for the build status
[01:03:17.227] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:17.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:91:0) after 1m0s
[01:04:17.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:91:0)
[01:04:17.555] Timer tick, asking Buildbucket for the build status
[01:04:17.650] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:17.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:92:0) after 1m0s
[01:05:17.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:92:0)
[01:05:17.938] Timer tick, asking Buildbucket for the build status
[01:05:18.086] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:18.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:93:0) after 1m0s
[01:06:18.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:93:0)
[01:06:18.717] Timer tick, asking Buildbucket for the build status
[01:06:18.981] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:18.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:94:0) after 1m0s
[01:07:19.157] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:94:0)
[01:07:19.157] Timer tick, asking Buildbucket for the build status
[01:07:19.383] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:19.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:95:0) after 1m0s
[01:08:19.546] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:95:0)
[01:08:19.546] Timer tick, asking Buildbucket for the build status
[01:08:19.811] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:19.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:96:0) after 1m0s
[01:09:19.975] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:96:0)
[01:09:19.975] Timer tick, asking Buildbucket for the build status
[01:09:20.075] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:20.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:97:0) after 1m0s
[01:10:20.157] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:97:0)
[01:10:20.157] Timer tick, asking Buildbucket for the build status
[01:10:20.356] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:20.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:98:0) after 1m0s
[01:11:20.404] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:98:0)
[01:11:20.404] Timer tick, asking Buildbucket for the build status
[01:11:20.810] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:20.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:99:0) after 1m0s
[01:12:20.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:99:0)
[01:12:20.951] Timer tick, asking Buildbucket for the build status
[01:12:21.144] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:21.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:100:0) after 1m0s
[01:13:21.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:100:0)
[01:13:21.195] Timer tick, asking Buildbucket for the build status
[01:13:21.296] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:21.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:101:0) after 1m0s
[01:14:21.423] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:101:0)
[01:14:21.423] Timer tick, asking Buildbucket for the build status
[01:14:21.681] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:21.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:102:0) after 1m0s
[01:15:21.911] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:102:0)
[01:15:21.911] Timer tick, asking Buildbucket for the build status
[01:15:22.109] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:22.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:103:0) after 1m0s
[01:16:22.236] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:103:0)
[01:16:22.236] Timer tick, asking Buildbucket for the build status
[01:16:22.332] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:22.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:104:0) after 1m0s
[01:17:22.613] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:104:0)
[01:17:22.613] Timer tick, asking Buildbucket for the build status
[01:17:22.708] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:22.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:105:0) after 1m0s
[01:18:23.028] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:105:0)
[01:18:23.028] Timer tick, asking Buildbucket for the build status
[01:18:23.123] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:23.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:106:0) after 1m0s
[01:19:23.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:106:0)
[01:19:23.298] Timer tick, asking Buildbucket for the build status
[01:19:23.506] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:23.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:107:0) after 1m0s
[01:20:23.722] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:107:0)
[01:20:23.722] Timer tick, asking Buildbucket for the build status
[01:20:23.858] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:23.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:108:0) after 1m0s
[01:21:24.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:108:0)
[01:21:24.034] Timer tick, asking Buildbucket for the build status
[01:21:24.154] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:24.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:109:0) after 1m0s
[01:22:24.502] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:109:0)
[01:22:24.502] Timer tick, asking Buildbucket for the build status
[01:22:24.591] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:24.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:110:0) after 1m0s
[01:23:24.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:110:0)
[01:23:24.804] Timer tick, asking Buildbucket for the build status
[01:23:25.067] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:25.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:111:0) after 1m0s
[01:24:25.111] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:111:0)
[01:24:25.111] Timer tick, asking Buildbucket for the build status
[01:24:25.200] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:25.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:112:0) after 1m0s
[01:25:25.231] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:112:0)
[01:25:25.231] Timer tick, asking Buildbucket for the build status
[01:25:25.368] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:25.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:113:0) after 1m0s
[01:26:25.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:113:0)
[01:26:25.484] Timer tick, asking Buildbucket for the build status
[01:26:25.768] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:25.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:114:0) after 1m0s
[01:27:25.798] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:114:0)
[01:27:25.798] Timer tick, asking Buildbucket for the build status
[01:27:29.190] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:29.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:115:0) after 1m0s
[01:28:29.420] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:115:0)
[01:28:29.420] Timer tick, asking Buildbucket for the build status
[01:28:29.535] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:29.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:116:0) after 1m0s
[01:29:29.743] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:116:0)
[01:29:29.743] Timer tick, asking Buildbucket for the build status
[01:29:29.859] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:29.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:117:0) after 1m0s
[01:30:30.000] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:117:0)
[01:30:30.000] Timer tick, asking Buildbucket for the build status
[01:30:30.207] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:30.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:118:0) after 1m0s
[01:31:30.398] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:118:0)
[01:31:30.398] Timer tick, asking Buildbucket for the build status
[01:31:30.756] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:30.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:119:0) after 1m0s
[01:32:30.789] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:119:0)
[01:32:30.789] Timer tick, asking Buildbucket for the build status
[01:32:31.625] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:31.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:120:0) after 1m0s
[01:33:31.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:120:0)
[01:33:31.666] Timer tick, asking Buildbucket for the build status
[01:33:31.781] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:31.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:121:0) after 1m0s
[01:34:31.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:121:0)
[01:34:31.809] Timer tick, asking Buildbucket for the build status
[01:34:31.905] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:31.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:122:0) after 1m0s
[01:35:31.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:122:0)
[01:35:31.930] Timer tick, asking Buildbucket for the build status
[01:35:32.194] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:32.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:123:0) after 1m0s
[01:36:32.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:123:0)
[01:36:32.230] Timer tick, asking Buildbucket for the build status
[01:36:32.495] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:32.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:124:0) after 1m0s
[01:37:32.554] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:124:0)
[01:37:32.554] Timer tick, asking Buildbucket for the build status
[01:37:32.711] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:32.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:125:0) after 1m0s
[01:38:32.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:125:0)
[01:38:32.744] Timer tick, asking Buildbucket for the build status
[01:38:32.959] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:32.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:126:0) after 1m0s
[01:39:32.988] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:126:0)
[01:39:32.988] Timer tick, asking Buildbucket for the build status
[01:39:33.088] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:33.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:127:0) after 1m0s
[01:40:33.119] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:127:0)
[01:40:33.119] Timer tick, asking Buildbucket for the build status
[01:40:33.220] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:33.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:128:0) after 1m0s
[01:41:33.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:128:0)
[01:41:33.268] Timer tick, asking Buildbucket for the build status
[01:41:33.367] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:33.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:129:0) after 1m0s
[01:42:33.481] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:129:0)
[01:42:33.481] Timer tick, asking Buildbucket for the build status
[01:42:33.780] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:33.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:130:0) after 1m0s
[01:43:33.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:130:0)
[01:43:33.761] Timer tick, asking Buildbucket for the build status
[01:43:36.141] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:36.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:131:0) after 1m0s
[01:44:36.270] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:131:0)
[01:44:36.270] Timer tick, asking Buildbucket for the build status
[01:44:36.379] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:36.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:132:0) after 1m0s
[01:45:36.466] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:132:0)
[01:45:36.466] Timer tick, asking Buildbucket for the build status
[01:45:36.782] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:36.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:133:0) after 1m0s
[01:46:36.923] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:133:0)
[01:46:36.923] Timer tick, asking Buildbucket for the build status
[01:46:37.166] Build 8873850538177347904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:37.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039280600644251776:134:0) after 1m0s
[01:47:06.385] Received PubSub notification, asking Buildbucket for the build status
[01:47:06.541] Build 8873850538177347904: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:47:06.541] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1595641625352377",
"created_by": "project:chromium",
"created_ts": "1595633676320791",
"id": "8873850538177347904",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"24eb259522000da909bf9e65c761d472abe925b9\"}}",
"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-2-n03a\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 165, \"got_angle_revision\": \"df5ed61ddbcab119b059e6e5bd28a049b726766a\", \"got_dawn_revision\": \"4d9cadd58aeaeeac18af40fa2b773db1a579328f\", \"got_nacl_revision\": \"fd20fafa8ec19700e12daa031bf792bae8b6bb21\", \"got_revision\": \"24eb259522000da909bf9e65c761d472abe925b9\", \"got_revision_cp\": \"refs/heads/master@{#791306}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"6a8a74986c357b0c6fa0dfd2b4b9230af8d39d1a\", \"got_v8_revision\": \"5cdec88a9e3e230528eeb9c34c68437a24db4b89\", \"got_v8_revision_cp\": \"refs/heads/8.6.230@{#1}\", \"got_webrtc_revision\": \"82e85676c3b35e721e8e61a1d089f4a0eca4750c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31782}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"24eb259522000da909bf9e65c761d472abe925b9\"}, \"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\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-2-n03a\"], \"image\": [\"chrome-win10-1703-20071600-faa8609efbc\"], \"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\": [\"5425-e4e3059\"], \"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": "1595633698592220",
"status": "COMPLETED",
"status_changed_ts": "1595641625878654",
"tags": [
"build_address:luci.chromium.ci/win-official/165",
"builder:win-official",
"buildset:commit/git/24eb259522000da909bf9e65c761d472abe925b9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/24eb259522000da909bf9e65c761d472abe925b9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039280600644251776",
"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/8873850538177347904/+/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:4d9bff3c651dd410",
"user_agent:luci-scheduler"
],
"updated_ts": "1595641625961831",
"url": "https://ci.chromium.org/b/8873850538177347904",
"utcnow_ts": "1595641626526743"
}
}
[01:47:06.541] Invocation finished in 2h12m31.956821781s with status SUCCEEDED