[06:46:15.268] New invocation is queued and will start shortly
[06:46:16.378] Starting the invocation (attempt 1)
[06:46:16.420] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:46:16.420] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:46:16.420] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9035267175308211296",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b351074253236dae5054b95ea8a96f1384919faa\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9035267175308211296",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/b351074253236dae5054b95ea8a96f1384919faa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b351074253236dae5054b95ea8a96f1384919faa",
"gitiles_ref:refs/heads/master"
]
}
[06:46:18.057] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1599461176517191",
"id": "8869837113330620512",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b351074253236dae5054b95ea8a96f1384919faa\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599461177352647",
"tags": [
"build_address:luci.chromium.ci/win-official/528",
"builder:win-official",
"buildset:commit/git/b351074253236dae5054b95ea8a96f1384919faa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b351074253236dae5054b95ea8a96f1384919faa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035267175308211296",
"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/8869837113330620512/+/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": "1599461177353925",
"url": "https://ci.chromium.org/b/8869837113330620512",
"utcnow_ts": "1599461178047731"
}
}
[06:46:18.057] Task URL: https://ci.chromium.org/b/8869837113330620512
[06:46:18.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:2:0) after 1m0s
[06:47:03.954] Received PubSub notification, asking Buildbucket for the build status
[06:47:04.085] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:18.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:2:0)
[06:47:18.217] Timer tick, asking Buildbucket for the build status
[06:47:18.792] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:18.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:4:0) after 1m0s
[06:48:18.814] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:4:0)
[06:48:18.814] Timer tick, asking Buildbucket for the build status
[06:48:19.023] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:19.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:5:0) after 1m0s
[06:49:19.126] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:5:0)
[06:49:19.126] Timer tick, asking Buildbucket for the build status
[06:49:19.223] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:19.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:6:0) after 1m0s
[06:50:19.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:6:0)
[06:50:19.505] Timer tick, asking Buildbucket for the build status
[06:50:19.628] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:19.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:7:0) after 1m0s
[06:51:19.746] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:7:0)
[06:51:19.746] Timer tick, asking Buildbucket for the build status
[06:51:19.906] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:19.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:8:0) after 1m0s
[06:52:19.999] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:8:0)
[06:52:19.999] Timer tick, asking Buildbucket for the build status
[06:52:20.151] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:20.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:9:0) after 1m0s
[06:53:20.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:9:0)
[06:53:20.364] Timer tick, asking Buildbucket for the build status
[06:53:20.474] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:20.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:10:0) after 1m0s
[06:54:20.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:10:0)
[06:54:20.590] Timer tick, asking Buildbucket for the build status
[06:54:20.669] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:20.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:11:0) after 1m0s
[06:55:20.975] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:11:0)
[06:55:20.975] Timer tick, asking Buildbucket for the build status
[06:55:21.147] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:21.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:12:0) after 1m0s
[06:56:21.211] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:12:0)
[06:56:21.211] Timer tick, asking Buildbucket for the build status
[06:56:21.350] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:21.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:13:0) after 1m0s
[06:57:21.375] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:13:0)
[06:57:21.375] Timer tick, asking Buildbucket for the build status
[06:57:21.612] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:21.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:14:0) after 1m0s
[06:58:21.815] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:14:0)
[06:58:21.815] Timer tick, asking Buildbucket for the build status
[06:58:21.922] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:21.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:15:0) after 1m0s
[06:59:21.948] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:15:0)
[06:59:21.948] Timer tick, asking Buildbucket for the build status
[06:59:22.217] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:22.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:16:0) after 1m0s
[07:00:22.517] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:16:0)
[07:00:22.517] Timer tick, asking Buildbucket for the build status
[07:00:22.714] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:22.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:17:0) after 1m0s
[07:01:22.710] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:17:0)
[07:01:22.710] Timer tick, asking Buildbucket for the build status
[07:01:23.096] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:23.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:18:0) after 1m0s
[07:02:23.100] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:18:0)
[07:02:23.100] Timer tick, asking Buildbucket for the build status
[07:02:23.173] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:23.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:19:0) after 1m0s
[07:03:23.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:19:0)
[07:03:23.241] Timer tick, asking Buildbucket for the build status
[07:03:23.369] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:23.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:20:0) after 1m0s
[07:04:23.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:20:0)
[07:04:23.413] Timer tick, asking Buildbucket for the build status
[07:04:23.549] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:23.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:21:0) after 1m0s
[07:05:23.624] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:21:0)
[07:05:23.624] Timer tick, asking Buildbucket for the build status
[07:05:23.716] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:23.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:22:0) after 1m0s
[07:06:23.829] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:22:0)
[07:06:23.829] Timer tick, asking Buildbucket for the build status
[07:06:23.994] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:23.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:23:0) after 1m0s
[07:07:23.989] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:23:0)
[07:07:23.989] Timer tick, asking Buildbucket for the build status
[07:07:24.142] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:24.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:24:0) after 1m0s
[07:08:24.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:24:0)
[07:08:24.217] Timer tick, asking Buildbucket for the build status
[07:08:24.349] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:24.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:25:0) after 1m0s
[07:09:24.421] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:25:0)
[07:09:24.421] Timer tick, asking Buildbucket for the build status
[07:09:24.571] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:24.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:26:0) after 1m0s
[07:10:24.579] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:26:0)
[07:10:24.579] Timer tick, asking Buildbucket for the build status
[07:10:24.651] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:24.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:27:0) after 1m0s
[07:11:24.638] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:27:0)
[07:11:24.638] Timer tick, asking Buildbucket for the build status
[07:11:25.011] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:25.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:28:0) after 1m0s
[07:12:25.069] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:28:0)
[07:12:25.069] Timer tick, asking Buildbucket for the build status
[07:12:25.332] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:25.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:29:0) after 1m0s
[07:13:25.443] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:29:0)
[07:13:25.443] Timer tick, asking Buildbucket for the build status
[07:13:25.568] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:25.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:30:0) after 1m0s
[07:14:25.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:30:0)
[07:14:25.603] Timer tick, asking Buildbucket for the build status
[07:14:25.724] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:25.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:31:0) after 1m0s
[07:15:25.802] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:31:0)
[07:15:25.802] Timer tick, asking Buildbucket for the build status
[07:15:25.888] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:25.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:32:0) after 1m0s
[07:16:25.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:32:0)
[07:16:25.927] Timer tick, asking Buildbucket for the build status
[07:16:26.055] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:26.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:33:0) after 1m0s
[07:17:26.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:33:0)
[07:17:26.152] Timer tick, asking Buildbucket for the build status
[07:17:26.566] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:26.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:34:0) after 1m0s
[07:18:26.796] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:34:0)
[07:18:26.796] Timer tick, asking Buildbucket for the build status
[07:18:26.944] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:26.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:35:0) after 1m0s
[07:19:26.979] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:35:0)
[07:19:26.979] Timer tick, asking Buildbucket for the build status
[07:19:27.074] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:27.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:36:0) after 1m0s
[07:20:27.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:36:0)
[07:20:27.118] Timer tick, asking Buildbucket for the build status
[07:20:27.194] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:27.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:37:0) after 1m0s
[07:21:27.219] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:37:0)
[07:21:27.219] Timer tick, asking Buildbucket for the build status
[07:21:27.398] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:27.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:38:0) after 1m0s
[07:22:27.486] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:38:0)
[07:22:27.486] Timer tick, asking Buildbucket for the build status
[07:22:28.025] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:28.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:39:0) after 1m0s
[07:23:28.677] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:39:0)
[07:23:28.677] Timer tick, asking Buildbucket for the build status
[07:23:28.970] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:28.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:40:0) after 1m0s
[07:24:29.698] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:40:0)
[07:24:29.698] Timer tick, asking Buildbucket for the build status
[07:24:29.893] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:29.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:41:0) after 1m0s
[07:25:30.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:41:0)
[07:25:30.130] Timer tick, asking Buildbucket for the build status
[07:25:30.270] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:30.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:42:0) after 1m0s
[07:26:30.764] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:42:0)
[07:26:30.764] Timer tick, asking Buildbucket for the build status
[07:26:31.109] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:31.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:43:0) after 1m0s
[07:27:31.297] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:43:0)
[07:27:31.297] Timer tick, asking Buildbucket for the build status
[07:27:31.408] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:31.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:44:0) after 1m0s
[07:28:37.932] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:44:0)
[07:28:37.932] Timer tick, asking Buildbucket for the build status
[07:28:38.031] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:38.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:45:0) after 1m0s
[07:29:38.158] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:45:0)
[07:29:38.158] Timer tick, asking Buildbucket for the build status
[07:29:38.306] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:38.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:46:0) after 1m0s
[07:30:38.397] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:46:0)
[07:30:38.397] Timer tick, asking Buildbucket for the build status
[07:30:38.655] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:38.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:47:0) after 1m0s
[07:31:38.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:47:0)
[07:31:38.763] Timer tick, asking Buildbucket for the build status
[07:31:38.976] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:38.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:48:0) after 1m0s
[07:32:38.998] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:48:0)
[07:32:38.998] Timer tick, asking Buildbucket for the build status
[07:32:39.236] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:39.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:49:0) after 1m0s
[07:33:39.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:49:0)
[07:33:39.417] Timer tick, asking Buildbucket for the build status
[07:33:39.548] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:39.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:50:0) after 1m0s
[07:34:39.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:50:0)
[07:34:39.921] Timer tick, asking Buildbucket for the build status
[07:34:40.029] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:40.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:51:0) after 1m0s
[07:35:40.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:51:0)
[07:35:40.160] Timer tick, asking Buildbucket for the build status
[07:35:40.364] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:40.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:52:0) after 1m0s
[07:36:40.377] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:52:0)
[07:36:40.377] Timer tick, asking Buildbucket for the build status
[07:36:40.571] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:40.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:53:0) after 1m0s
[07:37:40.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:53:0)
[07:37:40.600] Timer tick, asking Buildbucket for the build status
[07:37:40.684] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:40.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:54:0) after 1m0s
[07:38:40.914] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:54:0)
[07:38:40.914] Timer tick, asking Buildbucket for the build status
[07:38:41.065] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:41.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:55:0) after 1m0s
[07:39:41.181] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:55:0)
[07:39:41.181] Timer tick, asking Buildbucket for the build status
[07:39:41.265] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:41.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:56:0) after 1m0s
[07:40:41.468] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:56:0)
[07:40:41.468] Timer tick, asking Buildbucket for the build status
[07:40:41.565] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:41.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:57:0) after 1m0s
[07:41:41.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:57:0)
[07:41:41.671] Timer tick, asking Buildbucket for the build status
[07:41:41.816] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:41.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:58:0) after 1m0s
[07:42:41.988] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:58:0)
[07:42:41.988] Timer tick, asking Buildbucket for the build status
[07:42:42.061] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:42.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:59:0) after 1m0s
[07:43:42.166] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:59:0)
[07:43:42.166] Timer tick, asking Buildbucket for the build status
[07:43:42.271] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:42.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:60:0) after 1m0s
[07:44:42.336] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:60:0)
[07:44:42.336] Timer tick, asking Buildbucket for the build status
[07:44:42.473] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:42.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:61:0) after 1m0s
[07:45:42.635] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:61:0)
[07:45:42.635] Timer tick, asking Buildbucket for the build status
[07:45:42.710] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:42.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:62:0) after 1m0s
[07:46:42.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:62:0)
[07:46:42.811] Timer tick, asking Buildbucket for the build status
[07:46:42.980] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:42.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:63:0) after 1m0s
[07:47:43.111] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:63:0)
[07:47:43.111] Timer tick, asking Buildbucket for the build status
[07:47:43.206] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:43.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:64:0) after 1m0s
[07:48:43.289] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:64:0)
[07:48:43.289] Timer tick, asking Buildbucket for the build status
[07:48:43.392] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:43.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:65:0) after 1m0s
[07:49:43.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:65:0)
[07:49:43.676] Timer tick, asking Buildbucket for the build status
[07:49:43.767] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:43.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:66:0) after 1m0s
[07:50:43.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:66:0)
[07:50:43.976] Timer tick, asking Buildbucket for the build status
[07:50:44.505] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:44.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:67:0) after 1m0s
[07:51:44.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:67:0)
[07:51:44.732] Timer tick, asking Buildbucket for the build status
[07:51:45.138] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:45.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:68:0) after 1m0s
[07:52:45.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:68:0)
[07:52:45.217] Timer tick, asking Buildbucket for the build status
[07:52:45.293] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:45.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:69:0) after 1m0s
[07:53:45.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:69:0)
[07:53:45.451] Timer tick, asking Buildbucket for the build status
[07:53:45.677] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:45.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:70:0) after 1m0s
[07:54:46.780] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:70:0)
[07:54:46.780] Timer tick, asking Buildbucket for the build status
[07:54:46.879] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:46.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:71:0) after 1m0s
[07:55:47.154] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:71:0)
[07:55:47.154] Timer tick, asking Buildbucket for the build status
[07:55:47.420] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:47.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:72:0) after 1m0s
[07:56:47.856] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:72:0)
[07:56:47.856] Timer tick, asking Buildbucket for the build status
[07:56:48.279] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:48.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:73:0) after 1m0s
[07:57:48.477] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:73:0)
[07:57:48.477] Timer tick, asking Buildbucket for the build status
[07:57:48.621] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:48.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:74:0) after 1m0s
[07:58:48.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:74:0)
[07:58:48.805] Timer tick, asking Buildbucket for the build status
[07:58:49.158] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:49.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:75:0) after 1m0s
[07:59:49.335] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:75:0)
[07:59:49.335] Timer tick, asking Buildbucket for the build status
[07:59:49.457] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:49.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:76:0) after 1m0s
[08:00:49.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:76:0)
[08:00:49.722] Timer tick, asking Buildbucket for the build status
[08:00:49.838] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:49.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:77:0) after 1m0s
[08:01:50.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:77:0)
[08:01:50.033] Timer tick, asking Buildbucket for the build status
[08:01:50.514] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:50.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:78:0) after 1m0s
[08:02:50.725] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:78:0)
[08:02:50.725] Timer tick, asking Buildbucket for the build status
[08:02:50.835] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:50.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:79:0) after 1m0s
[08:03:51.088] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:79:0)
[08:03:51.088] Timer tick, asking Buildbucket for the build status
[08:03:51.423] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:51.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:80:0) after 1m0s
[08:04:51.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:80:0)
[08:04:51.571] Timer tick, asking Buildbucket for the build status
[08:04:51.826] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:51.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:81:0) after 1m0s
[08:05:51.922] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:81:0)
[08:05:51.922] Timer tick, asking Buildbucket for the build status
[08:05:52.017] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:52.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:82:0) after 1m0s
[08:06:52.303] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:82:0)
[08:06:52.303] Timer tick, asking Buildbucket for the build status
[08:06:52.438] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:52.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:83:0) after 1m0s
[08:07:52.637] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:83:0)
[08:07:52.637] Timer tick, asking Buildbucket for the build status
[08:07:52.836] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:52.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:84:0) after 1m0s
[08:08:53.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:84:0)
[08:08:53.264] Timer tick, asking Buildbucket for the build status
[08:08:53.452] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:53.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:85:0) after 1m0s
[08:09:53.561] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:85:0)
[08:09:53.561] Timer tick, asking Buildbucket for the build status
[08:09:53.754] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:53.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:86:0) after 1m0s
[08:10:54.012] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:86:0)
[08:10:54.012] Timer tick, asking Buildbucket for the build status
[08:10:54.162] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:54.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:87:0) after 1m0s
[08:11:54.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:87:0)
[08:11:54.282] Timer tick, asking Buildbucket for the build status
[08:11:54.571] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:54.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:88:0) after 1m0s
[08:12:54.596] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:88:0)
[08:12:54.596] Timer tick, asking Buildbucket for the build status
[08:12:54.684] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:54.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:89:0) after 1m0s
[08:13:54.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:89:0)
[08:13:54.832] Timer tick, asking Buildbucket for the build status
[08:13:54.986] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:54.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:90:0) after 1m0s
[08:14:55.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:90:0)
[08:14:55.361] Timer tick, asking Buildbucket for the build status
[08:14:55.526] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:55.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:91:0) after 1m0s
[08:15:55.598] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:91:0)
[08:15:55.598] Timer tick, asking Buildbucket for the build status
[08:15:55.777] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:55.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:92:0) after 1m0s
[08:16:55.846] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:92:0)
[08:16:55.846] Timer tick, asking Buildbucket for the build status
[08:16:56.033] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:56.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:93:0) after 1m0s
[08:17:56.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:93:0)
[08:17:56.160] Timer tick, asking Buildbucket for the build status
[08:17:56.248] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:56.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:94:0) after 1m0s
[08:18:56.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:94:0)
[08:18:56.283] Timer tick, asking Buildbucket for the build status
[08:18:56.346] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:56.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:95:0) after 1m0s
[08:19:56.426] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:95:0)
[08:19:56.426] Timer tick, asking Buildbucket for the build status
[08:19:56.572] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:56.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:96:0) after 1m0s
[08:20:56.750] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:96:0)
[08:20:56.750] Timer tick, asking Buildbucket for the build status
[08:20:56.827] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:56.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:97:0) after 1m0s
[08:21:56.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:97:0)
[08:21:56.855] Timer tick, asking Buildbucket for the build status
[08:21:57.153] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:57.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:98:0) after 1m0s
[08:22:57.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:98:0)
[08:22:57.337] Timer tick, asking Buildbucket for the build status
[08:22:57.616] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:57.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:99:0) after 1m0s
[08:23:57.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:99:0)
[08:23:57.669] Timer tick, asking Buildbucket for the build status
[08:23:58.047] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:58.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:100:0) after 1m0s
[08:24:58.406] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:100:0)
[08:24:58.406] Timer tick, asking Buildbucket for the build status
[08:24:58.899] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:58.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:101:0) after 1m0s
[08:25:59.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:101:0)
[08:25:59.086] Timer tick, asking Buildbucket for the build status
[08:25:59.183] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:59.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:102:0) after 1m0s
[08:26:59.192] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:102:0)
[08:26:59.194] Timer tick, asking Buildbucket for the build status
[08:26:59.559] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:59.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:103:0) after 1m0s
[08:27:59.750] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:103:0)
[08:27:59.750] Timer tick, asking Buildbucket for the build status
[08:27:59.878] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:59.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:104:0) after 1m0s
[08:29:00.017] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:104:0)
[08:29:00.017] Timer tick, asking Buildbucket for the build status
[08:29:00.425] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:00.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:105:0) after 1m0s
[08:30:00.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:105:0)
[08:30:00.962] Timer tick, asking Buildbucket for the build status
[08:30:01.297] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:01.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:106:0) after 1m0s
[08:31:02.644] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:106:0)
[08:31:02.644] Timer tick, asking Buildbucket for the build status
[08:31:02.808] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:02.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:107:0) after 1m0s
[08:32:02.838] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:107:0)
[08:32:02.838] Timer tick, asking Buildbucket for the build status
[08:32:03.028] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:03.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:108:0) after 1m0s
[08:33:03.092] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:108:0)
[08:33:03.092] Timer tick, asking Buildbucket for the build status
[08:33:03.211] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:03.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:109:0) after 1m0s
[08:34:03.262] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:109:0)
[08:34:03.262] Timer tick, asking Buildbucket for the build status
[08:34:03.349] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:03.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:110:0) after 1m0s
[08:35:03.902] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:110:0)
[08:35:03.902] Timer tick, asking Buildbucket for the build status
[08:35:04.098] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:04.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:111:0) after 1m0s
[08:36:04.562] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:111:0)
[08:36:04.562] Timer tick, asking Buildbucket for the build status
[08:36:04.694] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:04.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:112:0) after 1m0s
[08:37:04.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:112:0)
[08:37:04.845] Timer tick, asking Buildbucket for the build status
[08:37:05.394] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:05.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:113:0) after 1m0s
[08:38:05.644] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:113:0)
[08:38:05.644] Timer tick, asking Buildbucket for the build status
[08:38:05.902] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:05.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:114:0) after 1m0s
[08:39:06.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:114:0)
[08:39:06.575] Timer tick, asking Buildbucket for the build status
[08:39:07.786] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:07.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:115:0) after 1m0s
[08:40:08.236] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:115:0)
[08:40:08.236] Timer tick, asking Buildbucket for the build status
[08:40:08.345] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:08.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:116:0) after 1m0s
[08:41:08.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:116:0)
[08:41:08.784] Timer tick, asking Buildbucket for the build status
[08:41:08.870] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:08.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:117:0) after 1m0s
[08:42:09.312] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:117:0)
[08:42:09.312] Timer tick, asking Buildbucket for the build status
[08:42:09.543] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:09.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:118:0) after 1m0s
[08:43:09.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:118:0)
[08:43:09.925] Timer tick, asking Buildbucket for the build status
[08:43:10.004] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:10.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:119:0) after 1m0s
[08:44:10.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:119:0)
[08:44:10.167] Timer tick, asking Buildbucket for the build status
[08:44:10.280] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:10.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:120:0) after 1m0s
[08:45:10.527] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:120:0)
[08:45:10.527] Timer tick, asking Buildbucket for the build status
[08:45:10.604] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:10.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:121:0) after 1m0s
[08:46:10.844] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:121:0)
[08:46:10.844] Timer tick, asking Buildbucket for the build status
[08:46:10.943] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:10.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:122:0) after 1m0s
[08:47:11.522] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:122:0)
[08:47:11.522] Timer tick, asking Buildbucket for the build status
[08:47:12.106] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:12.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:123:0) after 1m0s
[08:48:12.182] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:123:0)
[08:48:12.182] Timer tick, asking Buildbucket for the build status
[08:48:12.568] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:12.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:124:0) after 1m0s
[08:49:12.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:124:0)
[08:49:12.842] Timer tick, asking Buildbucket for the build status
[08:49:12.991] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:12.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:125:0) after 1m0s
[08:50:13.275] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:125:0)
[08:50:13.275] Timer tick, asking Buildbucket for the build status
[08:50:13.407] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:13.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:126:0) after 1m0s
[08:51:15.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:126:0)
[08:51:15.452] Timer tick, asking Buildbucket for the build status
[08:51:15.557] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:15.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:127:0) after 1m0s
[08:52:15.859] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:127:0)
[08:52:15.859] Timer tick, asking Buildbucket for the build status
[08:52:16.825] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:16.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:128:0) after 1m0s
[08:53:16.994] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:128:0)
[08:53:16.994] Timer tick, asking Buildbucket for the build status
[08:53:17.529] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:17.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:129:0) after 1m0s
[08:54:18.166] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:129:0)
[08:54:18.166] Timer tick, asking Buildbucket for the build status
[08:54:19.231] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:19.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:130:0) after 1m0s
[08:55:19.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:130:0)
[08:55:19.425] Timer tick, asking Buildbucket for the build status
[08:55:20.768] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:20.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:131:0) after 1m0s
[08:56:21.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:131:0)
[08:56:21.086] Timer tick, asking Buildbucket for the build status
[08:56:21.303] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:21.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:132:0) after 1m0s
[08:57:21.330] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:132:0)
[08:57:21.330] Timer tick, asking Buildbucket for the build status
[08:57:23.245] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:23.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:133:0) after 1m0s
[08:58:23.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:133:0)
[08:58:23.313] Timer tick, asking Buildbucket for the build status
[08:58:24.795] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:24.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:134:0) after 1m0s
[08:59:24.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:134:0)
[08:59:24.855] Timer tick, asking Buildbucket for the build status
[08:59:24.954] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:24.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:135:0) after 1m0s
[09:00:24.999] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:135:0)
[09:00:24.999] Timer tick, asking Buildbucket for the build status
[09:00:25.115] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:25.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:136:0) after 1m0s
[09:01:25.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:136:0)
[09:01:25.160] Timer tick, asking Buildbucket for the build status
[09:01:25.243] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:25.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:137:0) after 1m0s
[09:02:25.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:137:0)
[09:02:25.779] Timer tick, asking Buildbucket for the build status
[09:02:26.207] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:26.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:138:0) after 1m0s
[09:03:26.378] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:138:0)
[09:03:26.378] Timer tick, asking Buildbucket for the build status
[09:03:26.480] Build 8869837113330620512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:26.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035267175308211296:139:0) after 1m0s
[09:03:48.103] Received PubSub notification, asking Buildbucket for the build status
[09:03:48.355] Build 8869837113330620512: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:03:48.355] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1599469427213039",
"created_by": "project:chromium",
"created_ts": "1599461176517191",
"id": "8869837113330620512",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b351074253236dae5054b95ea8a96f1384919faa\"}}",
"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\": \"b351074253236dae5054b95ea8a96f1384919faa\", \"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\": 528, \"got_angle_revision\": \"f184cb61cea6c70204154a24477f67c2f1a7d6b4\", \"got_dawn_revision\": \"62442aceb0fb8af0c1edd07b5f18b4ec181b8fbf\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"b351074253236dae5054b95ea8a96f1384919faa\", \"got_revision_cp\": \"refs/heads/master@{#804678}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"10121443c16deb0435be8ea61be54e3bee1b9855\", \"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\": \"b351074253236dae5054b95ea8a96f1384919faa\"}, \"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\": [\"5511-73542fe\"], \"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": "1599461223170672",
"status": "COMPLETED",
"status_changed_ts": "1599469427568452",
"tags": [
"build_address:luci.chromium.ci/win-official/528",
"builder:win-official",
"buildset:commit/git/b351074253236dae5054b95ea8a96f1384919faa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b351074253236dae5054b95ea8a96f1384919faa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035267175308211296",
"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/8869837113330620512/+/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:4e802241b141e710",
"user_agent:luci-scheduler"
],
"updated_ts": "1599469427597783",
"url": "https://ci.chromium.org/b/8869837113330620512",
"utcnow_ts": "1599469428320515"
}
}
[09:03:48.355] Invocation finished in 2h17m33.107338835s with status SUCCEEDED