[17:53:14.665] New invocation is queued and will start shortly
[17:53:15.927] Starting the invocation (attempt 1)
[17:53:15.975] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:53:15.975] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:53:15.975] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9037308942155703584",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7c62e6315427ea78fbd52d2687036dd15e741656\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9037308942155703584",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/7c62e6315427ea78fbd52d2687036dd15e741656",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7c62e6315427ea78fbd52d2687036dd15e741656",
"gitiles_ref:refs/heads/master"
]
}
[17:53:16.713] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1597513996102414",
"id": "8871878879981656288",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7c62e6315427ea78fbd52d2687036dd15e741656\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1597513996555516",
"tags": [
"build_address:luci.chromium.ci/win-official/347",
"builder:win-official",
"buildset:commit/git/7c62e6315427ea78fbd52d2687036dd15e741656",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7c62e6315427ea78fbd52d2687036dd15e741656",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037308942155703584",
"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/8871878879981656288/+/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": "1597513996555635",
"url": "https://ci.chromium.org/b/8871878879981656288",
"utcnow_ts": "1597513996702132"
}
}
[17:53:16.713] Task URL: https://ci.chromium.org/b/8871878879981656288
[17:53:16.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:2:0) after 1m0s
[17:53:18.718] Received PubSub notification, asking Buildbucket for the build status
[17:53:18.889] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:16.739] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:2:0)
[17:54:16.739] Timer tick, asking Buildbucket for the build status
[17:54:16.932] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:16.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:4:0) after 1m0s
[17:55:17.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:4:0)
[17:55:17.052] Timer tick, asking Buildbucket for the build status
[17:55:17.151] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:17.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:5:0) after 1m0s
[17:56:17.200] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:5:0)
[17:56:17.200] Timer tick, asking Buildbucket for the build status
[17:56:17.286] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:17.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:6:0) after 1m0s
[17:57:17.306] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:6:0)
[17:57:17.306] Timer tick, asking Buildbucket for the build status
[17:57:17.397] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:17.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:7:0) after 1m0s
[17:58:17.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:7:0)
[17:58:17.521] Timer tick, asking Buildbucket for the build status
[17:58:17.731] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:17.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:8:0) after 1m0s
[17:59:17.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:8:0)
[17:59:17.823] Timer tick, asking Buildbucket for the build status
[17:59:17.910] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:17.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:9:0) after 1m0s
[18:00:17.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:9:0)
[18:00:17.933] Timer tick, asking Buildbucket for the build status
[18:00:18.313] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:18.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:10:0) after 1m0s
[18:01:18.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:10:0)
[18:01:18.367] Timer tick, asking Buildbucket for the build status
[18:01:18.788] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:18.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:11:0) after 1m0s
[18:02:18.850] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:11:0)
[18:02:18.850] Timer tick, asking Buildbucket for the build status
[18:02:19.296] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:19.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:12:0) after 1m0s
[18:03:19.320] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:12:0)
[18:03:19.320] Timer tick, asking Buildbucket for the build status
[18:03:19.695] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:19.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:13:0) after 1m0s
[18:04:19.748] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:13:0)
[18:04:19.748] Timer tick, asking Buildbucket for the build status
[18:04:19.892] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:19.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:14:0) after 1m0s
[18:05:19.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:14:0)
[18:05:19.919] Timer tick, asking Buildbucket for the build status
[18:05:20.057] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:20.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:15:0) after 1m0s
[18:06:20.079] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:15:0)
[18:06:20.079] Timer tick, asking Buildbucket for the build status
[18:06:20.148] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:20.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:16:0) after 1m0s
[18:07:20.329] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:16:0)
[18:07:20.329] Timer tick, asking Buildbucket for the build status
[18:07:20.391] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:20.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:17:0) after 1m0s
[18:08:20.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:17:0)
[18:08:20.545] Timer tick, asking Buildbucket for the build status
[18:08:20.673] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:20.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:18:0) after 1m0s
[18:09:20.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:18:0)
[18:09:20.817] Timer tick, asking Buildbucket for the build status
[18:09:20.898] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:20.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:19:0) after 1m0s
[18:10:21.067] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:19:0)
[18:10:21.067] Timer tick, asking Buildbucket for the build status
[18:10:21.145] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:21.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:20:0) after 1m0s
[18:11:21.965] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:20:0)
[18:11:21.965] Timer tick, asking Buildbucket for the build status
[18:11:22.123] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:22.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:21:0) after 1m0s
[18:12:22.278] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:21:0)
[18:12:22.278] Timer tick, asking Buildbucket for the build status
[18:12:22.348] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:22.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:22:0) after 1m0s
[18:13:22.633] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:22:0)
[18:13:22.633] Timer tick, asking Buildbucket for the build status
[18:13:22.920] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:22.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:23:0) after 1m0s
[18:14:23.168] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:23:0)
[18:14:23.168] Timer tick, asking Buildbucket for the build status
[18:14:23.517] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:23.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:24:0) after 1m0s
[18:15:23.542] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:24:0)
[18:15:23.542] Timer tick, asking Buildbucket for the build status
[18:15:23.735] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:23.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:25:0) after 1m0s
[18:16:23.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:25:0)
[18:16:23.938] Timer tick, asking Buildbucket for the build status
[18:16:24.891] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:24.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:26:0) after 1m0s
[18:17:25.055] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:26:0)
[18:17:25.055] Timer tick, asking Buildbucket for the build status
[18:17:25.289] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:25.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:27:0) after 1m0s
[18:18:25.496] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:27:0)
[18:18:25.496] Timer tick, asking Buildbucket for the build status
[18:18:25.976] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:25.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:28:0) after 1m0s
[18:19:26.092] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:28:0)
[18:19:26.092] Timer tick, asking Buildbucket for the build status
[18:19:26.157] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:26.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:29:0) after 1m0s
[18:20:26.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:29:0)
[18:20:26.204] Timer tick, asking Buildbucket for the build status
[18:20:26.301] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:26.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:30:0) after 1m0s
[18:21:26.476] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:30:0)
[18:21:26.476] Timer tick, asking Buildbucket for the build status
[18:21:26.558] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:26.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:31:0) after 1m0s
[18:22:26.794] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:31:0)
[18:22:26.794] Timer tick, asking Buildbucket for the build status
[18:22:26.955] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:26.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:32:0) after 1m0s
[18:23:27.287] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:32:0)
[18:23:27.287] Timer tick, asking Buildbucket for the build status
[18:23:27.430] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:27.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:33:0) after 1m0s
[18:24:27.530] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:33:0)
[18:24:27.530] Timer tick, asking Buildbucket for the build status
[18:24:27.606] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:27.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:34:0) after 1m0s
[18:25:27.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:34:0)
[18:25:27.636] Timer tick, asking Buildbucket for the build status
[18:25:27.718] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:27.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:35:0) after 1m0s
[18:26:27.766] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:35:0)
[18:26:27.766] Timer tick, asking Buildbucket for the build status
[18:26:28.003] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:28.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:36:0) after 1m0s
[18:27:28.307] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:36:0)
[18:27:28.307] Timer tick, asking Buildbucket for the build status
[18:27:28.431] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:28.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:37:0) after 1m0s
[18:28:28.533] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:37:0)
[18:28:28.533] Timer tick, asking Buildbucket for the build status
[18:28:28.654] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:28.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:38:0) after 1m0s
[18:29:28.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:38:0)
[18:29:28.912] Timer tick, asking Buildbucket for the build status
[18:29:29.027] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:29.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:39:0) after 1m0s
[18:30:29.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:39:0)
[18:30:29.241] Timer tick, asking Buildbucket for the build status
[18:30:29.478] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:29.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:40:0) after 1m0s
[18:31:29.549] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:40:0)
[18:31:29.549] Timer tick, asking Buildbucket for the build status
[18:31:30.021] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:30.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:41:0) after 1m0s
[18:32:30.189] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:41:0)
[18:32:30.189] Timer tick, asking Buildbucket for the build status
[18:32:30.271] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:30.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:42:0) after 1m0s
[18:33:30.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:42:0)
[18:33:30.293] Timer tick, asking Buildbucket for the build status
[18:33:30.406] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:30.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:43:0) after 1m0s
[18:34:30.481] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:43:0)
[18:34:30.481] Timer tick, asking Buildbucket for the build status
[18:34:30.556] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:30.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:44:0) after 1m0s
[18:35:30.583] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:44:0)
[18:35:30.583] Timer tick, asking Buildbucket for the build status
[18:35:30.851] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:30.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:45:0) after 1m0s
[18:36:30.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:45:0)
[18:36:30.889] Timer tick, asking Buildbucket for the build status
[18:36:30.983] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:30.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:46:0) after 1m0s
[18:37:31.022] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:46:0)
[18:37:31.022] Timer tick, asking Buildbucket for the build status
[18:37:31.223] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:31.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:47:0) after 1m0s
[18:38:31.267] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:47:0)
[18:38:31.267] Timer tick, asking Buildbucket for the build status
[18:38:31.480] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:31.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:48:0) after 1m0s
[18:39:31.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:48:0)
[18:39:31.502] Timer tick, asking Buildbucket for the build status
[18:39:31.586] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:31.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:49:0) after 1m0s
[18:40:31.623] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:49:0)
[18:40:31.623] Timer tick, asking Buildbucket for the build status
[18:40:31.690] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:31.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:50:0) after 1m0s
[18:41:31.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:50:0)
[18:41:31.781] Timer tick, asking Buildbucket for the build status
[18:41:31.975] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:31.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:51:0) after 1m0s
[18:42:31.997] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:51:0)
[18:42:31.997] Timer tick, asking Buildbucket for the build status
[18:42:32.366] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:32.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:52:0) after 1m0s
[18:43:32.438] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:52:0)
[18:43:32.438] Timer tick, asking Buildbucket for the build status
[18:43:32.590] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:32.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:53:0) after 1m0s
[18:44:32.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:53:0)
[18:44:32.619] Timer tick, asking Buildbucket for the build status
[18:44:32.913] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:32.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:54:0) after 1m0s
[18:45:33.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:54:0)
[18:45:33.005] Timer tick, asking Buildbucket for the build status
[18:45:33.067] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:33.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:55:0) after 1m0s
[18:46:33.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:55:0)
[18:46:33.217] Timer tick, asking Buildbucket for the build status
[18:46:33.311] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:33.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:56:0) after 1m0s
[18:47:33.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:56:0)
[18:47:33.372] Timer tick, asking Buildbucket for the build status
[18:47:33.461] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:33.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:57:0) after 1m0s
[18:48:33.580] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:57:0)
[18:48:33.580] Timer tick, asking Buildbucket for the build status
[18:48:33.744] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:33.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:58:0) after 1m0s
[18:49:33.767] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:58:0)
[18:49:33.767] Timer tick, asking Buildbucket for the build status
[18:49:33.837] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:33.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:59:0) after 1m0s
[18:50:33.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:59:0)
[18:50:33.864] Timer tick, asking Buildbucket for the build status
[18:50:33.929] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:33.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:60:0) after 1m0s
[18:51:33.968] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:60:0)
[18:51:33.968] Timer tick, asking Buildbucket for the build status
[18:51:34.224] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:34.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:61:0) after 1m0s
[18:52:34.296] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:61:0)
[18:52:34.296] Timer tick, asking Buildbucket for the build status
[18:52:34.359] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:34.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:62:0) after 1m0s
[18:53:34.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:62:0)
[18:53:34.413] Timer tick, asking Buildbucket for the build status
[18:53:34.718] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:34.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:63:0) after 1m0s
[18:54:34.807] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:63:0)
[18:54:34.807] Timer tick, asking Buildbucket for the build status
[18:54:34.888] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:34.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:64:0) after 1m0s
[18:55:34.984] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:64:0)
[18:55:34.984] Timer tick, asking Buildbucket for the build status
[18:55:35.068] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:35.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:65:0) after 1m0s
[18:56:35.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:65:0)
[18:56:35.141] Timer tick, asking Buildbucket for the build status
[18:56:35.214] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:35.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:66:0) after 1m0s
[18:57:35.276] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:66:0)
[18:57:35.276] Timer tick, asking Buildbucket for the build status
[18:57:35.627] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:35.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:67:0) after 1m0s
[18:58:35.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:67:0)
[18:58:35.721] Timer tick, asking Buildbucket for the build status
[18:58:36.468] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:36.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:68:0) after 1m0s
[18:59:36.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:68:0)
[18:59:36.498] Timer tick, asking Buildbucket for the build status
[18:59:36.626] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:36.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:69:0) after 1m0s
[19:00:36.624] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:69:0)
[19:00:36.624] Timer tick, asking Buildbucket for the build status
[19:00:36.699] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:36.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:70:0) after 1m0s
[19:01:36.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:70:0)
[19:01:36.734] Timer tick, asking Buildbucket for the build status
[19:01:36.819] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:36.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:71:0) after 1m0s
[19:02:36.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:71:0)
[19:02:36.864] Timer tick, asking Buildbucket for the build status
[19:02:36.924] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:36.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:72:0) after 1m0s
[19:03:36.954] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:72:0)
[19:03:36.954] Timer tick, asking Buildbucket for the build status
[19:03:37.175] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:37.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:73:0) after 1m0s
[19:04:37.346] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:73:0)
[19:04:37.346] Timer tick, asking Buildbucket for the build status
[19:04:38.132] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:38.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:74:0) after 1m0s
[19:05:38.205] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:74:0)
[19:05:38.205] Timer tick, asking Buildbucket for the build status
[19:05:38.345] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:38.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:75:0) after 1m0s
[19:06:38.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:75:0)
[19:06:38.384] Timer tick, asking Buildbucket for the build status
[19:06:38.441] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:38.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:76:0) after 1m0s
[19:07:38.935] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:76:0)
[19:07:38.935] Timer tick, asking Buildbucket for the build status
[19:07:39.052] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:39.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:77:0) after 1m0s
[19:08:39.110] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:77:0)
[19:08:39.110] Timer tick, asking Buildbucket for the build status
[19:08:39.264] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:39.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:78:0) after 1m0s
[19:09:39.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:78:0)
[19:09:39.328] Timer tick, asking Buildbucket for the build status
[19:09:39.576] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:39.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:79:0) after 1m0s
[19:10:39.660] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:79:0)
[19:10:39.660] Timer tick, asking Buildbucket for the build status
[19:10:39.989] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:39.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:80:0) after 1m0s
[19:11:40.048] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:80:0)
[19:11:40.048] Timer tick, asking Buildbucket for the build status
[19:11:40.850] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:40.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:81:0) after 1m0s
[19:12:40.906] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:81:0)
[19:12:40.906] Timer tick, asking Buildbucket for the build status
[19:12:40.980] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:40.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:82:0) after 1m0s
[19:13:41.026] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:82:0)
[19:13:41.026] Timer tick, asking Buildbucket for the build status
[19:13:41.159] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:41.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:83:0) after 1m0s
[19:14:41.192] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:83:0)
[19:14:41.192] Timer tick, asking Buildbucket for the build status
[19:14:41.252] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:41.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:84:0) after 1m0s
[19:15:41.308] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:84:0)
[19:15:41.308] Timer tick, asking Buildbucket for the build status
[19:15:41.759] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:41.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:85:0) after 1m0s
[19:16:41.897] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:85:0)
[19:16:41.897] Timer tick, asking Buildbucket for the build status
[19:16:42.002] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:42.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:86:0) after 1m0s
[19:17:42.059] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:86:0)
[19:17:42.059] Timer tick, asking Buildbucket for the build status
[19:17:42.158] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:42.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:87:0) after 1m0s
[19:18:42.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:87:0)
[19:18:42.195] Timer tick, asking Buildbucket for the build status
[19:18:42.367] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:42.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:88:0) after 1m0s
[19:19:42.449] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:88:0)
[19:19:42.449] Timer tick, asking Buildbucket for the build status
[19:19:42.557] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:42.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:89:0) after 1m0s
[19:20:43.031] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:89:0)
[19:20:43.031] Timer tick, asking Buildbucket for the build status
[19:20:43.247] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:43.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:90:0) after 1m0s
[19:21:43.358] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:90:0)
[19:21:43.358] Timer tick, asking Buildbucket for the build status
[19:21:43.438] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:43.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:91:0) after 1m0s
[19:22:43.579] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:91:0)
[19:22:43.579] Timer tick, asking Buildbucket for the build status
[19:22:43.732] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:43.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:92:0) after 1m0s
[19:23:43.824] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:92:0)
[19:23:43.824] Timer tick, asking Buildbucket for the build status
[19:23:44.005] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:44.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:93:0) after 1m0s
[19:24:44.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:93:0)
[19:24:44.034] Timer tick, asking Buildbucket for the build status
[19:24:44.142] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:44.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:94:0) after 1m0s
[19:25:44.177] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:94:0)
[19:25:44.177] Timer tick, asking Buildbucket for the build status
[19:25:44.672] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:44.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:95:0) after 1m0s
[19:26:44.771] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:95:0)
[19:26:44.771] Timer tick, asking Buildbucket for the build status
[19:26:44.840] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:44.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:96:0) after 1m0s
[19:27:44.887] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:96:0)
[19:27:44.887] Timer tick, asking Buildbucket for the build status
[19:27:44.958] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:44.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:97:0) after 1m0s
[19:28:44.986] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:97:0)
[19:28:44.986] Timer tick, asking Buildbucket for the build status
[19:28:45.199] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:45.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:98:0) after 1m0s
[19:29:45.222] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:98:0)
[19:29:45.223] Timer tick, asking Buildbucket for the build status
[19:29:45.269] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:45.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:99:0) after 1m0s
[19:30:45.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:99:0)
[19:30:45.387] Timer tick, asking Buildbucket for the build status
[19:30:45.462] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:45.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:100:0) after 1m0s
[19:31:45.492] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:100:0)
[19:31:45.492] Timer tick, asking Buildbucket for the build status
[19:31:45.823] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:45.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:101:0) after 1m0s
[19:32:45.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:101:0)
[19:32:45.873] Timer tick, asking Buildbucket for the build status
[19:32:45.993] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:45.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:102:0) after 1m0s
[19:33:46.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:102:0)
[19:33:46.107] Timer tick, asking Buildbucket for the build status
[19:33:46.246] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:46.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:103:0) after 1m0s
[19:34:46.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:103:0)
[19:34:46.268] Timer tick, asking Buildbucket for the build status
[19:34:46.361] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:46.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:104:0) after 1m0s
[19:35:46.427] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:104:0)
[19:35:46.427] Timer tick, asking Buildbucket for the build status
[19:35:46.501] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:46.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:105:0) after 1m0s
[19:36:46.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:105:0)
[19:36:46.501] Timer tick, asking Buildbucket for the build status
[19:36:46.586] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:46.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:106:0) after 1m0s
[19:37:46.664] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:106:0)
[19:37:46.664] Timer tick, asking Buildbucket for the build status
[19:37:46.998] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:46.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:107:0) after 1m0s
[19:38:47.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:107:0)
[19:38:47.042] Timer tick, asking Buildbucket for the build status
[19:38:47.206] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:47.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:108:0) after 1m0s
[19:39:47.231] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:108:0)
[19:39:47.231] Timer tick, asking Buildbucket for the build status
[19:39:47.313] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:47.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:109:0) after 1m0s
[19:40:47.339] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:109:0)
[19:40:47.339] Timer tick, asking Buildbucket for the build status
[19:40:47.415] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:47.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:110:0) after 1m0s
[19:41:47.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:110:0)
[19:41:47.444] Timer tick, asking Buildbucket for the build status
[19:41:48.409] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:48.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:111:0) after 1m0s
[19:42:48.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:111:0)
[19:42:48.529] Timer tick, asking Buildbucket for the build status
[19:42:48.595] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:48.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:112:0) after 1m0s
[19:43:48.727] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:112:0)
[19:43:48.727] Timer tick, asking Buildbucket for the build status
[19:43:48.899] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:48.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:113:0) after 1m0s
[19:44:49.079] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:113:0)
[19:44:49.079] Timer tick, asking Buildbucket for the build status
[19:44:49.181] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:49.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:114:0) after 1m0s
[19:45:49.250] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:114:0)
[19:45:49.250] Timer tick, asking Buildbucket for the build status
[19:45:49.310] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:49.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:115:0) after 1m0s
[19:46:49.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:115:0)
[19:46:49.483] Timer tick, asking Buildbucket for the build status
[19:46:49.549] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:49.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:116:0) after 1m0s
[19:47:49.641] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:116:0)
[19:47:49.641] Timer tick, asking Buildbucket for the build status
[19:47:49.713] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:49.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:117:0) after 1m0s
[19:48:49.725] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:117:0)
[19:48:49.725] Timer tick, asking Buildbucket for the build status
[19:48:50.268] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:50.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:118:0) after 1m0s
[19:49:50.291] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:118:0)
[19:49:50.291] Timer tick, asking Buildbucket for the build status
[19:49:50.364] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:50.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:119:0) after 1m0s
[19:50:50.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:119:0)
[19:50:50.439] Timer tick, asking Buildbucket for the build status
[19:50:50.592] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:50.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:120:0) after 1m0s
[19:51:50.641] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:120:0)
[19:51:50.641] Timer tick, asking Buildbucket for the build status
[19:51:50.910] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:50.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:121:0) after 1m0s
[19:52:50.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:121:0)
[19:52:50.925] Timer tick, asking Buildbucket for the build status
[19:52:51.288] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:51.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:122:0) after 1m0s
[19:53:51.271] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:122:0)
[19:53:51.271] Timer tick, asking Buildbucket for the build status
[19:53:51.419] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:51.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:123:0) after 1m0s
[19:54:51.440] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:123:0)
[19:54:51.440] Timer tick, asking Buildbucket for the build status
[19:54:52.004] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:52.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:124:0) after 1m0s
[19:55:52.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:124:0)
[19:55:52.029] Timer tick, asking Buildbucket for the build status
[19:55:52.092] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:52.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:125:0) after 1m0s
[19:56:52.087] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:125:0)
[19:56:52.087] Timer tick, asking Buildbucket for the build status
[19:56:52.157] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:52.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:126:0) after 1m0s
[19:57:52.188] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:126:0)
[19:57:52.188] Timer tick, asking Buildbucket for the build status
[19:57:52.497] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:52.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:127:0) after 1m0s
[19:58:52.538] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:127:0)
[19:58:52.538] Timer tick, asking Buildbucket for the build status
[19:58:52.680] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:52.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:128:0) after 1m0s
[19:59:52.712] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:128:0)
[19:59:52.712] Timer tick, asking Buildbucket for the build status
[19:59:54.237] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:54.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:129:0) after 1m0s
[20:00:54.271] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:129:0)
[20:00:54.271] Timer tick, asking Buildbucket for the build status
[20:00:54.355] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:54.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:130:0) after 1m0s
[20:01:54.533] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:130:0)
[20:01:54.533] Timer tick, asking Buildbucket for the build status
[20:01:54.647] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:54.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:131:0) after 1m0s
[20:02:54.740] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:131:0)
[20:02:54.740] Timer tick, asking Buildbucket for the build status
[20:02:54.803] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:54.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:132:0) after 1m0s
[20:03:54.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:132:0)
[20:03:54.849] Timer tick, asking Buildbucket for the build status
[20:03:54.952] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:54.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:133:0) after 1m0s
[20:04:55.058] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:133:0)
[20:04:55.058] Timer tick, asking Buildbucket for the build status
[20:04:55.591] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:55.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:134:0) after 1m0s
[20:05:55.679] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:134:0)
[20:05:55.679] Timer tick, asking Buildbucket for the build status
[20:05:55.759] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:55.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:135:0) after 1m0s
[20:06:55.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:135:0)
[20:06:55.839] Timer tick, asking Buildbucket for the build status
[20:06:55.906] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:55.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:136:0) after 1m0s
[20:07:55.939] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:136:0)
[20:07:55.939] Timer tick, asking Buildbucket for the build status
[20:07:56.032] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:56.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:137:0) after 1m0s
[20:08:56.140] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:137:0)
[20:08:56.140] Timer tick, asking Buildbucket for the build status
[20:08:56.330] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:56.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:138:0) after 1m0s
[20:09:56.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:138:0)
[20:09:56.407] Timer tick, asking Buildbucket for the build status
[20:09:56.642] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:56.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:139:0) after 1m0s
[20:10:56.724] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:139:0)
[20:10:56.724] Timer tick, asking Buildbucket for the build status
[20:10:57.794] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:57.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:140:0) after 1m0s
[20:11:57.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:140:0)
[20:11:57.822] Timer tick, asking Buildbucket for the build status
[20:11:57.875] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:57.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:141:0) after 1m0s
[20:12:57.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:141:0)
[20:12:57.921] Timer tick, asking Buildbucket for the build status
[20:12:57.987] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:57.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:142:0) after 1m0s
[20:13:58.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:142:0)
[20:13:58.348] Timer tick, asking Buildbucket for the build status
[20:13:58.591] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:58.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:143:0) after 1m0s
[20:14:58.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:143:0)
[20:14:58.618] Timer tick, asking Buildbucket for the build status
[20:14:58.686] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:58.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:144:0) after 1m0s
[20:15:58.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:144:0)
[20:15:58.763] Timer tick, asking Buildbucket for the build status
[20:15:58.860] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:58.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:145:0) after 1m0s
[20:16:58.972] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:145:0)
[20:16:58.972] Timer tick, asking Buildbucket for the build status
[20:16:59.069] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:59.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:146:0) after 1m0s
[20:17:59.203] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:146:0)
[20:17:59.203] Timer tick, asking Buildbucket for the build status
[20:17:59.283] Build 8871878879981656288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:59.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037308942155703584:147:0) after 1m0s
[20:18:09.257] Received PubSub notification, asking Buildbucket for the build status
[20:18:09.510] Build 8871878879981656288: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:18:09.510] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1597522688426199",
"created_by": "project:chromium",
"created_ts": "1597513996102414",
"id": "8871878879981656288",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7c62e6315427ea78fbd52d2687036dd15e741656\"}}",
"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-yk3i\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 347, \"got_angle_revision\": \"9ae6d7f2ee9e12b707483dc9cadd7c3529595016\", \"got_dawn_revision\": \"5e9b29fab92d6d79125555ea0d84afde9ace908b\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"7c62e6315427ea78fbd52d2687036dd15e741656\", \"got_revision_cp\": \"refs/heads/master@{#798452}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"abe07b9438553f741e776dedb59a2d4b502a9983\", \"got_v8_revision\": \"26d2954fbe8d1d68f3daf3fc59f1826dd91d6383\", \"got_v8_revision_cp\": \"refs/heads/8.6.384@{#1}\", \"got_webrtc_revision\": \"383f2cfca4a1ebd9626134101fe70d5a716b2d92\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31921}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7c62e6315427ea78fbd52d2687036dd15e741656\"}, \"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-yk3i\"], \"image\": [\"chrome-win10-1703-20081200-b8de48ff3cf\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5488-fc78d20\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1597513997886442",
"status": "COMPLETED",
"status_changed_ts": "1597522688807575",
"tags": [
"build_address:luci.chromium.ci/win-official/347",
"builder:win-official",
"buildset:commit/git/7c62e6315427ea78fbd52d2687036dd15e741656",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7c62e6315427ea78fbd52d2687036dd15e741656",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037308942155703584",
"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/8871878879981656288/+/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:4e0c12a1da260e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1597522688866538",
"url": "https://ci.chromium.org/b/8871878879981656288",
"utcnow_ts": "1597522689493204"
}
}
[20:18:09.510] Invocation finished in 2h24m54.865176282s with status SUCCEEDED