[15:02:20.622] New invocation is queued and will start shortly
[15:02:22.338] Starting the invocation (attempt 1)
[15:02:22.381] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:02:22.382] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:02:22.382] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9038225663959864864",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1a3270152f2dcd617782b9b7a997660a02318dc9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9038225663959864864",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/1a3270152f2dcd617782b9b7a997660a02318dc9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1a3270152f2dcd617782b9b7a997660a02318dc9",
"gitiles_ref:refs/heads/master"
]
}
[15:02:23.152] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1596639742518259",
"id": "8872795601307471616",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1a3270152f2dcd617782b9b7a997660a02318dc9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1596639742978650",
"tags": [
"build_address:luci.chromium.ci/win-official/263",
"builder:win-official",
"buildset:commit/git/1a3270152f2dcd617782b9b7a997660a02318dc9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1a3270152f2dcd617782b9b7a997660a02318dc9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9038225663959864864",
"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/8872795601307471616/+/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": "1596639742978774",
"url": "https://ci.chromium.org/b/8872795601307471616",
"utcnow_ts": "1596639743141214"
}
}
[15:02:23.152] Task URL: https://ci.chromium.org/b/8872795601307471616
[15:02:23.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:2:0) after 1m0s
[15:02:26.564] Received PubSub notification, asking Buildbucket for the build status
[15:02:26.856] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:23.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:2:0)
[15:03:23.217] Timer tick, asking Buildbucket for the build status
[15:03:23.557] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:23.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:4:0) after 1m0s
[15:04:23.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:4:0)
[15:04:23.658] Timer tick, asking Buildbucket for the build status
[15:04:23.871] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:23.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:5:0) after 1m0s
[15:05:24.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:5:0)
[15:05:24.066] Timer tick, asking Buildbucket for the build status
[15:05:24.299] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:24.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:6:0) after 1m0s
[15:06:24.370] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:6:0)
[15:06:24.370] Timer tick, asking Buildbucket for the build status
[15:06:24.608] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:24.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:7:0) after 1m0s
[15:07:24.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:7:0)
[15:07:24.873] Timer tick, asking Buildbucket for the build status
[15:07:25.123] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:25.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:8:0) after 1m0s
[15:08:25.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:8:0)
[15:08:25.283] Timer tick, asking Buildbucket for the build status
[15:08:25.354] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:25.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:9:0) after 1m0s
[15:09:25.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:9:0)
[15:09:25.425] Timer tick, asking Buildbucket for the build status
[15:09:25.600] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:25.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:10:0) after 1m0s
[15:10:25.613] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:10:0)
[15:10:25.613] Timer tick, asking Buildbucket for the build status
[15:10:25.728] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:25.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:11:0) after 1m0s
[15:11:25.829] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:11:0)
[15:11:25.829] Timer tick, asking Buildbucket for the build status
[15:11:25.957] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:25.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:12:0) after 1m0s
[15:12:26.055] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:12:0)
[15:12:26.055] Timer tick, asking Buildbucket for the build status
[15:12:26.188] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:26.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:13:0) after 1m0s
[15:13:26.210] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:13:0)
[15:13:26.210] Timer tick, asking Buildbucket for the build status
[15:13:26.388] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:26.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:14:0) after 1m0s
[15:14:26.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:14:0)
[15:14:26.575] Timer tick, asking Buildbucket for the build status
[15:14:27.192] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:27.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:15:0) after 1m0s
[15:15:27.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:15:0)
[15:15:27.387] Timer tick, asking Buildbucket for the build status
[15:15:27.705] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:27.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:16:0) after 1m0s
[15:16:27.789] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:16:0)
[15:16:27.789] Timer tick, asking Buildbucket for the build status
[15:16:28.038] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:28.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:17:0) after 1m0s
[15:17:28.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:17:0)
[15:17:28.227] Timer tick, asking Buildbucket for the build status
[15:17:28.378] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:28.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:18:0) after 1m0s
[15:18:28.520] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:18:0)
[15:18:28.520] Timer tick, asking Buildbucket for the build status
[15:18:28.792] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:28.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:19:0) after 1m0s
[15:19:28.908] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:19:0)
[15:19:28.908] Timer tick, asking Buildbucket for the build status
[15:19:28.999] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:28.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:20:0) after 1m0s
[15:20:29.087] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:20:0)
[15:20:29.087] Timer tick, asking Buildbucket for the build status
[15:20:29.424] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:29.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:21:0) after 1m0s
[15:21:29.427] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:21:0)
[15:21:29.427] Timer tick, asking Buildbucket for the build status
[15:21:29.704] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:29.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:22:0) after 1m0s
[15:22:29.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:22:0)
[15:22:29.717] Timer tick, asking Buildbucket for the build status
[15:22:29.886] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:29.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:23:0) after 1m0s
[15:23:29.979] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:23:0)
[15:23:29.979] Timer tick, asking Buildbucket for the build status
[15:23:30.063] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:30.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:24:0) after 1m0s
[15:24:30.124] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:24:0)
[15:24:30.124] Timer tick, asking Buildbucket for the build status
[15:24:30.985] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:30.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:25:0) after 1m0s
[15:25:31.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:25:0)
[15:25:31.041] Timer tick, asking Buildbucket for the build status
[15:25:31.141] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:31.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:26:0) after 1m0s
[15:26:31.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:26:0)
[15:26:31.247] Timer tick, asking Buildbucket for the build status
[15:26:31.324] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:31.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:27:0) after 1m0s
[15:27:31.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:27:0)
[15:27:31.475] Timer tick, asking Buildbucket for the build status
[15:27:31.683] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:31.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:28:0) after 1m0s
[15:28:31.716] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:28:0)
[15:28:31.716] Timer tick, asking Buildbucket for the build status
[15:28:31.815] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:31.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:29:0) after 1m0s
[15:29:31.874] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:29:0)
[15:29:31.874] Timer tick, asking Buildbucket for the build status
[15:29:32.116] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:32.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:30:0) after 1m0s
[15:30:32.154] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:30:0)
[15:30:32.154] Timer tick, asking Buildbucket for the build status
[15:30:32.246] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:32.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:31:0) after 1m0s
[15:31:32.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:31:0)
[15:31:32.274] Timer tick, asking Buildbucket for the build status
[15:31:32.796] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:32.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:32:0) after 1m0s
[15:32:32.918] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:32:0)
[15:32:32.918] Timer tick, asking Buildbucket for the build status
[15:32:33.101] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:33.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:33:0) after 1m0s
[15:33:33.129] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:33:0)
[15:33:33.129] Timer tick, asking Buildbucket for the build status
[15:33:33.250] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:33.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:34:0) after 1m0s
[15:34:33.317] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:34:0)
[15:34:33.317] Timer tick, asking Buildbucket for the build status
[15:34:33.404] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:33.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:35:0) after 1m0s
[15:35:33.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:35:0)
[15:35:33.401] Timer tick, asking Buildbucket for the build status
[15:35:33.515] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:33.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:36:0) after 1m0s
[15:36:33.577] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:36:0)
[15:36:33.577] Timer tick, asking Buildbucket for the build status
[15:36:33.721] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:33.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:37:0) after 1m0s
[15:37:33.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:37:0)
[15:37:33.761] Timer tick, asking Buildbucket for the build status
[15:37:33.910] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:33.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:38:0) after 1m0s
[15:38:33.923] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:38:0)
[15:38:33.923] Timer tick, asking Buildbucket for the build status
[15:38:33.995] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:33.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:39:0) after 1m0s
[15:39:34.092] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:39:0)
[15:39:34.092] Timer tick, asking Buildbucket for the build status
[15:39:34.292] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:34.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:40:0) after 1m0s
[15:40:34.335] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:40:0)
[15:40:34.335] Timer tick, asking Buildbucket for the build status
[15:40:34.399] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:34.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:41:0) after 1m0s
[15:41:34.500] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:41:0)
[15:41:34.500] Timer tick, asking Buildbucket for the build status
[15:41:34.568] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:34.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:42:0) after 1m0s
[15:42:34.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:42:0)
[15:42:34.645] Timer tick, asking Buildbucket for the build status
[15:42:34.955] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:34.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:43:0) after 1m0s
[15:43:34.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:43:0)
[15:43:34.957] Timer tick, asking Buildbucket for the build status
[15:43:35.227] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:35.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:44:0) after 1m0s
[15:44:35.257] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:44:0)
[15:44:35.257] Timer tick, asking Buildbucket for the build status
[15:44:35.341] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:35.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:45:0) after 1m0s
[15:45:35.360] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:45:0)
[15:45:35.360] Timer tick, asking Buildbucket for the build status
[15:45:35.454] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:35.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:46:0) after 1m0s
[15:46:35.595] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:46:0)
[15:46:35.595] Timer tick, asking Buildbucket for the build status
[15:46:35.713] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:35.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:47:0) after 1m0s
[15:47:35.727] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:47:0)
[15:47:35.727] Timer tick, asking Buildbucket for the build status
[15:47:35.951] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:35.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:48:0) after 1m0s
[15:48:36.001] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:48:0)
[15:48:36.001] Timer tick, asking Buildbucket for the build status
[15:48:36.116] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:36.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:49:0) after 1m0s
[15:49:36.166] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:49:0)
[15:49:36.166] Timer tick, asking Buildbucket for the build status
[15:49:36.274] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:36.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:50:0) after 1m0s
[15:50:36.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:50:0)
[15:50:36.372] Timer tick, asking Buildbucket for the build status
[15:50:36.451] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:36.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:51:0) after 1m0s
[15:51:36.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:51:0)
[15:51:36.682] Timer tick, asking Buildbucket for the build status
[15:51:36.743] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:36.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:52:0) after 1m0s
[15:52:36.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:52:0)
[15:52:36.741] Timer tick, asking Buildbucket for the build status
[15:52:36.934] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:36.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:53:0) after 1m0s
[15:53:37.058] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:53:0)
[15:53:37.058] Timer tick, asking Buildbucket for the build status
[15:53:37.142] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:37.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:54:0) after 1m0s
[15:54:37.183] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:54:0)
[15:54:37.183] Timer tick, asking Buildbucket for the build status
[15:54:37.533] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:37.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:55:0) after 1m0s
[15:55:37.564] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:55:0)
[15:55:37.564] Timer tick, asking Buildbucket for the build status
[15:55:37.644] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:37.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:56:0) after 1m0s
[15:56:37.672] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:56:0)
[15:56:37.672] Timer tick, asking Buildbucket for the build status
[15:56:37.845] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:37.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:57:0) after 1m0s
[15:57:37.879] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:57:0)
[15:57:37.879] Timer tick, asking Buildbucket for the build status
[15:57:38.161] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:38.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:58:0) after 1m0s
[15:58:38.210] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:58:0)
[15:58:38.210] Timer tick, asking Buildbucket for the build status
[15:58:38.348] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:38.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:59:0) after 1m0s
[15:59:38.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:59:0)
[15:59:38.409] Timer tick, asking Buildbucket for the build status
[15:59:38.472] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:38.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:60:0) after 1m0s
[16:00:38.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:60:0)
[16:00:38.524] Timer tick, asking Buildbucket for the build status
[16:00:38.669] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:38.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:61:0) after 1m0s
[16:01:38.755] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:61:0)
[16:01:38.755] Timer tick, asking Buildbucket for the build status
[16:01:39.405] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:39.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:62:0) after 1m0s
[16:02:39.449] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:62:0)
[16:02:39.449] Timer tick, asking Buildbucket for the build status
[16:02:39.578] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:39.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:63:0) after 1m0s
[16:03:39.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:63:0)
[16:03:39.678] Timer tick, asking Buildbucket for the build status
[16:03:39.791] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:39.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:64:0) after 1m0s
[16:04:39.821] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:64:0)
[16:04:39.821] Timer tick, asking Buildbucket for the build status
[16:04:39.891] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:39.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:65:0) after 1m0s
[16:05:39.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:65:0)
[16:05:39.956] Timer tick, asking Buildbucket for the build status
[16:05:40.042] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:40.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:66:0) after 1m0s
[16:06:40.171] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:66:0)
[16:06:40.171] Timer tick, asking Buildbucket for the build status
[16:06:40.338] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:40.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:67:0) after 1m0s
[16:07:40.374] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:67:0)
[16:07:40.374] Timer tick, asking Buildbucket for the build status
[16:07:40.908] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:40.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:68:0) after 1m0s
[16:08:40.901] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:68:0)
[16:08:40.901] Timer tick, asking Buildbucket for the build status
[16:08:40.979] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:40.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:69:0) after 1m0s
[16:09:40.981] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:69:0)
[16:09:40.981] Timer tick, asking Buildbucket for the build status
[16:09:41.152] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:41.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:70:0) after 1m0s
[16:10:41.226] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:70:0)
[16:10:41.226] Timer tick, asking Buildbucket for the build status
[16:10:41.357] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:41.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:71:0) after 1m0s
[16:11:41.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:71:0)
[16:11:41.383] Timer tick, asking Buildbucket for the build status
[16:11:41.825] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:41.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:72:0) after 1m0s
[16:12:41.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:72:0)
[16:12:41.819] Timer tick, asking Buildbucket for the build status
[16:12:42.002] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:42.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:73:0) after 1m0s
[16:13:42.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:73:0)
[16:13:42.030] Timer tick, asking Buildbucket for the build status
[16:13:42.106] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:42.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:74:0) after 1m0s
[16:14:42.128] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:74:0)
[16:14:42.128] Timer tick, asking Buildbucket for the build status
[16:14:42.466] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:42.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:75:0) after 1m0s
[16:15:42.507] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:75:0)
[16:15:42.507] Timer tick, asking Buildbucket for the build status
[16:15:42.630] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:42.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:76:0) after 1m0s
[16:16:42.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:76:0)
[16:16:42.678] Timer tick, asking Buildbucket for the build status
[16:16:42.735] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:42.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:77:0) after 1m0s
[16:17:42.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:77:0)
[16:17:42.890] Timer tick, asking Buildbucket for the build status
[16:17:43.051] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:43.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:78:0) after 1m0s
[16:18:43.150] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:78:0)
[16:18:43.150] Timer tick, asking Buildbucket for the build status
[16:18:43.243] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:43.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:79:0) after 1m0s
[16:19:43.321] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:79:0)
[16:19:43.321] Timer tick, asking Buildbucket for the build status
[16:19:43.403] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:43.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:80:0) after 1m0s
[16:20:43.473] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:80:0)
[16:20:43.473] Timer tick, asking Buildbucket for the build status
[16:20:43.550] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:43.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:81:0) after 1m0s
[16:21:43.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:81:0)
[16:21:43.597] Timer tick, asking Buildbucket for the build status
[16:21:43.712] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:43.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:82:0) after 1m0s
[16:22:43.766] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:82:0)
[16:22:43.766] Timer tick, asking Buildbucket for the build status
[16:22:43.914] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:43.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:83:0) after 1m0s
[16:23:43.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:83:0)
[16:23:43.949] Timer tick, asking Buildbucket for the build status
[16:23:44.017] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:44.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:84:0) after 1m0s
[16:24:44.567] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:84:0)
[16:24:44.567] Timer tick, asking Buildbucket for the build status
[16:24:44.662] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:44.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:85:0) after 1m0s
[16:25:44.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:85:0)
[16:25:44.760] Timer tick, asking Buildbucket for the build status
[16:25:45.065] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:45.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:86:0) after 1m0s
[16:26:45.228] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:86:0)
[16:26:45.228] Timer tick, asking Buildbucket for the build status
[16:26:45.295] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:45.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:87:0) after 1m0s
[16:27:45.326] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:87:0)
[16:27:45.326] Timer tick, asking Buildbucket for the build status
[16:27:45.522] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:45.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:88:0) after 1m0s
[16:28:45.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:88:0)
[16:28:45.682] Timer tick, asking Buildbucket for the build status
[16:28:45.933] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:45.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:89:0) after 1m0s
[16:29:46.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:89:0)
[16:29:46.086] Timer tick, asking Buildbucket for the build status
[16:29:46.300] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:46.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:90:0) after 1m0s
[16:30:46.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:90:0)
[16:30:46.452] Timer tick, asking Buildbucket for the build status
[16:30:46.533] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:46.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:91:0) after 1m0s
[16:31:46.594] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:91:0)
[16:31:46.594] Timer tick, asking Buildbucket for the build status
[16:31:46.854] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:46.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:92:0) after 1m0s
[16:32:46.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:92:0)
[16:32:46.921] Timer tick, asking Buildbucket for the build status
[16:32:47.073] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:47.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:93:0) after 1m0s
[16:33:47.255] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:93:0)
[16:33:47.255] Timer tick, asking Buildbucket for the build status
[16:33:47.422] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:47.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:94:0) after 1m0s
[16:34:47.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:94:0)
[16:34:47.779] Timer tick, asking Buildbucket for the build status
[16:34:47.961] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:47.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:95:0) after 1m0s
[16:35:48.089] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:95:0)
[16:35:48.089] Timer tick, asking Buildbucket for the build status
[16:35:48.234] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:48.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:96:0) after 1m0s
[16:36:48.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:96:0)
[16:36:48.314] Timer tick, asking Buildbucket for the build status
[16:36:48.385] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:48.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:97:0) after 1m0s
[16:37:48.612] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:97:0)
[16:37:48.612] Timer tick, asking Buildbucket for the build status
[16:37:48.835] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:48.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:98:0) after 1m0s
[16:38:48.942] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:98:0)
[16:38:48.942] Timer tick, asking Buildbucket for the build status
[16:38:49.032] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:49.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:99:0) after 1m0s
[16:39:49.138] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:99:0)
[16:39:49.138] Timer tick, asking Buildbucket for the build status
[16:39:49.242] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:49.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:100:0) after 1m0s
[16:40:49.278] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:100:0)
[16:40:49.278] Timer tick, asking Buildbucket for the build status
[16:40:49.344] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:49.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:101:0) after 1m0s
[16:41:49.406] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:101:0)
[16:41:49.406] Timer tick, asking Buildbucket for the build status
[16:41:49.557] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:49.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:102:0) after 1m0s
[16:42:49.661] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:102:0)
[16:42:49.661] Timer tick, asking Buildbucket for the build status
[16:42:49.895] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:49.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:103:0) after 1m0s
[16:43:49.935] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:103:0)
[16:43:49.935] Timer tick, asking Buildbucket for the build status
[16:43:50.046] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:50.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:104:0) after 1m0s
[16:44:50.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:104:0)
[16:44:50.151] Timer tick, asking Buildbucket for the build status
[16:44:50.584] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:50.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:105:0) after 1m0s
[16:45:50.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:105:0)
[16:45:50.618] Timer tick, asking Buildbucket for the build status
[16:45:50.779] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:50.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:106:0) after 1m0s
[16:46:50.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:106:0)
[16:46:50.804] Timer tick, asking Buildbucket for the build status
[16:46:51.121] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:51.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:107:0) after 1m0s
[16:47:51.248] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:107:0)
[16:47:51.248] Timer tick, asking Buildbucket for the build status
[16:47:51.366] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:51.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:108:0) after 1m0s
[16:48:51.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:108:0)
[16:48:51.414] Timer tick, asking Buildbucket for the build status
[16:48:51.490] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:51.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:109:0) after 1m0s
[16:49:51.516] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:109:0)
[16:49:51.516] Timer tick, asking Buildbucket for the build status
[16:49:52.128] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:52.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:110:0) after 1m0s
[16:50:52.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:110:0)
[16:50:52.160] Timer tick, asking Buildbucket for the build status
[16:50:52.345] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:52.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:111:0) after 1m0s
[16:51:52.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:111:0)
[16:51:52.482] Timer tick, asking Buildbucket for the build status
[16:51:52.637] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:52.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:112:0) after 1m0s
[16:52:52.747] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:112:0)
[16:52:52.747] Timer tick, asking Buildbucket for the build status
[16:52:53.031] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:53.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:113:0) after 1m0s
[16:53:53.099] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:113:0)
[16:53:53.101] Timer tick, asking Buildbucket for the build status
[16:53:53.311] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:53.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:114:0) after 1m0s
[16:54:53.433] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:114:0)
[16:54:53.433] Timer tick, asking Buildbucket for the build status
[16:54:53.610] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:53.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:115:0) after 1m0s
[16:55:53.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:115:0)
[16:55:53.676] Timer tick, asking Buildbucket for the build status
[16:55:53.739] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:53.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:116:0) after 1m0s
[16:56:53.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:116:0)
[16:56:53.768] Timer tick, asking Buildbucket for the build status
[16:56:53.854] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:53.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:117:0) after 1m0s
[16:57:53.887] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:117:0)
[16:57:53.887] Timer tick, asking Buildbucket for the build status
[16:57:54.129] Build 8872795601307471616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:54.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038225663959864864:118:0) after 1m0s
[16:58:29.769] Received PubSub notification, asking Buildbucket for the build status
[16:58:29.913] Build 8872795601307471616: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:58:29.913] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1596646709121068",
"created_by": "project:chromium",
"created_ts": "1596639742518259",
"id": "8872795601307471616",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1a3270152f2dcd617782b9b7a997660a02318dc9\"}}",
"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/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"1a3270152f2dcd617782b9b7a997660a02318dc9\", \"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-6-w2va\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 263, \"got_angle_revision\": \"e4e2a847dab3512f10f28f212d8f6911336ed069\", \"got_dawn_revision\": \"076a4e58205dfa2bcf5e33cc8718aa4799d46735\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"1a3270152f2dcd617782b9b7a997660a02318dc9\", \"got_revision_cp\": \"refs/heads/master@{#795020}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"be7c55a2a8cebd46ba6912e6b7d4dae8353d154c\", \"got_v8_revision\": \"ce4c3060f6fbfd5ed410de314f7e83a66fd792d9\", \"got_v8_revision_cp\": \"refs/heads/8.6.334@{#1}\", \"got_webrtc_revision\": \"7e30909bf44a224f7dbb49fc6bac6f2fdd4fd822\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31842}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1a3270152f2dcd617782b9b7a997660a02318dc9\"}, \"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-6-w2va\"], \"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\": [\"5436-adf7f8a\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1596639745622352",
"status": "COMPLETED",
"status_changed_ts": "1596646709477269",
"tags": [
"build_address:luci.chromium.ci/win-official/263",
"builder:win-official",
"buildset:commit/git/1a3270152f2dcd617782b9b7a997660a02318dc9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1a3270152f2dcd617782b9b7a997660a02318dc9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9038225663959864864",
"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/8872795601307471616/+/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:4dd7f6958314c710",
"user_agent:luci-scheduler"
],
"updated_ts": "1596646709483382",
"url": "https://ci.chromium.org/b/8872795601307471616",
"utcnow_ts": "1596646709904068"
}
}
[16:58:29.913] Invocation finished in 1h56m9.308676406s with status SUCCEEDED