[01:20:20.098] New invocation is queued and will start shortly
[01:20:21.205] Starting the invocation (attempt 1)
[01:20:21.247] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:20:21.247] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:20:21.247] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9040270513545254288",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8e0f05416ac98b1d4d476bcf153d77eb2d684dfd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9040270513545254288",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/8e0f05416ac98b1d4d476bcf153d77eb2d684dfd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8e0f05416ac98b1d4d476bcf153d77eb2d684dfd",
"gitiles_ref:refs/heads/master"
]
}
[01:20:21.952] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1594689621350486",
"id": "8874840451561963040",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8e0f05416ac98b1d4d476bcf153d77eb2d684dfd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1594689621774443",
"tags": [
"build_address:luci.chromium.ci/win-official/96",
"builder:win-official",
"buildset:commit/git/8e0f05416ac98b1d4d476bcf153d77eb2d684dfd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8e0f05416ac98b1d4d476bcf153d77eb2d684dfd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9040270513545254288",
"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/8874840451561963040/+/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": "1594689621774559",
"url": "https://ci.chromium.org/b/8874840451561963040",
"utcnow_ts": "1594689621942624"
}
}
[01:20:21.952] Task URL: https://ci.chromium.org/b/8874840451561963040
[01:20:21.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:2:0) after 1m0s
[01:20:23.771] Received PubSub notification, asking Buildbucket for the build status
[01:20:24.108] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:22.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:2:0)
[01:21:22.015] Timer tick, asking Buildbucket for the build status
[01:21:22.151] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:22.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:4:0) after 1m0s
[01:22:22.187] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:4:0)
[01:22:22.187] Timer tick, asking Buildbucket for the build status
[01:22:22.325] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:22.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:5:0) after 1m0s
[01:23:22.349] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:5:0)
[01:23:22.349] Timer tick, asking Buildbucket for the build status
[01:23:22.543] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:22.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:6:0) after 1m0s
[01:24:22.569] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:6:0)
[01:24:22.569] Timer tick, asking Buildbucket for the build status
[01:24:22.711] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:22.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:7:0) after 1m0s
[01:25:22.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:7:0)
[01:25:22.758] Timer tick, asking Buildbucket for the build status
[01:25:23.081] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:23.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:8:0) after 1m0s
[01:26:23.246] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:8:0)
[01:26:23.246] Timer tick, asking Buildbucket for the build status
[01:26:23.392] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:23.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:9:0) after 1m0s
[01:27:23.486] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:9:0)
[01:27:23.486] Timer tick, asking Buildbucket for the build status
[01:27:23.961] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:23.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:10:0) after 1m0s
[01:28:23.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:10:0)
[01:28:23.983] Timer tick, asking Buildbucket for the build status
[01:28:24.188] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:24.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:11:0) after 1m0s
[01:29:24.221] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:11:0)
[01:29:24.221] Timer tick, asking Buildbucket for the build status
[01:29:24.364] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:24.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:12:0) after 1m0s
[01:30:24.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:12:0)
[01:30:24.392] Timer tick, asking Buildbucket for the build status
[01:30:24.824] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:24.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:13:0) after 1m0s
[01:31:24.902] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:13:0)
[01:31:24.902] Timer tick, asking Buildbucket for the build status
[01:31:26.290] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:26.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:14:0) after 1m0s
[01:32:26.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:14:0)
[01:32:26.282] Timer tick, asking Buildbucket for the build status
[01:32:26.450] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:26.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:15:0) after 1m0s
[01:33:26.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:15:0)
[01:33:26.591] Timer tick, asking Buildbucket for the build status
[01:33:27.214] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:27.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:16:0) after 1m0s
[01:34:27.239] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:16:0)
[01:34:27.239] Timer tick, asking Buildbucket for the build status
[01:34:27.462] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:27.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:17:0) after 1m0s
[01:35:27.522] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:17:0)
[01:35:27.522] Timer tick, asking Buildbucket for the build status
[01:35:27.664] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:27.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:18:0) after 1m0s
[01:36:27.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:18:0)
[01:36:27.808] Timer tick, asking Buildbucket for the build status
[01:36:27.952] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:27.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:19:0) after 1m0s
[01:37:28.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:19:0)
[01:37:28.107] Timer tick, asking Buildbucket for the build status
[01:37:28.293] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:28.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:20:0) after 1m0s
[01:38:28.406] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:20:0)
[01:38:28.406] Timer tick, asking Buildbucket for the build status
[01:38:29.048] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:29.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:21:0) after 1m0s
[01:39:29.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:21:0)
[01:39:29.173] Timer tick, asking Buildbucket for the build status
[01:39:29.405] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:29.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:22:0) after 1m0s
[01:40:29.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:22:0)
[01:40:29.439] Timer tick, asking Buildbucket for the build status
[01:40:31.988] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:31.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:23:0) after 1m0s
[01:41:32.004] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:23:0)
[01:41:32.004] Timer tick, asking Buildbucket for the build status
[01:41:32.127] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:32.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:24:0) after 1m0s
[01:42:32.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:24:0)
[01:42:32.152] Timer tick, asking Buildbucket for the build status
[01:42:32.381] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:32.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:25:0) after 1m0s
[01:43:32.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:25:0)
[01:43:32.417] Timer tick, asking Buildbucket for the build status
[01:43:32.598] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:32.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:26:0) after 1m0s
[01:44:32.637] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:26:0)
[01:44:32.637] Timer tick, asking Buildbucket for the build status
[01:44:33.185] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:33.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:27:0) after 1m0s
[01:45:33.267] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:27:0)
[01:45:33.267] Timer tick, asking Buildbucket for the build status
[01:45:33.681] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:33.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:28:0) after 1m0s
[01:46:33.704] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:28:0)
[01:46:33.704] Timer tick, asking Buildbucket for the build status
[01:46:33.848] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:33.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:29:0) after 1m0s
[01:47:33.901] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:29:0)
[01:47:33.901] Timer tick, asking Buildbucket for the build status
[01:47:34.125] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:34.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:30:0) after 1m0s
[01:48:34.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:30:0)
[01:48:34.155] Timer tick, asking Buildbucket for the build status
[01:48:34.391] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:34.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:31:0) after 1m0s
[01:49:34.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:31:0)
[01:49:34.415] Timer tick, asking Buildbucket for the build status
[01:49:34.588] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:34.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:32:0) after 1m0s
[01:50:34.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:32:0)
[01:50:34.619] Timer tick, asking Buildbucket for the build status
[01:50:35.015] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:35.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:33:0) after 1m0s
[01:51:35.078] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:33:0)
[01:51:35.078] Timer tick, asking Buildbucket for the build status
[01:51:35.317] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:35.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:34:0) after 1m0s
[01:52:35.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:34:0)
[01:52:35.350] Timer tick, asking Buildbucket for the build status
[01:52:35.541] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:35.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:35:0) after 1m0s
[01:53:35.565] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:35:0)
[01:53:35.565] Timer tick, asking Buildbucket for the build status
[01:53:35.788] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:35.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:36:0) after 1m0s
[01:54:35.843] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:36:0)
[01:54:35.843] Timer tick, asking Buildbucket for the build status
[01:54:36.146] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:36.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:37:0) after 1m0s
[01:55:36.211] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:37:0)
[01:55:36.211] Timer tick, asking Buildbucket for the build status
[01:55:36.377] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:36.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:38:0) after 1m0s
[01:56:36.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:38:0)
[01:56:36.409] Timer tick, asking Buildbucket for the build status
[01:56:36.749] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:36.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:39:0) after 1m0s
[01:57:36.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:39:0)
[01:57:36.854] Timer tick, asking Buildbucket for the build status
[01:57:37.209] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:37.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:40:0) after 1m0s
[01:58:37.242] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:40:0)
[01:58:37.242] Timer tick, asking Buildbucket for the build status
[01:58:37.450] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:37.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:41:0) after 1m0s
[01:59:37.490] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:41:0)
[01:59:37.490] Timer tick, asking Buildbucket for the build status
[01:59:37.731] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:37.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:42:0) after 1m0s
[02:00:37.759] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:42:0)
[02:00:37.759] Timer tick, asking Buildbucket for the build status
[02:00:37.871] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:37.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:43:0) after 1m0s
[02:01:37.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:43:0)
[02:01:37.849] Timer tick, asking Buildbucket for the build status
[02:01:38.055] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:38.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:44:0) after 1m0s
[02:02:38.194] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:44:0)
[02:02:38.194] Timer tick, asking Buildbucket for the build status
[02:02:39.535] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:39.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:45:0) after 1m0s
[02:03:39.566] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:45:0)
[02:03:39.566] Timer tick, asking Buildbucket for the build status
[02:03:39.675] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:39.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:46:0) after 1m0s
[02:04:39.728] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:46:0)
[02:04:39.728] Timer tick, asking Buildbucket for the build status
[02:04:39.884] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:39.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:47:0) after 1m0s
[02:05:39.907] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:47:0)
[02:05:39.907] Timer tick, asking Buildbucket for the build status
[02:05:40.655] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:40.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:48:0) after 1m0s
[02:06:40.755] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:48:0)
[02:06:40.755] Timer tick, asking Buildbucket for the build status
[02:06:40.884] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:40.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:49:0) after 1m0s
[02:07:40.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:49:0)
[02:07:40.955] Timer tick, asking Buildbucket for the build status
[02:07:41.216] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:41.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:50:0) after 1m0s
[02:08:41.273] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:50:0)
[02:08:41.273] Timer tick, asking Buildbucket for the build status
[02:08:41.401] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:41.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:51:0) after 1m0s
[02:09:41.492] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:51:0)
[02:09:41.492] Timer tick, asking Buildbucket for the build status
[02:09:41.612] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:41.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:52:0) after 1m0s
[02:10:41.622] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:52:0)
[02:10:41.622] Timer tick, asking Buildbucket for the build status
[02:10:41.786] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:41.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:53:0) after 1m0s
[02:11:41.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:53:0)
[02:11:41.848] Timer tick, asking Buildbucket for the build status
[02:11:41.994] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:41.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:54:0) after 1m0s
[02:12:42.103] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:54:0)
[02:12:42.103] Timer tick, asking Buildbucket for the build status
[02:12:42.502] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:42.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:55:0) after 1m0s
[02:13:42.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:55:0)
[02:13:42.571] Timer tick, asking Buildbucket for the build status
[02:13:42.682] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:42.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:56:0) after 1m0s
[02:14:42.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:56:0)
[02:14:42.676] Timer tick, asking Buildbucket for the build status
[02:14:42.803] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:42.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:57:0) after 1m0s
[02:15:42.881] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:57:0)
[02:15:42.881] Timer tick, asking Buildbucket for the build status
[02:15:43.000] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:43.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:58:0) after 1m0s
[02:16:43.037] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:58:0)
[02:16:43.037] Timer tick, asking Buildbucket for the build status
[02:16:43.320] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:43.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:59:0) after 1m0s
[02:17:43.352] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:59:0)
[02:17:43.352] Timer tick, asking Buildbucket for the build status
[02:17:43.529] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:43.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:60:0) after 1m0s
[02:18:43.620] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:60:0)
[02:18:43.620] Timer tick, asking Buildbucket for the build status
[02:18:43.765] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:43.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:61:0) after 1m0s
[02:19:43.862] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:61:0)
[02:19:43.862] Timer tick, asking Buildbucket for the build status
[02:19:44.400] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:44.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:62:0) after 1m0s
[02:20:44.458] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:62:0)
[02:20:44.458] Timer tick, asking Buildbucket for the build status
[02:20:45.113] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:45.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:63:0) after 1m0s
[02:21:45.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:63:0)
[02:21:45.141] Timer tick, asking Buildbucket for the build status
[02:21:45.304] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:45.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:64:0) after 1m0s
[02:22:45.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:64:0)
[02:22:45.414] Timer tick, asking Buildbucket for the build status
[02:22:45.740] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:45.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:65:0) after 1m0s
[02:23:45.871] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:65:0)
[02:23:45.871] Timer tick, asking Buildbucket for the build status
[02:23:45.999] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:45.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:66:0) after 1m0s
[02:24:46.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:66:0)
[02:24:46.033] Timer tick, asking Buildbucket for the build status
[02:24:46.186] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:46.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:67:0) after 1m0s
[02:25:46.212] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:67:0)
[02:25:46.212] Timer tick, asking Buildbucket for the build status
[02:25:47.995] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:47.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:68:0) after 1m0s
[02:26:48.025] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:68:0)
[02:26:48.025] Timer tick, asking Buildbucket for the build status
[02:26:48.213] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:48.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:69:0) after 1m0s
[02:27:48.246] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:69:0)
[02:27:48.246] Timer tick, asking Buildbucket for the build status
[02:27:48.383] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:48.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:70:0) after 1m0s
[02:28:48.410] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:70:0)
[02:28:48.410] Timer tick, asking Buildbucket for the build status
[02:28:48.575] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:48.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:71:0) after 1m0s
[02:29:48.605] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:71:0)
[02:29:48.605] Timer tick, asking Buildbucket for the build status
[02:29:49.104] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:49.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:72:0) after 1m0s
[02:30:49.100] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:72:0)
[02:30:49.100] Timer tick, asking Buildbucket for the build status
[02:30:49.309] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:49.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:73:0) after 1m0s
[02:31:49.423] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:73:0)
[02:31:49.423] Timer tick, asking Buildbucket for the build status
[02:31:50.937] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:50.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:74:0) after 1m0s
[02:32:50.997] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:74:0)
[02:32:50.997] Timer tick, asking Buildbucket for the build status
[02:32:51.161] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:51.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:75:0) after 1m0s
[02:33:51.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:75:0)
[02:33:51.167] Timer tick, asking Buildbucket for the build status
[02:33:51.284] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:51.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:76:0) after 1m0s
[02:34:51.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:76:0)
[02:34:51.314] Timer tick, asking Buildbucket for the build status
[02:34:51.449] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:51.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:77:0) after 1m0s
[02:35:51.509] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:77:0)
[02:35:51.509] Timer tick, asking Buildbucket for the build status
[02:35:51.902] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:51.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:78:0) after 1m0s
[02:36:51.932] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:78:0)
[02:36:51.932] Timer tick, asking Buildbucket for the build status
[02:36:52.065] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:52.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:79:0) after 1m0s
[02:37:52.147] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:79:0)
[02:37:52.147] Timer tick, asking Buildbucket for the build status
[02:37:52.370] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:52.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:80:0) after 1m0s
[02:38:52.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:80:0)
[02:38:52.414] Timer tick, asking Buildbucket for the build status
[02:38:52.619] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:52.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:81:0) after 1m0s
[02:39:52.660] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:81:0)
[02:39:52.660] Timer tick, asking Buildbucket for the build status
[02:39:53.040] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:53.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:82:0) after 1m0s
[02:40:53.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:82:0)
[02:40:53.038] Timer tick, asking Buildbucket for the build status
[02:40:53.341] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:53.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:83:0) after 1m0s
[02:41:53.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:83:0)
[02:41:53.384] Timer tick, asking Buildbucket for the build status
[02:41:53.518] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:53.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:84:0) after 1m0s
[02:42:53.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:84:0)
[02:42:53.555] Timer tick, asking Buildbucket for the build status
[02:42:55.094] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:55.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:85:0) after 1m0s
[02:43:55.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:85:0)
[02:43:55.117] Timer tick, asking Buildbucket for the build status
[02:43:55.254] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:55.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:86:0) after 1m0s
[02:44:55.288] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:86:0)
[02:44:55.288] Timer tick, asking Buildbucket for the build status
[02:44:55.427] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:55.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:87:0) after 1m0s
[02:45:55.460] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:87:0)
[02:45:55.460] Timer tick, asking Buildbucket for the build status
[02:45:55.616] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:55.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:88:0) after 1m0s
[02:46:55.664] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:88:0)
[02:46:55.664] Timer tick, asking Buildbucket for the build status
[02:46:55.823] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:55.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:89:0) after 1m0s
[02:47:56.023] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:89:0)
[02:47:56.023] Timer tick, asking Buildbucket for the build status
[02:47:56.541] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:56.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:90:0) after 1m0s
[02:48:56.696] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:90:0)
[02:48:56.696] Timer tick, asking Buildbucket for the build status
[02:48:57.292] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:57.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:91:0) after 1m0s
[02:49:57.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:91:0)
[02:49:57.367] Timer tick, asking Buildbucket for the build status
[02:49:57.527] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:57.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:92:0) after 1m0s
[02:50:57.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:92:0)
[02:50:57.556] Timer tick, asking Buildbucket for the build status
[02:50:57.684] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:57.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:93:0) after 1m0s
[02:51:57.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:93:0)
[02:51:57.804] Timer tick, asking Buildbucket for the build status
[02:51:57.956] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:57.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:94:0) after 1m0s
[02:52:58.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:94:0)
[02:52:58.006] Timer tick, asking Buildbucket for the build status
[02:52:58.127] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:58.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:95:0) after 1m0s
[02:53:58.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:95:0)
[02:53:58.238] Timer tick, asking Buildbucket for the build status
[02:53:58.692] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:58.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:96:0) after 1m0s
[02:54:58.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:96:0)
[02:54:58.721] Timer tick, asking Buildbucket for the build status
[02:54:58.931] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:58.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:97:0) after 1m0s
[02:55:58.966] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:97:0)
[02:55:58.966] Timer tick, asking Buildbucket for the build status
[02:55:59.299] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:59.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:98:0) after 1m0s
[02:56:59.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:98:0)
[02:56:59.413] Timer tick, asking Buildbucket for the build status
[02:56:59.727] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:59.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:99:0) after 1m0s
[02:57:59.880] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:99:0)
[02:57:59.880] Timer tick, asking Buildbucket for the build status
[02:58:00.183] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:00.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:100:0) after 1m0s
[02:59:00.221] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:100:0)
[02:59:00.221] Timer tick, asking Buildbucket for the build status
[02:59:00.569] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:00.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:101:0) after 1m0s
[03:00:00.637] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:101:0)
[03:00:00.637] Timer tick, asking Buildbucket for the build status
[03:00:00.906] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:00.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:102:0) after 1m0s
[03:01:02.390] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:102:0)
[03:01:02.390] Timer tick, asking Buildbucket for the build status
[03:01:02.537] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:02.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:103:0) after 1m0s
[03:02:03.294] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:103:0)
[03:02:03.294] Timer tick, asking Buildbucket for the build status
[03:02:03.449] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:03.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:104:0) after 1m0s
[03:03:03.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:104:0)
[03:03:03.545] Timer tick, asking Buildbucket for the build status
[03:03:03.972] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:03.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:105:0) after 1m0s
[03:04:04.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:105:0)
[03:04:04.230] Timer tick, asking Buildbucket for the build status
[03:04:04.397] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:04.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:106:0) after 1m0s
[03:05:04.437] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:106:0)
[03:05:04.437] Timer tick, asking Buildbucket for the build status
[03:05:06.300] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:06.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:107:0) after 1m0s
[03:06:06.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:107:0)
[03:06:06.535] Timer tick, asking Buildbucket for the build status
[03:06:09.437] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:09.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:108:0) after 1m0s
[03:07:09.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:108:0)
[03:07:09.484] Timer tick, asking Buildbucket for the build status
[03:07:10.114] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:10.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:109:0) after 1m0s
[03:08:10.188] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:109:0)
[03:08:10.188] Timer tick, asking Buildbucket for the build status
[03:08:10.572] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:10.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:110:0) after 1m0s
[03:09:10.662] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:110:0)
[03:09:10.662] Timer tick, asking Buildbucket for the build status
[03:09:10.854] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:10.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:111:0) after 1m0s
[03:10:10.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:111:0)
[03:10:10.933] Timer tick, asking Buildbucket for the build status
[03:10:11.164] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:11.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:112:0) after 1m0s
[03:11:11.286] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:112:0)
[03:11:11.286] Timer tick, asking Buildbucket for the build status
[03:11:11.454] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:11.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:113:0) after 1m0s
[03:12:11.525] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:113:0)
[03:12:11.525] Timer tick, asking Buildbucket for the build status
[03:12:11.659] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:11.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:114:0) after 1m0s
[03:13:11.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:114:0)
[03:13:11.818] Timer tick, asking Buildbucket for the build status
[03:13:11.933] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:11.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:115:0) after 1m0s
[03:14:12.008] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:115:0)
[03:14:12.008] Timer tick, asking Buildbucket for the build status
[03:14:12.370] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:12.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:116:0) after 1m0s
[03:15:12.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:116:0)
[03:15:12.452] Timer tick, asking Buildbucket for the build status
[03:15:12.815] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:12.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:117:0) after 1m0s
[03:16:12.936] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:117:0)
[03:16:12.936] Timer tick, asking Buildbucket for the build status
[03:16:16.418] Build 8874840451561963040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:16.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040270513545254288:118:0) after 1m0s
[03:16:29.615] Received PubSub notification, asking Buildbucket for the build status
[03:16:29.832] Build 8874840451561963040: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:16:29.832] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1594696588563162",
"created_by": "project:chromium",
"created_ts": "1594689621350486",
"id": "8874840451561963040",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8e0f05416ac98b1d4d476bcf153d77eb2d684dfd\"}}",
"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-8-pi5z\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 96, \"got_angle_revision\": \"e5c184751160fb0f314ee2227e5fadca440d688e\", \"got_dawn_revision\": \"261b05d3dd3f3c450a37668737243db60cad53ca\", \"got_nacl_revision\": \"fd20fafa8ec19700e12daa031bf792bae8b6bb21\", \"got_revision\": \"8e0f05416ac98b1d4d476bcf153d77eb2d684dfd\", \"got_revision_cp\": \"refs/heads/master@{#787992}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"cd0af6456eb1c427943c2cf60d4f6234f821aa09\", \"got_v8_revision\": \"d120885fe56ae1c21b2de93d1a15733c91115b40\", \"got_v8_revision_cp\": \"refs/heads/8.6.123@{#1}\", \"got_webrtc_revision\": \"8df59bc74ebb4d0b8e5d80a8b4f5c674db2a9c4d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31703}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8e0f05416ac98b1d4d476bcf153d77eb2d684dfd\"}, \"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-8-pi5z\"], \"image\": [\"chrome-win10-1703-20070700-8a9e7990cc1\"], \"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\": [\"5378-e275056\"], \"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": "1594689622665431",
"status": "COMPLETED",
"status_changed_ts": "1594696589157512",
"tags": [
"build_address:luci.chromium.ci/win-official/96",
"builder:win-official",
"buildset:commit/git/8e0f05416ac98b1d4d476bcf153d77eb2d684dfd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8e0f05416ac98b1d4d476bcf153d77eb2d684dfd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9040270513545254288",
"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/8874840451561963040/+/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:4d63ba188b368410",
"user_agent:luci-scheduler"
],
"updated_ts": "1594696589167193",
"url": "https://ci.chromium.org/b/8874840451561963040",
"utcnow_ts": "1594696589799535"
}
}
[03:16:29.832] Invocation finished in 1h56m9.75756306s with status SUCCEEDED