[04:29:04.259] New invocation is queued and will start shortly
[04:29:05.350] Starting the invocation (attempt 1)
[04:29:05.392] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:29:05.392] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:29:05.393] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9035275806166391120",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4adc9c073091c644d00c67468e76f4f6c9d57d2f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9035275806166391120",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/4adc9c073091c644d00c67468e76f4f6c9d57d2f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4adc9c073091c644d00c67468e76f4f6c9d57d2f",
"gitiles_ref:refs/heads/master"
]
}
[04:29:07.097] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1599452945630867",
"id": "8869845744041491600",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4adc9c073091c644d00c67468e76f4f6c9d57d2f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599452946821363",
"tags": [
"build_address:luci.chromium.ci/win-official/527",
"builder:win-official",
"buildset:commit/git/4adc9c073091c644d00c67468e76f4f6c9d57d2f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4adc9c073091c644d00c67468e76f4f6c9d57d2f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035275806166391120",
"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/8869845744041491600/+/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": "1599452946821510",
"url": "https://ci.chromium.org/b/8869845744041491600",
"utcnow_ts": "1599452947083232"
}
}
[04:29:07.097] Task URL: https://ci.chromium.org/b/8869845744041491600
[04:29:07.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:2:0) after 1m0s
[04:29:56.752] Received PubSub notification, asking Buildbucket for the build status
[04:29:58.989] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:07.180] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:2:0)
[04:30:07.180] Timer tick, asking Buildbucket for the build status
[04:30:07.297] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:07.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:4:0) after 1m0s
[04:31:07.664] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:4:0)
[04:31:07.664] Timer tick, asking Buildbucket for the build status
[04:31:07.897] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:07.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:5:0) after 1m0s
[04:32:08.138] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:5:0)
[04:32:08.138] Timer tick, asking Buildbucket for the build status
[04:32:08.247] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:08.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:6:0) after 1m0s
[04:33:08.386] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:6:0)
[04:33:08.386] Timer tick, asking Buildbucket for the build status
[04:33:08.508] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:08.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:7:0) after 1m0s
[04:34:08.715] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:7:0)
[04:34:08.715] Timer tick, asking Buildbucket for the build status
[04:34:08.822] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:08.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:8:0) after 1m0s
[04:35:08.880] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:8:0)
[04:35:08.880] Timer tick, asking Buildbucket for the build status
[04:35:08.968] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:08.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:9:0) after 1m0s
[04:36:09.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:9:0)
[04:36:09.013] Timer tick, asking Buildbucket for the build status
[04:36:09.186] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:09.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:10:0) after 1m0s
[04:37:09.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:10:0)
[04:37:09.411] Timer tick, asking Buildbucket for the build status
[04:37:09.746] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:09.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:11:0) after 1m0s
[04:38:09.984] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:11:0)
[04:38:09.984] Timer tick, asking Buildbucket for the build status
[04:38:10.244] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:10.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:12:0) after 1m0s
[04:39:10.390] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:12:0)
[04:39:10.390] Timer tick, asking Buildbucket for the build status
[04:39:10.522] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:10.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:13:0) after 1m0s
[04:40:10.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:13:0)
[04:40:10.597] Timer tick, asking Buildbucket for the build status
[04:40:10.798] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:10.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:14:0) after 1m0s
[04:41:10.833] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:14:0)
[04:41:10.833] Timer tick, asking Buildbucket for the build status
[04:41:10.937] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:10.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:15:0) after 1m0s
[04:42:11.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:15:0)
[04:42:11.016] Timer tick, asking Buildbucket for the build status
[04:42:11.200] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:11.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:16:0) after 1m0s
[04:43:11.221] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:16:0)
[04:43:11.221] Timer tick, asking Buildbucket for the build status
[04:43:11.341] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:11.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:17:0) after 1m0s
[04:44:11.445] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:17:0)
[04:44:11.445] Timer tick, asking Buildbucket for the build status
[04:44:11.561] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:11.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:18:0) after 1m0s
[04:45:11.689] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:18:0)
[04:45:11.689] Timer tick, asking Buildbucket for the build status
[04:45:11.885] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:11.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:19:0) after 1m0s
[04:46:11.956] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:19:0)
[04:46:11.956] Timer tick, asking Buildbucket for the build status
[04:46:12.042] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:12.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:20:0) after 1m0s
[04:47:12.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:20:0)
[04:47:12.118] Timer tick, asking Buildbucket for the build status
[04:47:12.257] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:12.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:21:0) after 1m0s
[04:48:12.257] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:21:0)
[04:48:12.257] Timer tick, asking Buildbucket for the build status
[04:48:12.823] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:12.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:22:0) after 1m0s
[04:49:12.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:22:0)
[04:49:12.927] Timer tick, asking Buildbucket for the build status
[04:49:13.035] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:13.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:23:0) after 1m0s
[04:50:13.149] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:23:0)
[04:50:13.149] Timer tick, asking Buildbucket for the build status
[04:50:13.295] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:13.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:24:0) after 1m0s
[04:51:13.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:24:0)
[04:51:13.451] Timer tick, asking Buildbucket for the build status
[04:51:13.535] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:13.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:25:0) after 1m0s
[04:52:13.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:25:0)
[04:52:13.666] Timer tick, asking Buildbucket for the build status
[04:52:13.762] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:13.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:26:0) after 1m0s
[04:53:13.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:26:0)
[04:53:13.898] Timer tick, asking Buildbucket for the build status
[04:53:13.987] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:13.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:27:0) after 1m0s
[04:54:14.018] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:27:0)
[04:54:14.018] Timer tick, asking Buildbucket for the build status
[04:54:14.217] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:14.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:28:0) after 1m0s
[04:55:14.244] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:28:0)
[04:55:14.244] Timer tick, asking Buildbucket for the build status
[04:55:14.319] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:14.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:29:0) after 1m0s
[04:56:14.389] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:29:0)
[04:56:14.390] Timer tick, asking Buildbucket for the build status
[04:56:14.532] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:14.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:30:0) after 1m0s
[04:57:14.629] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:30:0)
[04:57:14.629] Timer tick, asking Buildbucket for the build status
[04:57:14.964] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:14.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:31:0) after 1m0s
[04:58:15.092] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:31:0)
[04:58:15.092] Timer tick, asking Buildbucket for the build status
[04:58:15.203] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:15.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:32:0) after 1m0s
[04:59:15.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:32:0)
[04:59:15.227] Timer tick, asking Buildbucket for the build status
[04:59:15.373] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:15.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:33:0) after 1m0s
[05:00:15.450] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:33:0)
[05:00:15.450] Timer tick, asking Buildbucket for the build status
[05:00:15.788] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:15.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:34:0) after 1m0s
[05:01:15.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:34:0)
[05:01:15.848] Timer tick, asking Buildbucket for the build status
[05:01:16.076] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:16.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:35:0) after 1m0s
[05:02:16.390] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:35:0)
[05:02:16.390] Timer tick, asking Buildbucket for the build status
[05:02:16.523] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:16.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:36:0) after 1m0s
[05:03:16.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:36:0)
[05:03:16.855] Timer tick, asking Buildbucket for the build status
[05:03:16.930] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:16.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:37:0) after 1m0s
[05:04:17.027] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:37:0)
[05:04:17.027] Timer tick, asking Buildbucket for the build status
[05:04:17.219] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:17.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:38:0) after 1m0s
[05:05:17.286] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:38:0)
[05:05:17.286] Timer tick, asking Buildbucket for the build status
[05:05:17.435] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:17.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:39:0) after 1m0s
[05:06:17.538] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:39:0)
[05:06:17.538] Timer tick, asking Buildbucket for the build status
[05:06:17.756] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:17.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:40:0) after 1m0s
[05:07:17.807] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:40:0)
[05:07:17.807] Timer tick, asking Buildbucket for the build status
[05:07:18.088] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:18.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:41:0) after 1m0s
[05:08:18.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:41:0)
[05:08:18.283] Timer tick, asking Buildbucket for the build status
[05:08:18.433] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:18.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:42:0) after 1m0s
[05:09:18.460] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:42:0)
[05:09:18.460] Timer tick, asking Buildbucket for the build status
[05:09:18.675] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:18.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:43:0) after 1m0s
[05:10:18.838] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:43:0)
[05:10:18.838] Timer tick, asking Buildbucket for the build status
[05:10:18.953] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:18.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:44:0) after 1m0s
[05:11:19.004] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:44:0)
[05:11:19.004] Timer tick, asking Buildbucket for the build status
[05:11:19.152] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:19.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:45:0) after 1m0s
[05:12:19.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:45:0)
[05:12:19.366] Timer tick, asking Buildbucket for the build status
[05:12:19.457] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:19.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:46:0) after 1m0s
[05:13:19.667] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:46:0)
[05:13:19.667] Timer tick, asking Buildbucket for the build status
[05:13:19.885] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:19.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:47:0) after 1m0s
[05:14:20.185] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:47:0)
[05:14:20.185] Timer tick, asking Buildbucket for the build status
[05:14:20.670] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:20.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:48:0) after 1m0s
[05:15:20.834] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:48:0)
[05:15:20.835] Timer tick, asking Buildbucket for the build status
[05:15:20.925] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:20.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:49:0) after 1m0s
[05:16:21.203] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:49:0)
[05:16:21.203] Timer tick, asking Buildbucket for the build status
[05:16:21.373] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:21.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:50:0) after 1m0s
[05:17:21.448] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:50:0)
[05:17:21.448] Timer tick, asking Buildbucket for the build status
[05:17:21.548] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:21.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:51:0) after 1m0s
[05:18:21.800] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:51:0)
[05:18:21.800] Timer tick, asking Buildbucket for the build status
[05:18:21.927] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:21.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:52:0) after 1m0s
[05:19:22.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:52:0)
[05:19:22.274] Timer tick, asking Buildbucket for the build status
[05:19:22.446] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:22.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:53:0) after 1m0s
[05:20:22.816] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:53:0)
[05:20:22.816] Timer tick, asking Buildbucket for the build status
[05:20:22.889] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:22.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:54:0) after 1m0s
[05:21:23.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:54:0)
[05:21:23.899] Timer tick, asking Buildbucket for the build status
[05:21:24.126] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:24.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:55:0) after 1m0s
[05:22:24.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:55:0)
[05:22:24.429] Timer tick, asking Buildbucket for the build status
[05:22:24.489] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:24.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:56:0) after 1m0s
[05:23:24.900] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:56:0)
[05:23:24.900] Timer tick, asking Buildbucket for the build status
[05:23:24.972] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:24.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:57:0) after 1m0s
[05:24:25.250] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:57:0)
[05:24:25.250] Timer tick, asking Buildbucket for the build status
[05:24:25.403] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:25.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:58:0) after 1m0s
[05:25:25.691] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:58:0)
[05:25:25.691] Timer tick, asking Buildbucket for the build status
[05:25:25.767] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:25.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:59:0) after 1m0s
[05:26:25.935] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:59:0)
[05:26:25.935] Timer tick, asking Buildbucket for the build status
[05:26:26.007] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:26.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:60:0) after 1m0s
[05:27:26.122] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:60:0)
[05:27:26.122] Timer tick, asking Buildbucket for the build status
[05:27:26.232] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:26.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:61:0) after 1m0s
[05:28:26.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:61:0)
[05:28:26.430] Timer tick, asking Buildbucket for the build status
[05:28:26.629] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:26.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:62:0) after 1m0s
[05:29:26.740] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:62:0)
[05:29:26.740] Timer tick, asking Buildbucket for the build status
[05:29:26.953] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:26.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:63:0) after 1m0s
[05:30:27.014] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:63:0)
[05:30:27.015] Timer tick, asking Buildbucket for the build status
[05:30:27.293] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:27.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:64:0) after 1m0s
[05:31:27.423] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:64:0)
[05:31:27.423] Timer tick, asking Buildbucket for the build status
[05:31:27.585] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:27.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:65:0) after 1m0s
[05:32:27.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:65:0)
[05:32:27.729] Timer tick, asking Buildbucket for the build status
[05:32:27.785] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:27.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:66:0) after 1m0s
[05:33:28.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:66:0)
[05:33:28.006] Timer tick, asking Buildbucket for the build status
[05:33:28.091] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:28.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:67:0) after 1m0s
[05:34:28.164] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:67:0)
[05:34:28.164] Timer tick, asking Buildbucket for the build status
[05:34:28.314] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:28.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:68:0) after 1m0s
[05:35:28.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:68:0)
[05:35:28.337] Timer tick, asking Buildbucket for the build status
[05:35:28.596] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:28.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:69:0) after 1m0s
[05:36:33.651] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:69:0)
[05:36:33.651] Timer tick, asking Buildbucket for the build status
[05:36:33.740] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:33.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:70:0) after 1m0s
[05:37:33.812] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:70:0)
[05:37:33.812] Timer tick, asking Buildbucket for the build status
[05:37:33.889] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:33.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:71:0) after 1m0s
[05:38:33.973] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:71:0)
[05:38:33.973] Timer tick, asking Buildbucket for the build status
[05:38:34.211] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:34.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:72:0) after 1m0s
[05:39:34.295] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:72:0)
[05:39:34.295] Timer tick, asking Buildbucket for the build status
[05:39:34.474] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:34.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:73:0) after 1m0s
[05:40:34.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:73:0)
[05:40:34.498] Timer tick, asking Buildbucket for the build status
[05:40:34.649] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:34.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:74:0) after 1m0s
[05:41:34.681] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:74:0)
[05:41:34.681] Timer tick, asking Buildbucket for the build status
[05:41:34.752] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:34.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:75:0) after 1m0s
[05:42:34.837] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:75:0)
[05:42:34.837] Timer tick, asking Buildbucket for the build status
[05:42:34.984] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:34.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:76:0) after 1m0s
[05:43:35.046] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:76:0)
[05:43:35.046] Timer tick, asking Buildbucket for the build status
[05:43:35.137] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:35.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:77:0) after 1m0s
[05:44:35.138] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:77:0)
[05:44:35.138] Timer tick, asking Buildbucket for the build status
[05:44:36.006] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:36.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:78:0) after 1m0s
[05:45:36.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:78:0)
[05:45:36.042] Timer tick, asking Buildbucket for the build status
[05:45:36.117] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:36.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:79:0) after 1m0s
[05:46:36.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:79:0)
[05:46:36.204] Timer tick, asking Buildbucket for the build status
[05:46:36.607] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:36.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:80:0) after 1m0s
[05:47:36.679] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:80:0)
[05:47:36.679] Timer tick, asking Buildbucket for the build status
[05:47:37.028] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:37.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:81:0) after 1m0s
[05:48:37.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:81:0)
[05:48:37.066] Timer tick, asking Buildbucket for the build status
[05:48:37.545] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:37.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:82:0) after 1m0s
[05:49:37.568] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:82:0)
[05:49:37.568] Timer tick, asking Buildbucket for the build status
[05:49:37.699] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:37.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:83:0) after 1m0s
[05:50:37.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:83:0)
[05:50:37.721] Timer tick, asking Buildbucket for the build status
[05:50:37.852] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:37.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:84:0) after 1m0s
[05:51:37.893] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:84:0)
[05:51:37.893] Timer tick, asking Buildbucket for the build status
[05:51:38.204] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:38.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:85:0) after 1m0s
[05:52:38.262] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:85:0)
[05:52:38.262] Timer tick, asking Buildbucket for the build status
[05:52:38.351] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:38.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:86:0) after 1m0s
[05:53:38.428] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:86:0)
[05:53:38.428] Timer tick, asking Buildbucket for the build status
[05:53:38.664] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:38.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:87:0) after 1m0s
[05:54:38.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:87:0)
[05:54:38.742] Timer tick, asking Buildbucket for the build status
[05:54:39.109] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:39.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:88:0) after 1m0s
[05:55:39.087] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:88:0)
[05:55:39.087] Timer tick, asking Buildbucket for the build status
[05:55:39.209] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:39.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:89:0) after 1m0s
[05:56:39.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:89:0)
[05:56:39.241] Timer tick, asking Buildbucket for the build status
[05:56:39.334] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:39.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:90:0) after 1m0s
[05:57:39.353] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:90:0)
[05:57:39.353] Timer tick, asking Buildbucket for the build status
[05:57:39.550] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:39.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:91:0) after 1m0s
[05:58:39.651] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:91:0)
[05:58:39.651] Timer tick, asking Buildbucket for the build status
[05:58:40.905] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:40.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:92:0) after 1m0s
[05:59:40.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:92:0)
[05:59:40.971] Timer tick, asking Buildbucket for the build status
[05:59:41.121] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:41.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:93:0) after 1m0s
[06:00:41.177] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:93:0)
[06:00:41.177] Timer tick, asking Buildbucket for the build status
[06:00:41.290] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:41.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:94:0) after 1m0s
[06:01:41.379] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:94:0)
[06:01:41.379] Timer tick, asking Buildbucket for the build status
[06:01:41.480] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:41.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:95:0) after 1m0s
[06:02:41.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:95:0)
[06:02:41.618] Timer tick, asking Buildbucket for the build status
[06:02:41.712] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:41.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:96:0) after 1m0s
[06:03:41.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:96:0)
[06:03:41.744] Timer tick, asking Buildbucket for the build status
[06:03:42.534] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:42.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:97:0) after 1m0s
[06:04:42.623] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:97:0)
[06:04:42.623] Timer tick, asking Buildbucket for the build status
[06:04:42.812] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:42.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:98:0) after 1m0s
[06:05:42.932] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:98:0)
[06:05:42.932] Timer tick, asking Buildbucket for the build status
[06:05:43.024] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:43.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:99:0) after 1m0s
[06:06:43.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:99:0)
[06:06:43.102] Timer tick, asking Buildbucket for the build status
[06:06:43.194] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:43.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:100:0) after 1m0s
[06:07:43.220] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:100:0)
[06:07:43.220] Timer tick, asking Buildbucket for the build status
[06:07:43.287] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:43.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:101:0) after 1m0s
[06:08:43.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:101:0)
[06:08:43.314] Timer tick, asking Buildbucket for the build status
[06:08:43.447] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:43.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:102:0) after 1m0s
[06:09:43.518] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:102:0)
[06:09:43.518] Timer tick, asking Buildbucket for the build status
[06:09:43.909] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:43.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:103:0) after 1m0s
[06:10:44.012] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:103:0)
[06:10:44.012] Timer tick, asking Buildbucket for the build status
[06:10:44.232] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:44.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:104:0) after 1m0s
[06:11:44.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:104:0)
[06:11:44.263] Timer tick, asking Buildbucket for the build status
[06:11:44.347] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:44.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:105:0) after 1m0s
[06:12:44.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:105:0)
[06:12:44.447] Timer tick, asking Buildbucket for the build status
[06:12:44.544] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:44.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:106:0) after 1m0s
[06:13:44.584] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:106:0)
[06:13:44.584] Timer tick, asking Buildbucket for the build status
[06:13:44.752] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:44.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:107:0) after 1m0s
[06:14:45.078] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:107:0)
[06:14:45.078] Timer tick, asking Buildbucket for the build status
[06:14:45.423] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:45.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:108:0) after 1m0s
[06:15:45.457] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:108:0)
[06:15:45.457] Timer tick, asking Buildbucket for the build status
[06:15:45.535] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:45.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:109:0) after 1m0s
[06:16:45.568] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:109:0)
[06:16:45.568] Timer tick, asking Buildbucket for the build status
[06:16:45.659] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:45.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:110:0) after 1m0s
[06:17:45.750] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:110:0)
[06:17:45.751] Timer tick, asking Buildbucket for the build status
[06:17:45.905] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:45.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:111:0) after 1m0s
[06:18:45.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:111:0)
[06:18:45.930] Timer tick, asking Buildbucket for the build status
[06:18:46.062] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:46.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:112:0) after 1m0s
[06:19:46.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:112:0)
[06:19:46.265] Timer tick, asking Buildbucket for the build status
[06:19:46.400] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:46.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:113:0) after 1m0s
[06:20:46.544] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:113:0)
[06:20:46.544] Timer tick, asking Buildbucket for the build status
[06:20:46.795] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:46.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:114:0) after 1m0s
[06:21:46.944] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:114:0)
[06:21:46.944] Timer tick, asking Buildbucket for the build status
[06:21:47.067] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:47.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:115:0) after 1m0s
[06:22:47.251] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:115:0)
[06:22:47.252] Timer tick, asking Buildbucket for the build status
[06:22:47.353] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:47.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:116:0) after 1m0s
[06:23:47.460] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:116:0)
[06:23:47.460] Timer tick, asking Buildbucket for the build status
[06:23:47.532] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:47.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:117:0) after 1m0s
[06:24:47.679] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:117:0)
[06:24:47.679] Timer tick, asking Buildbucket for the build status
[06:24:47.888] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:47.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:118:0) after 1m0s
[06:25:48.012] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:118:0)
[06:25:48.012] Timer tick, asking Buildbucket for the build status
[06:25:48.179] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:48.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:119:0) after 1m0s
[06:26:48.277] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:119:0)
[06:26:48.277] Timer tick, asking Buildbucket for the build status
[06:26:48.369] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:48.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:120:0) after 1m0s
[06:27:48.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:120:0)
[06:27:48.451] Timer tick, asking Buildbucket for the build status
[06:27:48.610] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:48.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:121:0) after 1m0s
[06:28:48.646] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:121:0)
[06:28:48.646] Timer tick, asking Buildbucket for the build status
[06:28:48.717] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:48.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:122:0) after 1m0s
[06:29:48.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:122:0)
[06:29:48.761] Timer tick, asking Buildbucket for the build status
[06:29:48.871] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:48.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:123:0) after 1m0s
[06:30:48.940] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:123:0)
[06:30:48.940] Timer tick, asking Buildbucket for the build status
[06:30:49.044] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:49.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:124:0) after 1m0s
[06:31:49.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:124:0)
[06:31:49.086] Timer tick, asking Buildbucket for the build status
[06:31:49.253] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:49.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:125:0) after 1m0s
[06:32:49.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:125:0)
[06:32:49.282] Timer tick, asking Buildbucket for the build status
[06:32:49.436] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:49.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:126:0) after 1m0s
[06:33:49.467] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:126:0)
[06:33:49.467] Timer tick, asking Buildbucket for the build status
[06:33:49.694] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:49.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:127:0) after 1m0s
[06:34:49.765] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:127:0)
[06:34:49.765] Timer tick, asking Buildbucket for the build status
[06:34:50.074] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:50.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:128:0) after 1m0s
[06:35:50.098] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:128:0)
[06:35:50.098] Timer tick, asking Buildbucket for the build status
[06:35:50.492] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:50.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:129:0) after 1m0s
[06:36:50.530] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:129:0)
[06:36:50.530] Timer tick, asking Buildbucket for the build status
[06:36:50.617] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:50.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:130:0) after 1m0s
[06:37:50.683] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:130:0)
[06:37:50.683] Timer tick, asking Buildbucket for the build status
[06:37:50.752] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:50.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:131:0) after 1m0s
[06:38:50.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:131:0)
[06:38:50.842] Timer tick, asking Buildbucket for the build status
[06:38:50.994] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:50.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:132:0) after 1m0s
[06:39:51.045] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:132:0)
[06:39:51.045] Timer tick, asking Buildbucket for the build status
[06:39:51.487] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:51.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:133:0) after 1m0s
[06:40:51.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:133:0)
[06:40:51.545] Timer tick, asking Buildbucket for the build status
[06:40:51.709] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:51.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:134:0) after 1m0s
[06:41:51.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:134:0)
[06:41:51.732] Timer tick, asking Buildbucket for the build status
[06:41:51.825] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:51.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:135:0) after 1m0s
[06:42:51.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:135:0)
[06:42:51.963] Timer tick, asking Buildbucket for the build status
[06:42:52.220] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:52.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:136:0) after 1m0s
[06:43:52.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:136:0)
[06:43:52.299] Timer tick, asking Buildbucket for the build status
[06:43:52.539] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:52.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:137:0) after 1m0s
[06:44:52.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:137:0)
[06:44:52.529] Timer tick, asking Buildbucket for the build status
[06:44:52.656] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:52.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:138:0) after 1m0s
[06:45:52.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:138:0)
[06:45:52.707] Timer tick, asking Buildbucket for the build status
[06:45:52.789] Build 8869845744041491600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:52.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035275806166391120:139:0) after 1m0s
[06:46:12.703] Received PubSub notification, asking Buildbucket for the build status
[06:46:13.012] Build 8869845744041491600: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:46:13.012] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1599461172016469",
"created_by": "project:chromium",
"created_ts": "1599452945630867",
"id": "8869845744041491600",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4adc9c073091c644d00c67468e76f4f6c9d57d2f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"4adc9c073091c644d00c67468e76f4f6c9d57d2f\", \"project\": \"chromium/src\"}], \"$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-0oxp\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 527, \"got_angle_revision\": \"72f6b99c29f28bd7461f383e9b7223a8f670b7cc\", \"got_dawn_revision\": \"62442aceb0fb8af0c1edd07b5f18b4ec181b8fbf\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"4adc9c073091c644d00c67468e76f4f6c9d57d2f\", \"got_revision_cp\": \"refs/heads/master@{#804658}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"e63aa953ddfd935a022090b3ef221438a328d1a1\", \"got_v8_revision\": \"ef9e56c6d3efce74216a967f3774a5c8bc8c142a\", \"got_v8_revision_cp\": \"refs/heads/8.7.74@{#1}\", \"got_webrtc_revision\": \"d46db9f1523ae45909b4a6fdc90a140443068bc6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32036}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4adc9c073091c644d00c67468e76f4f6c9d57d2f\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-8-0oxp\"], \"image\": [\"chrome-win10-1703-20083100-22dcea0839e\"], \"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\": [\"5508-61c5b03\"], \"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": "1599452995794167",
"status": "COMPLETED",
"status_changed_ts": "1599461172361864",
"tags": [
"build_address:luci.chromium.ci/win-official/527",
"builder:win-official",
"buildset:commit/git/4adc9c073091c644d00c67468e76f4f6c9d57d2f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4adc9c073091c644d00c67468e76f4f6c9d57d2f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035275806166391120",
"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/8869845744041491600/+/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:4e7fa4aaa6fc5410",
"user_agent:luci-scheduler"
],
"updated_ts": "1599461172366191",
"url": "https://ci.chromium.org/b/8869845744041491600",
"utcnow_ts": "1599461172987388"
}
}
[06:46:13.012] Invocation finished in 2h17m8.792025953s with status SUCCEEDED