[07:39:41.045] New invocation is queued and will start shortly
[07:39:42.336] Starting the invocation (attempt 1)
[07:39:42.384] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:39:42.384] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:39:42.384] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9037981722806758288",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9dfba38d8e934105820fb9a298f5ee6538a1b152\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9037981722806758288",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/9dfba38d8e934105820fb9a298f5ee6538a1b152",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9dfba38d8e934105820fb9a298f5ee6538a1b152",
"gitiles_ref:refs/heads/master"
]
}
[07:39:43.172] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1596872382492992",
"id": "8872551660614170000",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9dfba38d8e934105820fb9a298f5ee6538a1b152\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1596872383029334",
"tags": [
"build_address:luci.chromium.ci/win-official/286",
"builder:win-official",
"buildset:commit/git/9dfba38d8e934105820fb9a298f5ee6538a1b152",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9dfba38d8e934105820fb9a298f5ee6538a1b152",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037981722806758288",
"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/8872551660614170000/+/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": "1596872383029467",
"url": "https://ci.chromium.org/b/8872551660614170000",
"utcnow_ts": "1596872383161013"
}
}
[07:39:43.172] Task URL: https://ci.chromium.org/b/8872551660614170000
[07:39:43.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:2:0) after 1m0s
[07:39:52.651] Received PubSub notification, asking Buildbucket for the build status
[07:39:52.781] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:43.161] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:2:0)
[07:40:43.161] Timer tick, asking Buildbucket for the build status
[07:40:43.299] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:43.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:4:0) after 1m0s
[07:41:43.321] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:4:0)
[07:41:43.321] Timer tick, asking Buildbucket for the build status
[07:41:43.537] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:43.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:5:0) after 1m0s
[07:42:43.556] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:5:0)
[07:42:43.556] Timer tick, asking Buildbucket for the build status
[07:42:43.663] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:43.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:6:0) after 1m0s
[07:43:43.696] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:6:0)
[07:43:43.696] Timer tick, asking Buildbucket for the build status
[07:43:44.142] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:44.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:7:0) after 1m0s
[07:44:44.169] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:7:0)
[07:44:44.169] Timer tick, asking Buildbucket for the build status
[07:44:44.272] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:44.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:8:0) after 1m0s
[07:45:44.365] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:8:0)
[07:45:44.365] Timer tick, asking Buildbucket for the build status
[07:45:44.493] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:44.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:9:0) after 1m0s
[07:46:44.583] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:9:0)
[07:46:44.583] Timer tick, asking Buildbucket for the build status
[07:46:44.742] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:44.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:10:0) after 1m0s
[07:47:44.801] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:10:0)
[07:47:44.801] Timer tick, asking Buildbucket for the build status
[07:47:44.977] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:44.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:11:0) after 1m0s
[07:48:45.026] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:11:0)
[07:48:45.026] Timer tick, asking Buildbucket for the build status
[07:48:45.195] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:45.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:12:0) after 1m0s
[07:49:45.219] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:12:0)
[07:49:45.219] Timer tick, asking Buildbucket for the build status
[07:49:45.345] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:45.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:13:0) after 1m0s
[07:50:45.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:13:0)
[07:50:45.367] Timer tick, asking Buildbucket for the build status
[07:50:45.462] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:45.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:14:0) after 1m0s
[07:51:45.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:14:0)
[07:51:45.524] Timer tick, asking Buildbucket for the build status
[07:51:45.711] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:45.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:15:0) after 1m0s
[07:52:45.730] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:15:0)
[07:52:45.730] Timer tick, asking Buildbucket for the build status
[07:52:45.833] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:45.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:16:0) after 1m0s
[07:53:45.857] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:16:0)
[07:53:45.857] Timer tick, asking Buildbucket for the build status
[07:53:46.075] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:46.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:17:0) after 1m0s
[07:54:46.101] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:17:0)
[07:54:46.101] Timer tick, asking Buildbucket for the build status
[07:54:46.383] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:46.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:18:0) after 1m0s
[07:55:46.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:18:0)
[07:55:46.384] Timer tick, asking Buildbucket for the build status
[07:55:46.535] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:46.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:19:0) after 1m0s
[07:56:46.562] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:19:0)
[07:56:46.562] Timer tick, asking Buildbucket for the build status
[07:56:46.627] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:46.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:20:0) after 1m0s
[07:57:46.654] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:20:0)
[07:57:46.654] Timer tick, asking Buildbucket for the build status
[07:57:46.738] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:46.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:21:0) after 1m0s
[07:58:46.772] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:21:0)
[07:58:46.772] Timer tick, asking Buildbucket for the build status
[07:58:46.944] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:46.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:22:0) after 1m0s
[07:59:46.963] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:22:0)
[07:59:46.963] Timer tick, asking Buildbucket for the build status
[07:59:47.126] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:47.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:23:0) after 1m0s
[08:00:47.256] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:23:0)
[08:00:47.256] Timer tick, asking Buildbucket for the build status
[08:00:47.453] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:47.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:24:0) after 1m0s
[08:01:47.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:24:0)
[08:01:47.491] Timer tick, asking Buildbucket for the build status
[08:01:47.705] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:47.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:25:0) after 1m0s
[08:02:47.748] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:25:0)
[08:02:47.748] Timer tick, asking Buildbucket for the build status
[08:02:47.862] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:47.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:26:0) after 1m0s
[08:03:47.903] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:26:0)
[08:03:47.903] Timer tick, asking Buildbucket for the build status
[08:03:48.066] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:48.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:27:0) after 1m0s
[08:04:48.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:27:0)
[08:04:48.125] Timer tick, asking Buildbucket for the build status
[08:04:51.319] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:51.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:28:0) after 1m0s
[08:05:51.340] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:28:0)
[08:05:51.340] Timer tick, asking Buildbucket for the build status
[08:05:51.529] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:51.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:29:0) after 1m0s
[08:06:51.551] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:29:0)
[08:06:51.551] Timer tick, asking Buildbucket for the build status
[08:06:51.623] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:51.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:30:0) after 1m0s
[08:07:51.677] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:30:0)
[08:07:51.677] Timer tick, asking Buildbucket for the build status
[08:07:51.791] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:51.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:31:0) after 1m0s
[08:08:51.812] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:31:0)
[08:08:51.812] Timer tick, asking Buildbucket for the build status
[08:08:51.895] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:51.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:32:0) after 1m0s
[08:09:51.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:32:0)
[08:09:51.949] Timer tick, asking Buildbucket for the build status
[08:09:52.114] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:52.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:33:0) after 1m0s
[08:10:52.188] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:33:0)
[08:10:52.188] Timer tick, asking Buildbucket for the build status
[08:10:52.411] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:52.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:34:0) after 1m0s
[08:11:52.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:34:0)
[08:11:52.451] Timer tick, asking Buildbucket for the build status
[08:11:52.621] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:52.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:35:0) after 1m0s
[08:12:52.674] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:35:0)
[08:12:52.674] Timer tick, asking Buildbucket for the build status
[08:12:52.751] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:52.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:36:0) after 1m0s
[08:13:52.799] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:36:0)
[08:13:52.799] Timer tick, asking Buildbucket for the build status
[08:13:53.218] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:53.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:37:0) after 1m0s
[08:14:53.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:37:0)
[08:14:53.302] Timer tick, asking Buildbucket for the build status
[08:14:53.430] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:53.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:38:0) after 1m0s
[08:15:53.553] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:38:0)
[08:15:53.553] Timer tick, asking Buildbucket for the build status
[08:15:53.734] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:53.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:39:0) after 1m0s
[08:16:53.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:39:0)
[08:16:53.758] Timer tick, asking Buildbucket for the build status
[08:16:53.843] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:53.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:40:0) after 1m0s
[08:17:54.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:40:0)
[08:17:54.168] Timer tick, asking Buildbucket for the build status
[08:17:54.253] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:54.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:41:0) after 1m0s
[08:18:54.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:41:0)
[08:18:54.282] Timer tick, asking Buildbucket for the build status
[08:18:54.408] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:54.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:42:0) after 1m0s
[08:19:54.532] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:42:0)
[08:19:54.532] Timer tick, asking Buildbucket for the build status
[08:19:54.726] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:54.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:43:0) after 1m0s
[08:20:54.771] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:43:0)
[08:20:54.771] Timer tick, asking Buildbucket for the build status
[08:20:54.857] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:54.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:44:0) after 1m0s
[08:21:54.913] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:44:0)
[08:21:54.913] Timer tick, asking Buildbucket for the build status
[08:21:54.969] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:54.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:45:0) after 1m0s
[08:22:55.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:45:0)
[08:22:55.042] Timer tick, asking Buildbucket for the build status
[08:22:55.296] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:55.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:46:0) after 1m0s
[08:23:55.816] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:46:0)
[08:23:55.816] Timer tick, asking Buildbucket for the build status
[08:23:56.171] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:56.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:47:0) after 1m0s
[08:24:56.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:47:0)
[08:24:56.238] Timer tick, asking Buildbucket for the build status
[08:24:56.621] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:56.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:48:0) after 1m0s
[08:25:56.715] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:48:0)
[08:25:56.715] Timer tick, asking Buildbucket for the build status
[08:25:56.785] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:56.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:49:0) after 1m0s
[08:26:56.847] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:49:0)
[08:26:56.847] Timer tick, asking Buildbucket for the build status
[08:26:57.033] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:57.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:50:0) after 1m0s
[08:27:57.124] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:50:0)
[08:27:57.124] Timer tick, asking Buildbucket for the build status
[08:27:57.199] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:57.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:51:0) after 1m0s
[08:28:57.378] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:51:0)
[08:28:57.378] Timer tick, asking Buildbucket for the build status
[08:28:57.577] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:57.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:52:0) after 1m0s
[08:29:57.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:52:0)
[08:29:57.671] Timer tick, asking Buildbucket for the build status
[08:29:57.850] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:57.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:53:0) after 1m0s
[08:30:57.887] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:53:0)
[08:30:57.887] Timer tick, asking Buildbucket for the build status
[08:30:57.956] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:57.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:54:0) after 1m0s
[08:31:57.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:54:0)
[08:31:57.979] Timer tick, asking Buildbucket for the build status
[08:31:58.332] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:58.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:55:0) after 1m0s
[08:32:58.396] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:55:0)
[08:32:58.396] Timer tick, asking Buildbucket for the build status
[08:32:58.901] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:58.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:56:0) after 1m0s
[08:33:59.000] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:56:0)
[08:33:59.000] Timer tick, asking Buildbucket for the build status
[08:33:59.198] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:59.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:57:0) after 1m0s
[08:34:59.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:57:0)
[08:34:59.293] Timer tick, asking Buildbucket for the build status
[08:34:59.450] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:59.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:58:0) after 1m0s
[08:35:59.547] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:58:0)
[08:35:59.547] Timer tick, asking Buildbucket for the build status
[08:35:59.795] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:59.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:59:0) after 1m0s
[08:37:01.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:59:0)
[08:37:01.819] Timer tick, asking Buildbucket for the build status
[08:37:01.903] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:01.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:60:0) after 1m0s
[08:38:01.935] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:60:0)
[08:38:01.935] Timer tick, asking Buildbucket for the build status
[08:38:02.021] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:02.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:61:0) after 1m0s
[08:39:02.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:61:0)
[08:39:02.043] Timer tick, asking Buildbucket for the build status
[08:39:02.181] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:02.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:62:0) after 1m0s
[08:40:02.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:62:0)
[08:40:02.204] Timer tick, asking Buildbucket for the build status
[08:40:02.398] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:02.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:63:0) after 1m0s
[08:41:02.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:63:0)
[08:41:02.597] Timer tick, asking Buildbucket for the build status
[08:41:02.801] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:02.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:64:0) after 1m0s
[08:42:02.858] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:64:0)
[08:42:02.858] Timer tick, asking Buildbucket for the build status
[08:42:02.956] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:02.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:65:0) after 1m0s
[08:43:03.053] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:65:0)
[08:43:03.053] Timer tick, asking Buildbucket for the build status
[08:43:03.246] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:03.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:66:0) after 1m0s
[08:44:03.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:66:0)
[08:44:03.268] Timer tick, asking Buildbucket for the build status
[08:44:03.342] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:03.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:67:0) after 1m0s
[08:45:04.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:67:0)
[08:45:04.104] Timer tick, asking Buildbucket for the build status
[08:45:04.447] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:04.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:68:0) after 1m0s
[08:46:04.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:68:0)
[08:46:04.539] Timer tick, asking Buildbucket for the build status
[08:46:04.701] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:04.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:69:0) after 1m0s
[08:47:04.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:69:0)
[08:47:04.738] Timer tick, asking Buildbucket for the build status
[08:47:05.002] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:05.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:70:0) after 1m0s
[08:48:05.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:70:0)
[08:48:05.052] Timer tick, asking Buildbucket for the build status
[08:48:05.221] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:05.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:71:0) after 1m0s
[08:49:05.246] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:71:0)
[08:49:05.246] Timer tick, asking Buildbucket for the build status
[08:49:05.319] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:05.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:72:0) after 1m0s
[08:50:06.220] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:72:0)
[08:50:06.220] Timer tick, asking Buildbucket for the build status
[08:50:06.352] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:06.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:73:0) after 1m0s
[08:51:06.380] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:73:0)
[08:51:06.380] Timer tick, asking Buildbucket for the build status
[08:51:06.559] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:06.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:74:0) after 1m0s
[08:52:06.584] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:74:0)
[08:52:06.584] Timer tick, asking Buildbucket for the build status
[08:52:06.688] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:06.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:75:0) after 1m0s
[08:53:06.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:75:0)
[08:53:06.849] Timer tick, asking Buildbucket for the build status
[08:53:06.917] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:06.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:76:0) after 1m0s
[08:54:06.994] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:76:0)
[08:54:06.994] Timer tick, asking Buildbucket for the build status
[08:54:07.114] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:07.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:77:0) after 1m0s
[08:55:07.150] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:77:0)
[08:55:07.150] Timer tick, asking Buildbucket for the build status
[08:55:07.364] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:07.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:78:0) after 1m0s
[08:56:07.456] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:78:0)
[08:56:07.456] Timer tick, asking Buildbucket for the build status
[08:56:07.925] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:07.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:79:0) after 1m0s
[08:57:08.046] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:79:0)
[08:57:08.046] Timer tick, asking Buildbucket for the build status
[08:57:08.150] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:08.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:80:0) after 1m0s
[08:58:08.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:80:0)
[08:58:08.225] Timer tick, asking Buildbucket for the build status
[08:58:08.282] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:08.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:81:0) after 1m0s
[08:59:08.306] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:81:0)
[08:59:08.306] Timer tick, asking Buildbucket for the build status
[08:59:08.577] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:08.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:82:0) after 1m0s
[09:00:08.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:82:0)
[09:00:08.665] Timer tick, asking Buildbucket for the build status
[09:00:08.823] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:08.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:83:0) after 1m0s
[09:01:09.023] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:83:0)
[09:01:09.023] Timer tick, asking Buildbucket for the build status
[09:01:09.148] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:09.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:84:0) after 1m0s
[09:02:09.450] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:84:0)
[09:02:09.450] Timer tick, asking Buildbucket for the build status
[09:02:09.656] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:09.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:85:0) after 1m0s
[09:03:09.973] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:85:0)
[09:03:09.973] Timer tick, asking Buildbucket for the build status
[09:03:10.051] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:10.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:86:0) after 1m0s
[09:04:10.163] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:86:0)
[09:04:10.163] Timer tick, asking Buildbucket for the build status
[09:04:11.031] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:11.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:87:0) after 1m0s
[09:05:11.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:87:0)
[09:05:11.034] Timer tick, asking Buildbucket for the build status
[09:05:11.255] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:11.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:88:0) after 1m0s
[09:06:11.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:88:0)
[09:06:11.367] Timer tick, asking Buildbucket for the build status
[09:06:11.607] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:11.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:89:0) after 1m0s
[09:07:11.795] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:89:0)
[09:07:11.795] Timer tick, asking Buildbucket for the build status
[09:07:12.295] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:12.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:90:0) after 1m0s
[09:08:12.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:90:0)
[09:08:12.361] Timer tick, asking Buildbucket for the build status
[09:08:12.584] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:12.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:91:0) after 1m0s
[09:09:12.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:91:0)
[09:09:12.607] Timer tick, asking Buildbucket for the build status
[09:09:12.930] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:12.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:92:0) after 1m0s
[09:10:12.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:92:0)
[09:10:12.983] Timer tick, asking Buildbucket for the build status
[09:10:13.395] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:13.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:93:0) after 1m0s
[09:11:13.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:93:0)
[09:11:13.514] Timer tick, asking Buildbucket for the build status
[09:11:13.679] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:13.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:94:0) after 1m0s
[09:12:13.778] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:94:0)
[09:12:13.778] Timer tick, asking Buildbucket for the build status
[09:12:14.255] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:14.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:95:0) after 1m0s
[09:13:14.321] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:95:0)
[09:13:14.322] Timer tick, asking Buildbucket for the build status
[09:13:14.660] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:14.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:96:0) after 1m0s
[09:14:14.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:96:0)
[09:14:14.742] Timer tick, asking Buildbucket for the build status
[09:14:14.811] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:14.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:97:0) after 1m0s
[09:15:14.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:97:0)
[09:15:14.890] Timer tick, asking Buildbucket for the build status
[09:15:15.005] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:15.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:98:0) after 1m0s
[09:16:15.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:98:0)
[09:16:15.104] Timer tick, asking Buildbucket for the build status
[09:16:15.281] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:15.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:99:0) after 1m0s
[09:17:15.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:99:0)
[09:17:15.371] Timer tick, asking Buildbucket for the build status
[09:17:15.511] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:15.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:100:0) after 1m0s
[09:18:16.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:100:0)
[09:18:16.726] Timer tick, asking Buildbucket for the build status
[09:18:16.896] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:16.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:101:0) after 1m0s
[09:19:17.024] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:101:0)
[09:19:17.024] Timer tick, asking Buildbucket for the build status
[09:19:17.101] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:17.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:102:0) after 1m0s
[09:20:17.231] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:102:0)
[09:20:17.231] Timer tick, asking Buildbucket for the build status
[09:20:17.351] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:17.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:103:0) after 1m0s
[09:21:18.131] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:103:0)
[09:21:18.131] Timer tick, asking Buildbucket for the build status
[09:21:18.199] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:18.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:104:0) after 1m0s
[09:22:18.650] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:104:0)
[09:22:18.650] Timer tick, asking Buildbucket for the build status
[09:22:19.111] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:19.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:105:0) after 1m0s
[09:23:19.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:105:0)
[09:23:19.223] Timer tick, asking Buildbucket for the build status
[09:23:19.291] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:19.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:106:0) after 1m0s
[09:24:19.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:106:0)
[09:24:19.415] Timer tick, asking Buildbucket for the build status
[09:24:19.515] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:19.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:107:0) after 1m0s
[09:25:19.694] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:107:0)
[09:25:19.694] Timer tick, asking Buildbucket for the build status
[09:25:20.126] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:20.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:108:0) after 1m0s
[09:26:20.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:108:0)
[09:26:20.176] Timer tick, asking Buildbucket for the build status
[09:26:20.533] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:20.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:109:0) after 1m0s
[09:27:20.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:109:0)
[09:27:20.652] Timer tick, asking Buildbucket for the build status
[09:27:20.723] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:20.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:110:0) after 1m0s
[09:28:20.773] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:110:0)
[09:28:20.773] Timer tick, asking Buildbucket for the build status
[09:28:20.947] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:20.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:111:0) after 1m0s
[09:29:21.187] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:111:0)
[09:29:21.187] Timer tick, asking Buildbucket for the build status
[09:29:21.295] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:21.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:112:0) after 1m0s
[09:30:21.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:112:0)
[09:30:21.388] Timer tick, asking Buildbucket for the build status
[09:30:21.800] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:21.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:113:0) after 1m0s
[09:31:22.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:113:0)
[09:31:22.066] Timer tick, asking Buildbucket for the build status
[09:31:22.153] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:22.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:114:0) after 1m0s
[09:32:22.296] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:114:0)
[09:32:22.296] Timer tick, asking Buildbucket for the build status
[09:32:22.381] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:22.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:115:0) after 1m0s
[09:33:22.412] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:115:0)
[09:33:22.412] Timer tick, asking Buildbucket for the build status
[09:33:22.473] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:22.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:116:0) after 1m0s
[09:34:22.715] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:116:0)
[09:34:22.715] Timer tick, asking Buildbucket for the build status
[09:34:22.989] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:22.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:117:0) after 1m0s
[09:35:23.114] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:117:0)
[09:35:23.114] Timer tick, asking Buildbucket for the build status
[09:35:23.407] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:23.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:118:0) after 1m0s
[09:36:23.594] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:118:0)
[09:36:23.594] Timer tick, asking Buildbucket for the build status
[09:36:23.860] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:23.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:119:0) after 1m0s
[09:37:23.952] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:119:0)
[09:37:23.952] Timer tick, asking Buildbucket for the build status
[09:37:24.135] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:24.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:120:0) after 1m0s
[09:38:24.229] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:120:0)
[09:38:24.229] Timer tick, asking Buildbucket for the build status
[09:38:24.316] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:24.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:121:0) after 1m0s
[09:39:24.427] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:121:0)
[09:39:24.427] Timer tick, asking Buildbucket for the build status
[09:39:24.708] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:24.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:122:0) after 1m0s
[09:40:24.775] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:122:0)
[09:40:24.775] Timer tick, asking Buildbucket for the build status
[09:40:24.929] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:24.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:123:0) after 1m0s
[09:41:24.984] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:123:0)
[09:41:24.984] Timer tick, asking Buildbucket for the build status
[09:41:25.304] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:25.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:124:0) after 1m0s
[09:42:25.435] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:124:0)
[09:42:25.435] Timer tick, asking Buildbucket for the build status
[09:42:25.513] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:25.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:125:0) after 1m0s
[09:43:25.542] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:125:0)
[09:43:25.542] Timer tick, asking Buildbucket for the build status
[09:43:25.610] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:25.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:126:0) after 1m0s
[09:44:25.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:126:0)
[09:44:25.636] Timer tick, asking Buildbucket for the build status
[09:44:26.000] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:26.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:127:0) after 1m0s
[09:45:26.179] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:127:0)
[09:45:26.179] Timer tick, asking Buildbucket for the build status
[09:45:26.922] Build 8872551660614170000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:26.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037981722806758288:128:0) after 1m0s
[09:45:58.436] Received PubSub notification, asking Buildbucket for the build status
[09:45:58.538] Build 8872551660614170000: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:45:58.538] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1596879957653915",
"created_by": "project:chromium",
"created_ts": "1596872382492992",
"id": "8872551660614170000",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9dfba38d8e934105820fb9a298f5ee6538a1b152\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-4-74dv\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 286, \"got_angle_revision\": \"48ba75ac6845428971ea2a12bfb69eb85e608409\", \"got_dawn_revision\": \"101a5829d50f9509d514a13d49946a99c8f1c469\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"9dfba38d8e934105820fb9a298f5ee6538a1b152\", \"got_revision_cp\": \"refs/heads/master@{#796225}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"59b4828f293eb16f8983ae9ea0a8a0a84cf8174c\", \"got_v8_revision\": \"4f52dde736e657ac01cf74a6cdc38c752f9a5dae\", \"got_v8_revision_cp\": \"refs/heads/8.6.337@{#1}\", \"got_webrtc_revision\": \"4c2f9c90f42591f0935b496d61ee256ebf53198f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31887}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9dfba38d8e934105820fb9a298f5ee6538a1b152\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-4-74dv\"], \"image\": [\"chrome-win10-1703-20073100-2eb11ddbc6e\"], \"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\": [\"5472-d0af7f3\"], \"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": "1596872389827314",
"status": "COMPLETED",
"status_changed_ts": "1596879958066186",
"tags": [
"build_address:luci.chromium.ci/win-official/286",
"builder:win-official",
"buildset:commit/git/9dfba38d8e934105820fb9a298f5ee6538a1b152",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9dfba38d8e934105820fb9a298f5ee6538a1b152",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037981722806758288",
"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/8872551660614170000/+/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:4de5d4639c279910",
"user_agent:luci-scheduler"
],
"updated_ts": "1596879958074229",
"url": "https://ci.chromium.org/b/8872551660614170000",
"utcnow_ts": "1596879958525561"
}
}
[09:45:58.538] Invocation finished in 2h6m17.52005599s with status SUCCEEDED