[02:36:46.773] New invocation is queued and will start shortly
[02:36:50.327] Starting the invocation (attempt 1)
[02:36:50.367] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:36:50.367] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:36:50.367] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9038634958666931232",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f152c1337325da944f8ca6deb673a4f30378f9b2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9038634958666931232",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/f152c1337325da944f8ca6deb673a4f30378f9b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f152c1337325da944f8ca6deb673a4f30378f9b2",
"gitiles_ref:refs/heads/master"
]
}
[02:36:51.525] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1596249410488651",
"id": "8873204894106238240",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f152c1337325da944f8ca6deb673a4f30378f9b2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1596249411280990",
"tags": [
"build_address:luci.chromium.ci/win-official/217",
"builder:win-official",
"buildset:commit/git/f152c1337325da944f8ca6deb673a4f30378f9b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f152c1337325da944f8ca6deb673a4f30378f9b2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9038634958666931232",
"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/8873204894106238240/+/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": "1596249411281139",
"url": "https://ci.chromium.org/b/8873204894106238240",
"utcnow_ts": "1596249411512411"
}
}
[02:36:51.525] Task URL: https://ci.chromium.org/b/8873204894106238240
[02:36:51.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:2:0) after 1m0s
[02:36:53.897] Received PubSub notification, asking Buildbucket for the build status
[02:36:54.309] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:51.691] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:2:0)
[02:37:51.691] Timer tick, asking Buildbucket for the build status
[02:37:51.903] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:51.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:4:0) after 1m0s
[02:38:52.056] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:4:0)
[02:38:52.056] Timer tick, asking Buildbucket for the build status
[02:38:52.353] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:52.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:5:0) after 1m0s
[02:39:52.389] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:5:0)
[02:39:52.389] Timer tick, asking Buildbucket for the build status
[02:39:52.471] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:52.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:6:0) after 1m0s
[02:40:52.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:6:0)
[02:40:52.501] Timer tick, asking Buildbucket for the build status
[02:40:52.571] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:52.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:7:0) after 1m0s
[02:41:52.568] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:7:0)
[02:41:52.568] Timer tick, asking Buildbucket for the build status
[02:41:52.708] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:52.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:8:0) after 1m0s
[02:42:52.792] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:8:0)
[02:42:52.792] Timer tick, asking Buildbucket for the build status
[02:42:53.040] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:53.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:9:0) after 1m0s
[02:43:53.040] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:9:0)
[02:43:53.040] Timer tick, asking Buildbucket for the build status
[02:43:53.398] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:53.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:10:0) after 1m0s
[02:44:53.453] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:10:0)
[02:44:53.453] Timer tick, asking Buildbucket for the build status
[02:44:53.803] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:53.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:11:0) after 1m0s
[02:45:53.834] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:11:0)
[02:45:53.834] Timer tick, asking Buildbucket for the build status
[02:45:54.045] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:54.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:12:0) after 1m0s
[02:46:54.099] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:12:0)
[02:46:54.099] Timer tick, asking Buildbucket for the build status
[02:46:55.015] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:55.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:13:0) after 1m0s
[02:47:55.064] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:13:0)
[02:47:55.064] Timer tick, asking Buildbucket for the build status
[02:47:55.236] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:55.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:14:0) after 1m0s
[02:48:55.257] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:14:0)
[02:48:55.257] Timer tick, asking Buildbucket for the build status
[02:48:55.334] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:55.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:15:0) after 1m0s
[02:49:55.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:15:0)
[02:49:55.372] Timer tick, asking Buildbucket for the build status
[02:49:55.477] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:55.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:16:0) after 1m0s
[02:50:55.576] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:16:0)
[02:50:55.576] Timer tick, asking Buildbucket for the build status
[02:50:55.908] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:55.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:17:0) after 1m0s
[02:51:55.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:17:0)
[02:51:55.930] Timer tick, asking Buildbucket for the build status
[02:51:56.066] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:56.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:18:0) after 1m0s
[02:52:56.114] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:18:0)
[02:52:56.114] Timer tick, asking Buildbucket for the build status
[02:52:56.401] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:56.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:19:0) after 1m0s
[02:53:56.434] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:19:0)
[02:53:56.434] Timer tick, asking Buildbucket for the build status
[02:53:56.547] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:56.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:20:0) after 1m0s
[02:54:56.581] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:20:0)
[02:54:56.581] Timer tick, asking Buildbucket for the build status
[02:54:56.814] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:56.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:21:0) after 1m0s
[02:55:56.871] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:21:0)
[02:55:56.871] Timer tick, asking Buildbucket for the build status
[02:55:57.022] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:57.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:22:0) after 1m0s
[02:56:57.050] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:22:0)
[02:56:57.050] Timer tick, asking Buildbucket for the build status
[02:56:57.423] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:57.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:23:0) after 1m0s
[02:57:57.426] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:23:0)
[02:57:57.426] Timer tick, asking Buildbucket for the build status
[02:57:57.733] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:57.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:24:0) after 1m0s
[02:58:57.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:24:0)
[02:58:57.744] Timer tick, asking Buildbucket for the build status
[02:58:57.816] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:57.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:25:0) after 1m0s
[02:59:57.876] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:25:0)
[02:59:57.876] Timer tick, asking Buildbucket for the build status
[02:59:57.966] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:57.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:26:0) after 1m0s
[03:00:57.993] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:26:0)
[03:00:57.993] Timer tick, asking Buildbucket for the build status
[03:00:58.082] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:58.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:27:0) after 1m0s
[03:01:58.168] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:27:0)
[03:01:58.168] Timer tick, asking Buildbucket for the build status
[03:01:58.304] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:58.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:28:0) after 1m0s
[03:02:58.440] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:28:0)
[03:02:58.440] Timer tick, asking Buildbucket for the build status
[03:02:58.725] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:58.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:29:0) after 1m0s
[03:03:58.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:29:0)
[03:03:58.839] Timer tick, asking Buildbucket for the build status
[03:03:58.940] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:58.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:30:0) after 1m0s
[03:04:59.088] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:30:0)
[03:04:59.088] Timer tick, asking Buildbucket for the build status
[03:04:59.159] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:59.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:31:0) after 1m0s
[03:06:00.002] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:31:0)
[03:06:00.002] Timer tick, asking Buildbucket for the build status
[03:06:00.290] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:00.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:32:0) after 1m0s
[03:07:00.626] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:32:0)
[03:07:00.626] Timer tick, asking Buildbucket for the build status
[03:07:00.819] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:00.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:33:0) after 1m0s
[03:08:00.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:33:0)
[03:08:00.819] Timer tick, asking Buildbucket for the build status
[03:08:00.888] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:00.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:34:0) after 1m0s
[03:09:02.286] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:34:0)
[03:09:02.286] Timer tick, asking Buildbucket for the build status
[03:09:02.393] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:02.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:35:0) after 1m0s
[03:10:02.433] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:35:0)
[03:10:02.433] Timer tick, asking Buildbucket for the build status
[03:10:02.519] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:02.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:36:0) after 1m0s
[03:11:02.563] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:36:0)
[03:11:02.563] Timer tick, asking Buildbucket for the build status
[03:11:02.622] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:02.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:37:0) after 1m0s
[03:12:02.751] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:37:0)
[03:12:02.751] Timer tick, asking Buildbucket for the build status
[03:12:03.072] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:03.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:38:0) after 1m0s
[03:13:03.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:38:0)
[03:13:03.104] Timer tick, asking Buildbucket for the build status
[03:13:03.310] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:03.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:39:0) after 1m0s
[03:14:03.354] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:39:0)
[03:14:03.354] Timer tick, asking Buildbucket for the build status
[03:14:03.443] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:03.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:40:0) after 1m0s
[03:15:03.605] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:40:0)
[03:15:03.605] Timer tick, asking Buildbucket for the build status
[03:15:03.715] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:03.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:41:0) after 1m0s
[03:16:03.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:41:0)
[03:16:03.919] Timer tick, asking Buildbucket for the build status
[03:16:04.183] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:04.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:42:0) after 1m0s
[03:17:04.477] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:42:0)
[03:17:04.477] Timer tick, asking Buildbucket for the build status
[03:17:04.650] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:04.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:43:0) after 1m0s
[03:18:04.722] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:43:0)
[03:18:04.722] Timer tick, asking Buildbucket for the build status
[03:18:04.885] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:04.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:44:0) after 1m0s
[03:19:04.975] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:44:0)
[03:19:04.975] Timer tick, asking Buildbucket for the build status
[03:19:05.299] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:05.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:45:0) after 1m0s
[03:20:05.625] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:45:0)
[03:20:05.625] Timer tick, asking Buildbucket for the build status
[03:20:05.730] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:05.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:46:0) after 1m0s
[03:21:06.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:46:0)
[03:21:06.145] Timer tick, asking Buildbucket for the build status
[03:21:06.310] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:06.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:47:0) after 1m0s
[03:22:06.467] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:47:0)
[03:22:06.467] Timer tick, asking Buildbucket for the build status
[03:22:06.665] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:06.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:48:0) after 1m0s
[03:23:06.704] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:48:0)
[03:23:06.704] Timer tick, asking Buildbucket for the build status
[03:23:06.773] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:06.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:49:0) after 1m0s
[03:24:07.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:49:0)
[03:24:07.034] Timer tick, asking Buildbucket for the build status
[03:24:07.133] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:07.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:50:0) after 1m0s
[03:25:07.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:50:0)
[03:25:07.151] Timer tick, asking Buildbucket for the build status
[03:25:07.365] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:07.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:51:0) after 1m0s
[03:26:07.454] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:51:0)
[03:26:07.454] Timer tick, asking Buildbucket for the build status
[03:26:08.000] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:08.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:52:0) after 1m0s
[03:27:08.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:52:0)
[03:27:08.125] Timer tick, asking Buildbucket for the build status
[03:27:08.254] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:08.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:53:0) after 1m0s
[03:28:08.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:53:0)
[03:28:08.337] Timer tick, asking Buildbucket for the build status
[03:28:08.422] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:08.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:54:0) after 1m0s
[03:29:08.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:54:0)
[03:29:08.555] Timer tick, asking Buildbucket for the build status
[03:29:09.154] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:09.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:55:0) after 1m0s
[03:30:09.375] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:55:0)
[03:30:09.376] Timer tick, asking Buildbucket for the build status
[03:30:09.512] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:09.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:56:0) after 1m0s
[03:31:09.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:56:0)
[03:31:09.805] Timer tick, asking Buildbucket for the build status
[03:31:09.985] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:09.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:57:0) after 1m0s
[03:32:10.094] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:57:0)
[03:32:10.094] Timer tick, asking Buildbucket for the build status
[03:32:10.245] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:10.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:58:0) after 1m0s
[03:33:10.406] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:58:0)
[03:33:10.406] Timer tick, asking Buildbucket for the build status
[03:33:10.607] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:10.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:59:0) after 1m0s
[03:34:10.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:59:0)
[03:34:10.669] Timer tick, asking Buildbucket for the build status
[03:34:10.763] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:10.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:60:0) after 1m0s
[03:35:10.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:60:0)
[03:35:10.912] Timer tick, asking Buildbucket for the build status
[03:35:11.300] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:11.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:61:0) after 1m0s
[03:36:11.467] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:61:0)
[03:36:11.467] Timer tick, asking Buildbucket for the build status
[03:36:11.622] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:11.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:62:0) after 1m0s
[03:37:11.644] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:62:0)
[03:37:11.644] Timer tick, asking Buildbucket for the build status
[03:37:11.753] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:11.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:63:0) after 1m0s
[03:38:11.777] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:63:0)
[03:38:11.777] Timer tick, asking Buildbucket for the build status
[03:38:11.873] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:11.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:64:0) after 1m0s
[03:39:11.973] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:64:0)
[03:39:11.973] Timer tick, asking Buildbucket for the build status
[03:39:12.045] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:12.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:65:0) after 1m0s
[03:40:12.170] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:65:0)
[03:40:12.170] Timer tick, asking Buildbucket for the build status
[03:40:12.316] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:12.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:66:0) after 1m0s
[03:41:12.418] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:66:0)
[03:41:12.418] Timer tick, asking Buildbucket for the build status
[03:41:12.530] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:12.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:67:0) after 1m0s
[03:42:12.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:67:0)
[03:42:12.604] Timer tick, asking Buildbucket for the build status
[03:42:12.723] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:12.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:68:0) after 1m0s
[03:43:12.798] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:68:0)
[03:43:12.798] Timer tick, asking Buildbucket for the build status
[03:43:12.969] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:12.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:69:0) after 1m0s
[03:44:13.024] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:69:0)
[03:44:13.024] Timer tick, asking Buildbucket for the build status
[03:44:13.145] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:13.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:70:0) after 1m0s
[03:45:13.186] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:70:0)
[03:45:13.186] Timer tick, asking Buildbucket for the build status
[03:45:13.291] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:13.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:71:0) after 1m0s
[03:46:13.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:71:0)
[03:46:13.392] Timer tick, asking Buildbucket for the build status
[03:46:13.462] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:13.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:72:0) after 1m0s
[03:47:13.522] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:72:0)
[03:47:13.522] Timer tick, asking Buildbucket for the build status
[03:47:14.093] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:14.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:73:0) after 1m0s
[03:48:14.148] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:73:0)
[03:48:14.148] Timer tick, asking Buildbucket for the build status
[03:48:14.406] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:14.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:74:0) after 1m0s
[03:49:14.427] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:74:0)
[03:49:14.427] Timer tick, asking Buildbucket for the build status
[03:49:14.539] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:14.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:75:0) after 1m0s
[03:50:14.526] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:75:0)
[03:50:14.526] Timer tick, asking Buildbucket for the build status
[03:50:14.601] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:14.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:76:0) after 1m0s
[03:51:14.613] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:76:0)
[03:51:14.613] Timer tick, asking Buildbucket for the build status
[03:51:14.699] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:14.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:77:0) after 1m0s
[03:52:14.772] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:77:0)
[03:52:14.772] Timer tick, asking Buildbucket for the build status
[03:52:14.929] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:14.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:78:0) after 1m0s
[03:53:15.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:78:0)
[03:53:15.016] Timer tick, asking Buildbucket for the build status
[03:53:15.140] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:15.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:79:0) after 1m0s
[03:54:15.291] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:79:0)
[03:54:15.291] Timer tick, asking Buildbucket for the build status
[03:54:15.564] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:15.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:80:0) after 1m0s
[03:55:15.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:80:0)
[03:55:15.678] Timer tick, asking Buildbucket for the build status
[03:55:15.875] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:15.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:81:0) after 1m0s
[03:56:15.977] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:81:0)
[03:56:15.977] Timer tick, asking Buildbucket for the build status
[03:56:16.061] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:16.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:82:0) after 1m0s
[03:57:16.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:82:0)
[03:57:16.160] Timer tick, asking Buildbucket for the build status
[03:57:16.328] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:16.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:83:0) after 1m0s
[03:58:16.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:83:0)
[03:58:16.452] Timer tick, asking Buildbucket for the build status
[03:58:16.730] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:16.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:84:0) after 1m0s
[03:59:16.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:84:0)
[03:59:16.758] Timer tick, asking Buildbucket for the build status
[03:59:16.839] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:16.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:85:0) after 1m0s
[04:00:16.948] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:85:0)
[04:00:16.948] Timer tick, asking Buildbucket for the build status
[04:00:17.042] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:17.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:86:0) after 1m0s
[04:01:17.134] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:86:0)
[04:01:17.134] Timer tick, asking Buildbucket for the build status
[04:01:17.231] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:17.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:87:0) after 1m0s
[04:02:17.318] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:87:0)
[04:02:17.318] Timer tick, asking Buildbucket for the build status
[04:02:17.683] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:17.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:88:0) after 1m0s
[04:03:17.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:88:0)
[04:03:17.811] Timer tick, asking Buildbucket for the build status
[04:03:17.889] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:17.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:89:0) after 1m0s
[04:04:17.993] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:89:0)
[04:04:17.993] Timer tick, asking Buildbucket for the build status
[04:04:18.067] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:18.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:90:0) after 1m0s
[04:05:18.150] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:90:0)
[04:05:18.150] Timer tick, asking Buildbucket for the build status
[04:05:18.220] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:18.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:91:0) after 1m0s
[04:06:18.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:91:0)
[04:06:18.258] Timer tick, asking Buildbucket for the build status
[04:06:18.333] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:18.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:92:0) after 1m0s
[04:07:18.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:92:0)
[04:07:18.388] Timer tick, asking Buildbucket for the build status
[04:07:18.535] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:18.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:93:0) after 1m0s
[04:08:18.646] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:93:0)
[04:08:18.646] Timer tick, asking Buildbucket for the build status
[04:08:18.905] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:18.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:94:0) after 1m0s
[04:09:19.004] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:94:0)
[04:09:19.004] Timer tick, asking Buildbucket for the build status
[04:09:19.067] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:19.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:95:0) after 1m0s
[04:10:19.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:95:0)
[04:10:19.112] Timer tick, asking Buildbucket for the build status
[04:10:19.386] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:19.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:96:0) after 1m0s
[04:11:19.467] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:96:0)
[04:11:19.467] Timer tick, asking Buildbucket for the build status
[04:11:19.547] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:19.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:97:0) after 1m0s
[04:12:19.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:97:0)
[04:12:19.676] Timer tick, asking Buildbucket for the build status
[04:12:19.798] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:19.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:98:0) after 1m0s
[04:13:19.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:98:0)
[04:13:19.899] Timer tick, asking Buildbucket for the build status
[04:13:20.042] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:20.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:99:0) after 1m0s
[04:14:20.113] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:99:0)
[04:14:20.113] Timer tick, asking Buildbucket for the build status
[04:14:20.522] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:20.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:100:0) after 1m0s
[04:15:20.583] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:100:0)
[04:15:20.583] Timer tick, asking Buildbucket for the build status
[04:15:20.638] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:20.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:101:0) after 1m0s
[04:16:20.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:101:0)
[04:16:20.666] Timer tick, asking Buildbucket for the build status
[04:16:20.786] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:20.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:102:0) after 1m0s
[04:17:20.816] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:102:0)
[04:17:20.816] Timer tick, asking Buildbucket for the build status
[04:17:20.972] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:20.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:103:0) after 1m0s
[04:18:21.026] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:103:0)
[04:18:21.026] Timer tick, asking Buildbucket for the build status
[04:18:21.117] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:21.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:104:0) after 1m0s
[04:19:21.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:104:0)
[04:19:21.223] Timer tick, asking Buildbucket for the build status
[04:19:21.338] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:21.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:105:0) after 1m0s
[04:20:21.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:105:0)
[04:20:21.316] Timer tick, asking Buildbucket for the build status
[04:20:21.399] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:21.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:106:0) after 1m0s
[04:21:21.617] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:106:0)
[04:21:21.617] Timer tick, asking Buildbucket for the build status
[04:21:21.813] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:21.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:107:0) after 1m0s
[04:22:21.853] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:107:0)
[04:22:21.853] Timer tick, asking Buildbucket for the build status
[04:22:22.122] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:22.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:108:0) after 1m0s
[04:23:22.210] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:108:0)
[04:23:22.210] Timer tick, asking Buildbucket for the build status
[04:23:22.304] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:22.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:109:0) after 1m0s
[04:24:22.584] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:109:0)
[04:24:22.584] Timer tick, asking Buildbucket for the build status
[04:24:22.911] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:22.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:110:0) after 1m0s
[04:25:22.964] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:110:0)
[04:25:22.964] Timer tick, asking Buildbucket for the build status
[04:25:23.118] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:23.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:111:0) after 1m0s
[04:26:23.166] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:111:0)
[04:26:23.166] Timer tick, asking Buildbucket for the build status
[04:26:23.236] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:23.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:112:0) after 1m0s
[04:27:23.253] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:112:0)
[04:27:23.253] Timer tick, asking Buildbucket for the build status
[04:27:23.448] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:23.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:113:0) after 1m0s
[04:28:23.466] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:113:0)
[04:28:23.466] Timer tick, asking Buildbucket for the build status
[04:28:23.625] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:23.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:114:0) after 1m0s
[04:29:23.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:114:0)
[04:29:23.779] Timer tick, asking Buildbucket for the build status
[04:29:23.940] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:23.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:115:0) after 1m0s
[04:30:24.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:115:0)
[04:30:24.038] Timer tick, asking Buildbucket for the build status
[04:30:24.209] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:24.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:116:0) after 1m0s
[04:31:24.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:116:0)
[04:31:24.204] Timer tick, asking Buildbucket for the build status
[04:31:24.285] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:24.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:117:0) after 1m0s
[04:32:24.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:117:0)
[04:32:24.316] Timer tick, asking Buildbucket for the build status
[04:32:24.430] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:24.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:118:0) after 1m0s
[04:33:24.467] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:118:0)
[04:33:24.467] Timer tick, asking Buildbucket for the build status
[04:33:24.570] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:24.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:119:0) after 1m0s
[04:34:24.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:119:0)
[04:34:24.607] Timer tick, asking Buildbucket for the build status
[04:34:24.705] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:24.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:120:0) after 1m0s
[04:35:24.735] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:120:0)
[04:35:24.735] Timer tick, asking Buildbucket for the build status
[04:35:24.906] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:24.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:121:0) after 1m0s
[04:36:24.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:121:0)
[04:36:24.955] Timer tick, asking Buildbucket for the build status
[04:36:25.051] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:25.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:122:0) after 1m0s
[04:37:25.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:122:0)
[04:37:25.086] Timer tick, asking Buildbucket for the build status
[04:37:25.249] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:25.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:123:0) after 1m0s
[04:38:25.257] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:123:0)
[04:38:25.257] Timer tick, asking Buildbucket for the build status
[04:38:25.419] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:25.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:124:0) after 1m0s
[04:39:25.441] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:124:0)
[04:39:25.441] Timer tick, asking Buildbucket for the build status
[04:39:25.537] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:25.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:125:0) after 1m0s
[04:40:25.599] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:125:0)
[04:40:25.599] Timer tick, asking Buildbucket for the build status
[04:40:25.748] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:25.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:126:0) after 1m0s
[04:41:25.862] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:126:0)
[04:41:25.862] Timer tick, asking Buildbucket for the build status
[04:41:26.018] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:26.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:127:0) after 1m0s
[04:42:26.071] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:127:0)
[04:42:26.071] Timer tick, asking Buildbucket for the build status
[04:42:26.295] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:26.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:128:0) after 1m0s
[04:43:26.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:128:0)
[04:43:26.328] Timer tick, asking Buildbucket for the build status
[04:43:26.423] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:26.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:129:0) after 1m0s
[04:44:26.476] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:129:0)
[04:44:26.476] Timer tick, asking Buildbucket for the build status
[04:44:27.147] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:27.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:130:0) after 1m0s
[04:45:27.259] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:130:0)
[04:45:27.259] Timer tick, asking Buildbucket for the build status
[04:45:27.333] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:27.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:131:0) after 1m0s
[04:46:27.365] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:131:0)
[04:46:27.365] Timer tick, asking Buildbucket for the build status
[04:46:27.519] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:27.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:132:0) after 1m0s
[04:47:27.538] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:132:0)
[04:47:27.538] Timer tick, asking Buildbucket for the build status
[04:47:27.664] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:27.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:133:0) after 1m0s
[04:48:27.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:133:0)
[04:48:27.701] Timer tick, asking Buildbucket for the build status
[04:48:27.925] Build 8873204894106238240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:27.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038634958666931232:134:0) after 1m0s
[04:48:35.556] Received PubSub notification, asking Buildbucket for the build status
[04:48:35.729] Build 8873204894106238240: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:48:35.729] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1596257314895559",
"created_by": "project:chromium",
"created_ts": "1596249410488651",
"id": "8873204894106238240",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f152c1337325da944f8ca6deb673a4f30378f9b2\"}}",
"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-0-fdfa\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 217, \"got_angle_revision\": \"dae778938f21de7b5611e1963c58911f34f73937\", \"got_dawn_revision\": \"fada50119020483021a6b620c8fb85d62fc3e8f0\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"f152c1337325da944f8ca6deb673a4f30378f9b2\", \"got_revision_cp\": \"refs/heads/master@{#793856}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"aa5bd7657e6cc140b95f3f070f011c65213a2cc1\", \"got_v8_revision\": \"ee8cec922c940067397bcc70a229b664114068fe\", \"got_v8_revision_cp\": \"refs/heads/8.6.310@{#1}\", \"got_webrtc_revision\": \"81bbd7199a2e97680a4488c2da4f8248137e12e0\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31819}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f152c1337325da944f8ca6deb673a4f30378f9b2\"}, \"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-0-fdfa\"], \"image\": [\"chrome-win10-1703-20071600-faa8609efbc\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5436-adf7f8a\"], \"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": "1596249413040027",
"status": "COMPLETED",
"status_changed_ts": "1596257315202799",
"tags": [
"build_address:luci.chromium.ci/win-official/217",
"builder:win-official",
"buildset:commit/git/f152c1337325da944f8ca6deb673a4f30378f9b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f152c1337325da944f8ca6deb673a4f30378f9b2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9038634958666931232",
"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/8873204894106238240/+/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:4dc0b297eb7a1710",
"user_agent:luci-scheduler"
],
"updated_ts": "1596257315218679",
"url": "https://ci.chromium.org/b/8873204894106238240",
"utcnow_ts": "1596257315712657"
}
}
[04:48:35.729] Invocation finished in 2h11m48.974782359s with status SUCCEEDED