[21:22:33.455] New invocation is queued and will start shortly
[21:22:34.799] Starting the invocation (attempt 1)
[21:22:34.860] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:22:34.860] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:22:34.860] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9035302640046535776",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1ccf4c97ccfc6fcd1ea631acae9ca217da044b61\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9035302640046535776",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/1ccf4c97ccfc6fcd1ea631acae9ca217da044b61",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ccf4c97ccfc6fcd1ea631acae9ca217da044b61",
"gitiles_ref:refs/heads/master"
]
}
[21:22:35.775] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1599427354984906",
"id": "8869872577778397152",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1ccf4c97ccfc6fcd1ea631acae9ca217da044b61\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599427355617143",
"tags": [
"build_address:luci.chromium.ci/win-official/524",
"builder:win-official",
"buildset:commit/git/1ccf4c97ccfc6fcd1ea631acae9ca217da044b61",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ccf4c97ccfc6fcd1ea631acae9ca217da044b61",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035302640046535776",
"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/8869872577778397152/+/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": "1599427355617346",
"url": "https://ci.chromium.org/b/8869872577778397152",
"utcnow_ts": "1599427355762082"
}
}
[21:22:35.775] Task URL: https://ci.chromium.org/b/8869872577778397152
[21:22:35.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:2:0) after 1m0s
[21:23:36.876] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:2:0)
[21:23:36.876] Timer tick, asking Buildbucket for the build status
[21:23:37.023] Build 8869872577778397152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:37.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:3:0) after 1m0s
[21:24:03.781] Received PubSub notification, asking Buildbucket for the build status
[21:24:03.892] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:37.088] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:3:0)
[21:24:37.088] Timer tick, asking Buildbucket for the build status
[21:24:37.228] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:37.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:5:0) after 1m0s
[21:25:37.252] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:5:0)
[21:25:37.252] Timer tick, asking Buildbucket for the build status
[21:25:37.842] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:37.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:6:0) after 1m0s
[21:26:37.960] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:6:0)
[21:26:37.960] Timer tick, asking Buildbucket for the build status
[21:26:38.158] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:38.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:7:0) after 1m0s
[21:27:38.190] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:7:0)
[21:27:38.190] Timer tick, asking Buildbucket for the build status
[21:27:38.311] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:38.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:8:0) after 1m0s
[21:28:38.332] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:8:0)
[21:28:38.332] Timer tick, asking Buildbucket for the build status
[21:28:38.440] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:38.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:9:0) after 1m0s
[21:29:38.469] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:9:0)
[21:29:38.470] Timer tick, asking Buildbucket for the build status
[21:29:38.722] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:38.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:10:0) after 1m0s
[21:30:39.158] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:10:0)
[21:30:39.158] Timer tick, asking Buildbucket for the build status
[21:30:39.445] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:39.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:11:0) after 1m0s
[21:31:39.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:11:0)
[21:31:39.482] Timer tick, asking Buildbucket for the build status
[21:31:39.571] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:39.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:12:0) after 1m0s
[21:32:39.593] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:12:0)
[21:32:39.593] Timer tick, asking Buildbucket for the build status
[21:32:39.717] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:39.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:13:0) after 1m0s
[21:33:39.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:13:0)
[21:33:39.763] Timer tick, asking Buildbucket for the build status
[21:33:39.950] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:39.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:14:0) after 1m0s
[21:34:40.211] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:14:0)
[21:34:40.211] Timer tick, asking Buildbucket for the build status
[21:34:40.352] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:40.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:15:0) after 1m0s
[21:35:40.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:15:0)
[21:35:40.371] Timer tick, asking Buildbucket for the build status
[21:35:40.715] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:40.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:16:0) after 1m0s
[21:36:40.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:16:0)
[21:36:40.756] Timer tick, asking Buildbucket for the build status
[21:36:40.834] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:40.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:17:0) after 1m0s
[21:37:40.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:17:0)
[21:37:40.868] Timer tick, asking Buildbucket for the build status
[21:37:41.095] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:41.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:18:0) after 1m0s
[21:38:41.124] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:18:0)
[21:38:41.124] Timer tick, asking Buildbucket for the build status
[21:38:41.317] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:41.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:19:0) after 1m0s
[21:39:41.343] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:19:0)
[21:39:41.343] Timer tick, asking Buildbucket for the build status
[21:39:41.442] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:41.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:20:0) after 1m0s
[21:40:41.464] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:20:0)
[21:40:41.464] Timer tick, asking Buildbucket for the build status
[21:40:41.531] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:41.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:21:0) after 1m0s
[21:41:41.551] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:21:0)
[21:41:41.551] Timer tick, asking Buildbucket for the build status
[21:41:41.616] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:41.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:22:0) after 1m0s
[21:42:41.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:22:0)
[21:42:41.676] Timer tick, asking Buildbucket for the build status
[21:42:41.788] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:41.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:23:0) after 1m0s
[21:43:41.835] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:23:0)
[21:43:41.835] Timer tick, asking Buildbucket for the build status
[21:43:42.598] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:42.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:24:0) after 1m0s
[21:44:42.664] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:24:0)
[21:44:42.664] Timer tick, asking Buildbucket for the build status
[21:44:42.730] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:42.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:25:0) after 1m0s
[21:45:42.806] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:25:0)
[21:45:42.806] Timer tick, asking Buildbucket for the build status
[21:45:42.876] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:42.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:26:0) after 1m0s
[21:46:42.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:26:0)
[21:46:42.933] Timer tick, asking Buildbucket for the build status
[21:46:43.037] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:43.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:27:0) after 1m0s
[21:47:43.068] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:27:0)
[21:47:43.068] Timer tick, asking Buildbucket for the build status
[21:47:43.163] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:43.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:28:0) after 1m0s
[21:48:43.243] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:28:0)
[21:48:43.243] Timer tick, asking Buildbucket for the build status
[21:48:43.365] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:43.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:29:0) after 1m0s
[21:49:43.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:29:0)
[21:49:43.607] Timer tick, asking Buildbucket for the build status
[21:49:43.877] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:43.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:30:0) after 1m0s
[21:50:43.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:30:0)
[21:50:43.976] Timer tick, asking Buildbucket for the build status
[21:50:44.079] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:44.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:31:0) after 1m0s
[21:51:44.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:31:0)
[21:51:44.328] Timer tick, asking Buildbucket for the build status
[21:51:44.476] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:44.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:32:0) after 1m0s
[21:52:44.609] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:32:0)
[21:52:44.609] Timer tick, asking Buildbucket for the build status
[21:52:44.670] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:44.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:33:0) after 1m0s
[21:53:44.736] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:33:0)
[21:53:44.736] Timer tick, asking Buildbucket for the build status
[21:53:44.884] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:44.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:34:0) after 1m0s
[21:54:44.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:34:0)
[21:54:44.987] Timer tick, asking Buildbucket for the build status
[21:54:45.050] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:45.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:35:0) after 1m0s
[21:55:45.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:35:0)
[21:55:45.146] Timer tick, asking Buildbucket for the build status
[21:55:45.222] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:45.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:36:0) after 1m0s
[21:56:45.376] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:36:0)
[21:56:45.376] Timer tick, asking Buildbucket for the build status
[21:56:45.518] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:45.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:37:0) after 1m0s
[21:57:45.538] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:37:0)
[21:57:45.538] Timer tick, asking Buildbucket for the build status
[21:57:45.680] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:45.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:38:0) after 1m0s
[21:58:45.702] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:38:0)
[21:58:45.702] Timer tick, asking Buildbucket for the build status
[21:58:45.816] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:45.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:39:0) after 1m0s
[21:59:45.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:39:0)
[21:59:45.842] Timer tick, asking Buildbucket for the build status
[21:59:46.121] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:46.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:40:0) after 1m0s
[22:00:46.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:40:0)
[22:00:46.209] Timer tick, asking Buildbucket for the build status
[22:00:46.282] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:46.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:41:0) after 1m0s
[22:01:46.311] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:41:0)
[22:01:46.311] Timer tick, asking Buildbucket for the build status
[22:01:46.399] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:46.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:42:0) after 1m0s
[22:02:46.453] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:42:0)
[22:02:46.453] Timer tick, asking Buildbucket for the build status
[22:02:46.609] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:46.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:43:0) after 1m0s
[22:03:46.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:43:0)
[22:03:46.639] Timer tick, asking Buildbucket for the build status
[22:03:46.754] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:46.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:44:0) after 1m0s
[22:04:46.775] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:44:0)
[22:04:46.775] Timer tick, asking Buildbucket for the build status
[22:04:47.122] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:47.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:45:0) after 1m0s
[22:05:47.158] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:45:0)
[22:05:47.158] Timer tick, asking Buildbucket for the build status
[22:05:47.249] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:47.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:46:0) after 1m0s
[22:06:47.346] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:46:0)
[22:06:47.349] Timer tick, asking Buildbucket for the build status
[22:06:47.548] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:47.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:47:0) after 1m0s
[22:07:47.583] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:47:0)
[22:07:47.583] Timer tick, asking Buildbucket for the build status
[22:07:47.654] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:47.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:48:0) after 1m0s
[22:08:47.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:48:0)
[22:08:47.676] Timer tick, asking Buildbucket for the build status
[22:08:47.832] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:47.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:49:0) after 1m0s
[22:09:47.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:49:0)
[22:09:47.955] Timer tick, asking Buildbucket for the build status
[22:09:48.085] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:48.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:50:0) after 1m0s
[22:10:48.113] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:50:0)
[22:10:48.113] Timer tick, asking Buildbucket for the build status
[22:10:48.171] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:48.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:51:0) after 1m0s
[22:11:48.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:51:0)
[22:11:48.204] Timer tick, asking Buildbucket for the build status
[22:11:48.440] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:48.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:52:0) after 1m0s
[22:12:48.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:52:0)
[22:12:48.701] Timer tick, asking Buildbucket for the build status
[22:12:49.052] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:49.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:53:0) after 1m0s
[22:13:49.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:53:0)
[22:13:49.107] Timer tick, asking Buildbucket for the build status
[22:13:49.209] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:49.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:54:0) after 1m0s
[22:14:49.445] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:54:0)
[22:14:49.445] Timer tick, asking Buildbucket for the build status
[22:14:49.564] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:49.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:55:0) after 1m0s
[22:15:49.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:55:0)
[22:15:49.864] Timer tick, asking Buildbucket for the build status
[22:15:49.931] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:49.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:56:0) after 1m0s
[22:16:50.320] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:56:0)
[22:16:50.320] Timer tick, asking Buildbucket for the build status
[22:16:50.394] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:50.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:57:0) after 1m0s
[22:17:50.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:57:0)
[22:17:50.731] Timer tick, asking Buildbucket for the build status
[22:17:50.868] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:50.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:58:0) after 1m0s
[22:18:50.888] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:58:0)
[22:18:50.888] Timer tick, asking Buildbucket for the build status
[22:18:51.502] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:51.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:59:0) after 1m0s
[22:19:51.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:59:0)
[22:19:51.604] Timer tick, asking Buildbucket for the build status
[22:19:51.688] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:51.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:60:0) after 1m0s
[22:20:51.712] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:60:0)
[22:20:51.712] Timer tick, asking Buildbucket for the build status
[22:20:51.783] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:51.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:61:0) after 1m0s
[22:21:52.000] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:61:0)
[22:21:52.000] Timer tick, asking Buildbucket for the build status
[22:21:52.079] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:52.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:62:0) after 1m0s
[22:22:52.320] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:62:0)
[22:22:52.320] Timer tick, asking Buildbucket for the build status
[22:22:52.493] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:52.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:63:0) after 1m0s
[22:23:52.536] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:63:0)
[22:23:52.536] Timer tick, asking Buildbucket for the build status
[22:23:52.883] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:52.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:64:0) after 1m0s
[22:24:52.923] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:64:0)
[22:24:52.923] Timer tick, asking Buildbucket for the build status
[22:24:53.541] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:53.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:65:0) after 1m0s
[22:25:53.563] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:65:0)
[22:25:53.563] Timer tick, asking Buildbucket for the build status
[22:25:53.712] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:53.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:66:0) after 1m0s
[22:26:53.773] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:66:0)
[22:26:53.773] Timer tick, asking Buildbucket for the build status
[22:26:54.614] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:54.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:67:0) after 1m0s
[22:27:54.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:67:0)
[22:27:54.639] Timer tick, asking Buildbucket for the build status
[22:27:54.903] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:54.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:68:0) after 1m0s
[22:28:55.355] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:68:0)
[22:28:55.356] Timer tick, asking Buildbucket for the build status
[22:28:55.742] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:55.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:69:0) after 1m0s
[22:29:55.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:69:0)
[22:29:55.768] Timer tick, asking Buildbucket for the build status
[22:29:55.851] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:55.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:70:0) after 1m0s
[22:30:55.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:70:0)
[22:30:55.962] Timer tick, asking Buildbucket for the build status
[22:30:56.103] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:56.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:71:0) after 1m0s
[22:31:56.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:71:0)
[22:31:56.268] Timer tick, asking Buildbucket for the build status
[22:31:56.337] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:56.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:72:0) after 1m0s
[22:32:56.515] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:72:0)
[22:32:56.515] Timer tick, asking Buildbucket for the build status
[22:32:56.632] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:56.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:73:0) after 1m0s
[22:33:56.881] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:73:0)
[22:33:56.881] Timer tick, asking Buildbucket for the build status
[22:33:57.218] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:57.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:74:0) after 1m0s
[22:34:57.634] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:74:0)
[22:34:57.634] Timer tick, asking Buildbucket for the build status
[22:34:57.772] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:57.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:75:0) after 1m0s
[22:35:57.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:75:0)
[22:35:57.761] Timer tick, asking Buildbucket for the build status
[22:35:57.834] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:57.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:76:0) after 1m0s
[22:36:57.906] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:76:0)
[22:36:57.906] Timer tick, asking Buildbucket for the build status
[22:36:58.120] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:58.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:77:0) after 1m0s
[22:37:58.153] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:77:0)
[22:37:58.153] Timer tick, asking Buildbucket for the build status
[22:37:58.226] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:58.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:78:0) after 1m0s
[22:38:58.318] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:78:0)
[22:38:58.319] Timer tick, asking Buildbucket for the build status
[22:38:58.632] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:58.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:79:0) after 1m0s
[22:39:58.914] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:79:0)
[22:39:58.914] Timer tick, asking Buildbucket for the build status
[22:39:59.146] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:59.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:80:0) after 1m0s
[22:40:59.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:80:0)
[22:40:59.233] Timer tick, asking Buildbucket for the build status
[22:40:59.370] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:59.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:81:0) after 1m0s
[22:41:59.594] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:81:0)
[22:41:59.594] Timer tick, asking Buildbucket for the build status
[22:41:59.657] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:59.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:82:0) after 1m0s
[22:42:59.713] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:82:0)
[22:42:59.713] Timer tick, asking Buildbucket for the build status
[22:42:59.799] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:59.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:83:0) after 1m0s
[22:43:59.778] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:83:0)
[22:43:59.778] Timer tick, asking Buildbucket for the build status
[22:43:59.915] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:59.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:84:0) after 1m0s
[22:44:59.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:84:0)
[22:44:59.951] Timer tick, asking Buildbucket for the build status
[22:45:00.178] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:00.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:85:0) after 1m0s
[22:46:00.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:85:0)
[22:46:00.209] Timer tick, asking Buildbucket for the build status
[22:46:00.345] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:00.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:86:0) after 1m0s
[22:47:01.878] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:86:0)
[22:47:01.878] Timer tick, asking Buildbucket for the build status
[22:47:02.275] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:02.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:87:0) after 1m0s
[22:48:02.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:87:0)
[22:48:02.309] Timer tick, asking Buildbucket for the build status
[22:48:02.386] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:02.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:88:0) after 1m0s
[22:49:03.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:88:0)
[22:49:03.241] Timer tick, asking Buildbucket for the build status
[22:49:03.483] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:03.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:89:0) after 1m0s
[22:50:03.633] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:89:0)
[22:50:03.633] Timer tick, asking Buildbucket for the build status
[22:50:03.707] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:03.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:90:0) after 1m0s
[22:51:03.810] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:90:0)
[22:51:03.810] Timer tick, asking Buildbucket for the build status
[22:51:03.892] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:03.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:91:0) after 1m0s
[22:52:03.883] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:91:0)
[22:52:03.883] Timer tick, asking Buildbucket for the build status
[22:52:04.008] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:04.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:92:0) after 1m0s
[22:53:04.072] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:92:0)
[22:53:04.072] Timer tick, asking Buildbucket for the build status
[22:53:04.285] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:04.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:93:0) after 1m0s
[22:54:04.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:93:0)
[22:54:04.369] Timer tick, asking Buildbucket for the build status
[22:54:04.692] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:04.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:94:0) after 1m0s
[22:55:04.856] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:94:0)
[22:55:04.856] Timer tick, asking Buildbucket for the build status
[22:55:04.952] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:04.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:95:0) after 1m0s
[22:56:04.990] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:95:0)
[22:56:04.990] Timer tick, asking Buildbucket for the build status
[22:56:05.070] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:05.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:96:0) after 1m0s
[22:57:05.140] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:96:0)
[22:57:05.140] Timer tick, asking Buildbucket for the build status
[22:57:05.316] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:05.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:97:0) after 1m0s
[22:58:05.486] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:97:0)
[22:58:05.486] Timer tick, asking Buildbucket for the build status
[22:58:05.606] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:05.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:98:0) after 1m0s
[22:59:05.739] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:98:0)
[22:59:05.739] Timer tick, asking Buildbucket for the build status
[22:59:05.808] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:05.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:99:0) after 1m0s
[23:00:05.961] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:99:0)
[23:00:05.961] Timer tick, asking Buildbucket for the build status
[23:00:06.043] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:06.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:100:0) after 1m0s
[23:01:06.513] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:100:0)
[23:01:06.513] Timer tick, asking Buildbucket for the build status
[23:01:06.814] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:06.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:101:0) after 1m0s
[23:02:07.128] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:101:0)
[23:02:07.128] Timer tick, asking Buildbucket for the build status
[23:02:07.305] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:07.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:102:0) after 1m0s
[23:03:08.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:102:0)
[23:03:08.015] Timer tick, asking Buildbucket for the build status
[23:03:08.189] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:08.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:103:0) after 1m0s
[23:04:08.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:103:0)
[23:04:08.412] Timer tick, asking Buildbucket for the build status
[23:04:08.505] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:08.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:104:0) after 1m0s
[23:05:08.551] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:104:0)
[23:05:08.551] Timer tick, asking Buildbucket for the build status
[23:05:08.620] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:08.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:105:0) after 1m0s
[23:06:08.651] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:105:0)
[23:06:08.652] Timer tick, asking Buildbucket for the build status
[23:06:08.730] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:08.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:106:0) after 1m0s
[23:07:09.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:106:0)
[23:07:09.013] Timer tick, asking Buildbucket for the build status
[23:07:09.092] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:09.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:107:0) after 1m0s
[23:08:09.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:107:0)
[23:08:09.241] Timer tick, asking Buildbucket for the build status
[23:08:09.364] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:09.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:108:0) after 1m0s
[23:09:09.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:108:0)
[23:09:09.537] Timer tick, asking Buildbucket for the build status
[23:09:09.640] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:09.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:109:0) after 1m0s
[23:10:11.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:109:0)
[23:10:11.619] Timer tick, asking Buildbucket for the build status
[23:10:11.743] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:11.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:110:0) after 1m0s
[23:11:13.360] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:110:0)
[23:11:13.360] Timer tick, asking Buildbucket for the build status
[23:11:13.473] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:13.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:111:0) after 1m0s
[23:12:13.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:111:0)
[23:12:13.919] Timer tick, asking Buildbucket for the build status
[23:12:13.991] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:13.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:112:0) after 1m0s
[23:13:14.620] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:112:0)
[23:13:14.620] Timer tick, asking Buildbucket for the build status
[23:13:14.780] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:14.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:113:0) after 1m0s
[23:14:15.396] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:113:0)
[23:14:15.396] Timer tick, asking Buildbucket for the build status
[23:14:15.847] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:15.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:114:0) after 1m0s
[23:15:15.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:114:0)
[23:15:15.951] Timer tick, asking Buildbucket for the build status
[23:15:16.014] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:16.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:115:0) after 1m0s
[23:16:16.212] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:115:0)
[23:16:16.212] Timer tick, asking Buildbucket for the build status
[23:16:16.314] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:16.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:116:0) after 1m0s
[23:17:16.502] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:116:0)
[23:17:16.502] Timer tick, asking Buildbucket for the build status
[23:17:16.567] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:16.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:117:0) after 1m0s
[23:18:16.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:117:0)
[23:18:16.905] Timer tick, asking Buildbucket for the build status
[23:18:17.051] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:17.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:118:0) after 1m0s
[23:19:17.289] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:118:0)
[23:19:17.289] Timer tick, asking Buildbucket for the build status
[23:19:17.504] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:17.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:119:0) after 1m0s
[23:20:17.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:119:0)
[23:20:17.701] Timer tick, asking Buildbucket for the build status
[23:20:17.854] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:17.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:120:0) after 1m0s
[23:21:17.876] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:120:0)
[23:21:17.876] Timer tick, asking Buildbucket for the build status
[23:21:17.926] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:17.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:121:0) after 1m0s
[23:22:17.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:121:0)
[23:22:17.951] Timer tick, asking Buildbucket for the build status
[23:22:18.153] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:18.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:122:0) after 1m0s
[23:23:18.178] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:122:0)
[23:23:18.178] Timer tick, asking Buildbucket for the build status
[23:23:18.391] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:18.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:123:0) after 1m0s
[23:24:18.420] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:123:0)
[23:24:18.420] Timer tick, asking Buildbucket for the build status
[23:24:18.532] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:18.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:124:0) after 1m0s
[23:25:18.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:124:0)
[23:25:18.938] Timer tick, asking Buildbucket for the build status
[23:25:19.115] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:19.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:125:0) after 1m0s
[23:26:19.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:125:0)
[23:26:19.195] Timer tick, asking Buildbucket for the build status
[23:26:20.834] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:20.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:126:0) after 1m0s
[23:27:21.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:126:0)
[23:27:21.151] Timer tick, asking Buildbucket for the build status
[23:27:21.414] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:21.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:127:0) after 1m0s
[23:28:21.454] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:127:0)
[23:28:21.454] Timer tick, asking Buildbucket for the build status
[23:28:21.622] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:21.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:128:0) after 1m0s
[23:29:21.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:128:0)
[23:29:21.761] Timer tick, asking Buildbucket for the build status
[23:29:21.903] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:21.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:129:0) after 1m0s
[23:30:22.124] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:129:0)
[23:30:22.124] Timer tick, asking Buildbucket for the build status
[23:30:22.214] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:22.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:130:0) after 1m0s
[23:31:22.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:130:0)
[23:31:22.233] Timer tick, asking Buildbucket for the build status
[23:31:22.313] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:22.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:131:0) after 1m0s
[23:32:22.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:131:0)
[23:32:22.366] Timer tick, asking Buildbucket for the build status
[23:32:22.485] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:22.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:132:0) after 1m0s
[23:33:22.518] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:132:0)
[23:33:22.518] Timer tick, asking Buildbucket for the build status
[23:33:22.666] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:22.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:133:0) after 1m0s
[23:34:22.909] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:133:0)
[23:34:22.909] Timer tick, asking Buildbucket for the build status
[23:34:23.002] Build 8869872577778397152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:23.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035302640046535776:134:0) after 1m0s
[23:35:06.240] Received PubSub notification, asking Buildbucket for the build status
[23:35:06.363] Build 8869872577778397152: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:35:06.363] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1599435305451089",
"created_by": "project:chromium",
"created_ts": "1599427354984906",
"id": "8869872577778397152",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1ccf4c97ccfc6fcd1ea631acae9ca217da044b61\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"1ccf4c97ccfc6fcd1ea631acae9ca217da044b61\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-8-0oxp\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 524, \"got_angle_revision\": \"72f6b99c29f28bd7461f383e9b7223a8f670b7cc\", \"got_dawn_revision\": \"62442aceb0fb8af0c1edd07b5f18b4ec181b8fbf\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"1ccf4c97ccfc6fcd1ea631acae9ca217da044b61\", \"got_revision_cp\": \"refs/heads/master@{#804635}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"e63aa953ddfd935a022090b3ef221438a328d1a1\", \"got_v8_revision\": \"ef9e56c6d3efce74216a967f3774a5c8bc8c142a\", \"got_v8_revision_cp\": \"refs/heads/8.7.74@{#1}\", \"got_webrtc_revision\": \"d46db9f1523ae45909b4a6fdc90a140443068bc6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32036}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1ccf4c97ccfc6fcd1ea631acae9ca217da044b61\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-8-0oxp\"], \"image\": [\"chrome-win10-1703-20083100-22dcea0839e\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5507-6e8d655\"], \"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": "1599427429723265",
"status": "COMPLETED",
"status_changed_ts": "1599435305747681",
"tags": [
"build_address:luci.chromium.ci/win-official/524",
"builder:win-official",
"buildset:commit/git/1ccf4c97ccfc6fcd1ea631acae9ca217da044b61",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ccf4c97ccfc6fcd1ea631acae9ca217da044b61",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035302640046535776",
"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/8869872577778397152/+/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:4e7e1e8aaa067510",
"user_agent:luci-scheduler"
],
"updated_ts": "1599435305751847",
"url": "https://ci.chromium.org/b/8869872577778397152",
"utcnow_ts": "1599435306326803"
}
}
[23:35:06.363] Invocation finished in 2h12m32.924987599s with status SUCCEEDED