[13:14:33.566] New invocation is queued and will start shortly
[13:14:34.796] Starting the invocation (attempt 1)
[13:14:34.834] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:14:34.834] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:14:34.834] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9032434239309787488",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f0a4e6849a6b98bcf307c9ddeae042a3a33f2b12\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9032434239309787488",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/f0a4e6849a6b98bcf307c9ddeae042a3a33f2b12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f0a4e6849a6b98bcf307c9ddeae042a3a33f2b12",
"gitiles_ref:refs/heads/master"
]
}
[13:14:35.522] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1602162874961041",
"id": "8867004177183435296",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f0a4e6849a6b98bcf307c9ddeae042a3a33f2b12\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1602162875377573",
"tags": [
"build_address:luci.chromium.ci/win-official/769",
"builder:win-official",
"buildset:commit/git/f0a4e6849a6b98bcf307c9ddeae042a3a33f2b12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f0a4e6849a6b98bcf307c9ddeae042a3a33f2b12",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032434239309787488",
"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/8867004177183435296/+/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": "1602162875377686",
"url": "https://ci.chromium.org/b/8867004177183435296",
"utcnow_ts": "1602162875513720"
}
}
[13:14:35.522] Task URL: https://ci.chromium.org/b/8867004177183435296
[13:14:35.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:2:0) after 1m0s
[13:15:11.570] Received PubSub notification, asking Buildbucket for the build status
[13:15:12.189] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:35.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:2:0)
[13:15:35.619] Timer tick, asking Buildbucket for the build status
[13:15:35.693] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:35.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:4:0) after 1m0s
[13:16:35.777] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:4:0)
[13:16:35.777] Timer tick, asking Buildbucket for the build status
[13:16:35.898] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:35.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:5:0) after 1m0s
[13:17:35.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:5:0)
[13:17:35.957] Timer tick, asking Buildbucket for the build status
[13:17:36.049] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:36.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:6:0) after 1m0s
[13:18:36.088] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:6:0)
[13:18:36.088] Timer tick, asking Buildbucket for the build status
[13:18:36.232] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:36.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:7:0) after 1m0s
[13:19:36.205] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:7:0)
[13:19:36.205] Timer tick, asking Buildbucket for the build status
[13:19:36.309] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:36.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:8:0) after 1m0s
[13:20:36.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:8:0)
[13:20:36.342] Timer tick, asking Buildbucket for the build status
[13:20:36.426] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:36.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:9:0) after 1m0s
[13:21:36.445] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:9:0)
[13:21:36.445] Timer tick, asking Buildbucket for the build status
[13:21:36.590] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:36.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:10:0) after 1m0s
[13:22:36.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:10:0)
[13:22:36.621] Timer tick, asking Buildbucket for the build status
[13:22:36.695] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:36.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:11:0) after 1m0s
[13:23:36.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:11:0)
[13:23:36.729] Timer tick, asking Buildbucket for the build status
[13:23:36.795] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:36.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:12:0) after 1m0s
[13:24:36.850] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:12:0)
[13:24:36.850] Timer tick, asking Buildbucket for the build status
[13:24:36.957] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:36.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:13:0) after 1m0s
[13:25:37.191] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:13:0)
[13:25:37.191] Timer tick, asking Buildbucket for the build status
[13:25:37.504] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:37.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:14:0) after 1m0s
[13:26:37.630] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:14:0)
[13:26:37.630] Timer tick, asking Buildbucket for the build status
[13:26:37.705] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:37.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:15:0) after 1m0s
[13:27:37.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:15:0)
[13:27:37.868] Timer tick, asking Buildbucket for the build status
[13:27:37.926] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:37.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:16:0) after 1m0s
[13:28:39.620] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:16:0)
[13:28:39.620] Timer tick, asking Buildbucket for the build status
[13:28:39.677] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:39.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:17:0) after 1m0s
[13:29:39.681] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:17:0)
[13:29:39.681] Timer tick, asking Buildbucket for the build status
[13:29:39.770] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:39.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:18:0) after 1m0s
[13:30:39.816] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:18:0)
[13:30:39.816] Timer tick, asking Buildbucket for the build status
[13:30:39.864] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:39.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:19:0) after 1m0s
[13:31:39.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:19:0)
[13:31:39.921] Timer tick, asking Buildbucket for the build status
[13:31:40.306] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:40.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:20:0) after 1m0s
[13:32:40.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:20:0)
[13:32:40.429] Timer tick, asking Buildbucket for the build status
[13:32:40.496] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:40.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:21:0) after 1m0s
[13:33:40.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:21:0)
[13:33:40.665] Timer tick, asking Buildbucket for the build status
[13:33:40.766] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:40.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:22:0) after 1m0s
[13:34:40.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:22:0)
[13:34:40.781] Timer tick, asking Buildbucket for the build status
[13:34:41.027] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:41.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:23:0) after 1m0s
[13:35:41.049] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:23:0)
[13:35:41.049] Timer tick, asking Buildbucket for the build status
[13:35:41.173] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:41.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:24:0) after 1m0s
[13:36:41.297] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:24:0)
[13:36:41.297] Timer tick, asking Buildbucket for the build status
[13:36:41.504] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:41.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:25:0) after 1m0s
[13:37:41.513] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:25:0)
[13:37:41.513] Timer tick, asking Buildbucket for the build status
[13:37:41.576] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:41.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:26:0) after 1m0s
[13:38:41.641] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:26:0)
[13:38:41.641] Timer tick, asking Buildbucket for the build status
[13:38:41.708] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:41.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:27:0) after 1m0s
[13:39:41.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:27:0)
[13:39:41.726] Timer tick, asking Buildbucket for the build status
[13:39:41.828] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:41.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:28:0) after 1m0s
[13:40:41.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:28:0)
[13:40:41.842] Timer tick, asking Buildbucket for the build status
[13:40:41.909] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:41.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:29:0) after 1m0s
[13:41:41.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:29:0)
[13:41:41.905] Timer tick, asking Buildbucket for the build status
[13:41:41.961] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:41.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:30:0) after 1m0s
[13:42:41.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:30:0)
[13:42:41.987] Timer tick, asking Buildbucket for the build status
[13:42:42.290] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:42.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:31:0) after 1m0s
[13:43:42.346] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:31:0)
[13:43:42.346] Timer tick, asking Buildbucket for the build status
[13:43:42.567] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:42.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:32:0) after 1m0s
[13:44:42.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:32:0)
[13:44:42.678] Timer tick, asking Buildbucket for the build status
[13:44:42.805] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:42.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:33:0) after 1m0s
[13:45:42.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:33:0)
[13:45:42.854] Timer tick, asking Buildbucket for the build status
[13:45:43.035] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:43.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:34:0) after 1m0s
[13:46:43.094] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:34:0)
[13:46:43.094] Timer tick, asking Buildbucket for the build status
[13:46:43.178] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:43.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:35:0) after 1m0s
[13:47:43.272] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:35:0)
[13:47:43.272] Timer tick, asking Buildbucket for the build status
[13:47:43.445] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:43.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:36:0) after 1m0s
[13:48:43.549] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:36:0)
[13:48:43.549] Timer tick, asking Buildbucket for the build status
[13:48:43.752] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:43.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:37:0) after 1m0s
[13:49:43.977] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:37:0)
[13:49:43.977] Timer tick, asking Buildbucket for the build status
[13:49:44.331] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:44.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:38:0) after 1m0s
[13:50:44.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:38:0)
[13:50:44.347] Timer tick, asking Buildbucket for the build status
[13:50:45.336] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:45.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:39:0) after 1m0s
[13:51:45.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:39:0)
[13:51:45.407] Timer tick, asking Buildbucket for the build status
[13:51:45.474] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:45.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:40:0) after 1m0s
[13:52:45.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:40:0)
[13:52:45.574] Timer tick, asking Buildbucket for the build status
[13:52:45.653] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:45.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:41:0) after 1m0s
[13:53:45.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:41:0)
[13:53:45.726] Timer tick, asking Buildbucket for the build status
[13:53:45.786] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:45.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:42:0) after 1m0s
[13:54:46.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:42:0)
[13:54:46.388] Timer tick, asking Buildbucket for the build status
[13:54:46.508] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:46.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:43:0) after 1m0s
[13:55:46.623] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:43:0)
[13:55:46.623] Timer tick, asking Buildbucket for the build status
[13:55:46.686] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:46.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:44:0) after 1m0s
[13:56:46.850] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:44:0)
[13:56:46.850] Timer tick, asking Buildbucket for the build status
[13:56:46.933] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:46.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:45:0) after 1m0s
[13:57:46.960] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:45:0)
[13:57:46.960] Timer tick, asking Buildbucket for the build status
[13:57:47.017] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:47.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:46:0) after 1m0s
[13:58:47.133] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:46:0)
[13:58:47.133] Timer tick, asking Buildbucket for the build status
[13:58:47.192] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:47.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:47:0) after 1m0s
[13:59:47.277] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:47:0)
[13:59:47.277] Timer tick, asking Buildbucket for the build status
[13:59:47.369] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:47.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:48:0) after 1m0s
[14:00:47.455] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:48:0)
[14:00:47.455] Timer tick, asking Buildbucket for the build status
[14:00:47.522] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:47.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:49:0) after 1m0s
[14:01:47.564] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:49:0)
[14:01:47.564] Timer tick, asking Buildbucket for the build status
[14:01:47.641] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:47.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:50:0) after 1m0s
[14:02:47.826] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:50:0)
[14:02:47.826] Timer tick, asking Buildbucket for the build status
[14:02:47.929] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:47.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:51:0) after 1m0s
[14:03:47.946] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:51:0)
[14:03:47.946] Timer tick, asking Buildbucket for the build status
[14:03:48.033] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:48.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:52:0) after 1m0s
[14:04:48.115] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:52:0)
[14:04:48.115] Timer tick, asking Buildbucket for the build status
[14:04:48.239] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:48.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:53:0) after 1m0s
[14:05:48.288] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:53:0)
[14:05:48.288] Timer tick, asking Buildbucket for the build status
[14:05:48.349] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:48.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:54:0) after 1m0s
[14:06:48.421] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:54:0)
[14:06:48.421] Timer tick, asking Buildbucket for the build status
[14:06:48.553] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:48.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:55:0) after 1m0s
[14:07:48.641] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:55:0)
[14:07:48.641] Timer tick, asking Buildbucket for the build status
[14:07:48.700] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:48.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:56:0) after 1m0s
[14:08:48.918] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:56:0)
[14:08:48.918] Timer tick, asking Buildbucket for the build status
[14:08:48.999] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:48.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:57:0) after 1m0s
[14:09:49.088] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:57:0)
[14:09:49.088] Timer tick, asking Buildbucket for the build status
[14:09:49.716] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:49.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:58:0) after 1m0s
[14:10:49.866] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:58:0)
[14:10:49.866] Timer tick, asking Buildbucket for the build status
[14:10:50.004] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:50.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:59:0) after 1m0s
[14:11:50.032] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:59:0)
[14:11:50.032] Timer tick, asking Buildbucket for the build status
[14:11:50.092] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:50.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:60:0) after 1m0s
[14:12:50.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:60:0)
[14:12:50.123] Timer tick, asking Buildbucket for the build status
[14:12:50.197] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:50.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:61:0) after 1m0s
[14:13:50.312] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:61:0)
[14:13:50.312] Timer tick, asking Buildbucket for the build status
[14:13:50.600] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:50.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:62:0) after 1m0s
[14:14:50.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:62:0)
[14:14:50.665] Timer tick, asking Buildbucket for the build status
[14:14:51.006] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:51.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:63:0) after 1m0s
[14:15:51.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:63:0)
[14:15:51.076] Timer tick, asking Buildbucket for the build status
[14:15:51.136] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:51.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:64:0) after 1m0s
[14:16:51.190] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:64:0)
[14:16:51.190] Timer tick, asking Buildbucket for the build status
[14:16:51.245] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:51.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:65:0) after 1m0s
[14:17:51.321] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:65:0)
[14:17:51.321] Timer tick, asking Buildbucket for the build status
[14:17:51.404] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:51.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:66:0) after 1m0s
[14:18:51.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:66:0)
[14:18:51.430] Timer tick, asking Buildbucket for the build status
[14:18:51.490] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:51.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:67:0) after 1m0s
[14:19:51.502] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:67:0)
[14:19:51.502] Timer tick, asking Buildbucket for the build status
[14:19:51.587] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:51.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:68:0) after 1m0s
[14:20:51.648] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:68:0)
[14:20:51.648] Timer tick, asking Buildbucket for the build status
[14:20:51.855] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:51.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:69:0) after 1m0s
[14:21:51.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:69:0)
[14:21:51.924] Timer tick, asking Buildbucket for the build status
[14:21:51.991] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:51.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:70:0) after 1m0s
[14:22:52.023] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:70:0)
[14:22:52.023] Timer tick, asking Buildbucket for the build status
[14:22:52.078] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:52.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:71:0) after 1m0s
[14:23:52.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:71:0)
[14:23:52.225] Timer tick, asking Buildbucket for the build status
[14:23:52.296] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:52.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:72:0) after 1m0s
[14:24:52.311] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:72:0)
[14:24:52.311] Timer tick, asking Buildbucket for the build status
[14:24:52.415] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:52.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:73:0) after 1m0s
[14:25:52.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:73:0)
[14:25:52.475] Timer tick, asking Buildbucket for the build status
[14:25:52.531] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:52.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:74:0) after 1m0s
[14:26:52.588] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:74:0)
[14:26:52.588] Timer tick, asking Buildbucket for the build status
[14:26:52.662] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:52.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:75:0) after 1m0s
[14:27:52.714] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:75:0)
[14:27:52.714] Timer tick, asking Buildbucket for the build status
[14:27:52.771] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:52.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:76:0) after 1m0s
[14:28:52.872] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:76:0)
[14:28:52.872] Timer tick, asking Buildbucket for the build status
[14:28:52.932] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:52.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:77:0) after 1m0s
[14:29:53.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:77:0)
[14:29:53.043] Timer tick, asking Buildbucket for the build status
[14:29:53.203] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:53.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:78:0) after 1m0s
[14:30:53.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:78:0)
[14:30:53.361] Timer tick, asking Buildbucket for the build status
[14:30:53.421] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:53.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:79:0) after 1m0s
[14:31:53.587] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:79:0)
[14:31:53.587] Timer tick, asking Buildbucket for the build status
[14:31:53.665] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:53.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:80:0) after 1m0s
[14:32:53.737] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:80:0)
[14:32:53.737] Timer tick, asking Buildbucket for the build status
[14:32:53.814] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:53.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:81:0) after 1m0s
[14:33:53.903] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:81:0)
[14:33:53.904] Timer tick, asking Buildbucket for the build status
[14:33:53.967] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:53.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:82:0) after 1m0s
[14:34:54.018] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:82:0)
[14:34:54.018] Timer tick, asking Buildbucket for the build status
[14:34:54.125] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:54.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:83:0) after 1m0s
[14:35:54.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:83:0)
[14:35:54.204] Timer tick, asking Buildbucket for the build status
[14:35:54.276] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:54.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:84:0) after 1m0s
[14:36:54.338] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:84:0)
[14:36:54.338] Timer tick, asking Buildbucket for the build status
[14:36:54.395] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:54.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:85:0) after 1m0s
[14:37:54.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:85:0)
[14:37:54.439] Timer tick, asking Buildbucket for the build status
[14:37:54.492] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:54.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:86:0) after 1m0s
[14:38:54.504] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:86:0)
[14:38:54.504] Timer tick, asking Buildbucket for the build status
[14:38:54.571] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:54.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:87:0) after 1m0s
[14:39:54.612] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:87:0)
[14:39:54.612] Timer tick, asking Buildbucket for the build status
[14:39:54.721] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:54.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:88:0) after 1m0s
[14:40:54.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:88:0)
[14:40:54.758] Timer tick, asking Buildbucket for the build status
[14:40:55.086] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:55.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:89:0) after 1m0s
[14:41:55.137] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:89:0)
[14:41:55.137] Timer tick, asking Buildbucket for the build status
[14:41:55.288] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:55.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:90:0) after 1m0s
[14:42:55.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:90:0)
[14:42:55.366] Timer tick, asking Buildbucket for the build status
[14:42:55.570] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:55.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:91:0) after 1m0s
[14:43:55.559] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:91:0)
[14:43:55.559] Timer tick, asking Buildbucket for the build status
[14:43:55.623] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:55.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:92:0) after 1m0s
[14:44:55.648] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:92:0)
[14:44:55.648] Timer tick, asking Buildbucket for the build status
[14:44:55.749] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:55.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:93:0) after 1m0s
[14:45:55.816] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:93:0)
[14:45:55.816] Timer tick, asking Buildbucket for the build status
[14:45:55.885] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:55.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:94:0) after 1m0s
[14:46:55.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:94:0)
[14:46:55.957] Timer tick, asking Buildbucket for the build status
[14:46:56.193] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:56.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:95:0) after 1m0s
[14:47:56.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:95:0)
[14:47:56.263] Timer tick, asking Buildbucket for the build status
[14:47:56.409] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:56.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:96:0) after 1m0s
[14:48:56.536] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:96:0)
[14:48:56.536] Timer tick, asking Buildbucket for the build status
[14:48:56.787] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:56.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:97:0) after 1m0s
[14:49:56.813] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:97:0)
[14:49:56.813] Timer tick, asking Buildbucket for the build status
[14:49:57.124] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:57.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:98:0) after 1m0s
[14:50:57.172] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:98:0)
[14:50:57.172] Timer tick, asking Buildbucket for the build status
[14:50:57.240] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:57.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:99:0) after 1m0s
[14:51:57.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:99:0)
[14:51:57.293] Timer tick, asking Buildbucket for the build status
[14:51:57.747] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:57.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:100:0) after 1m0s
[14:52:57.799] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:100:0)
[14:52:57.799] Timer tick, asking Buildbucket for the build status
[14:52:57.858] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:57.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:101:0) after 1m0s
[14:53:57.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:101:0)
[14:53:57.891] Timer tick, asking Buildbucket for the build status
[14:53:57.946] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:57.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:102:0) after 1m0s
[14:54:58.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:102:0)
[14:54:58.029] Timer tick, asking Buildbucket for the build status
[14:54:58.156] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:58.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:103:0) after 1m0s
[14:55:58.131] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:103:0)
[14:55:58.131] Timer tick, asking Buildbucket for the build status
[14:55:58.190] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:58.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:104:0) after 1m0s
[14:56:58.229] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:104:0)
[14:56:58.229] Timer tick, asking Buildbucket for the build status
[14:56:58.282] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:58.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:105:0) after 1m0s
[14:57:58.343] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:105:0)
[14:57:58.343] Timer tick, asking Buildbucket for the build status
[14:57:58.450] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:58.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:106:0) after 1m0s
[14:58:58.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:106:0)
[14:58:58.927] Timer tick, asking Buildbucket for the build status
[14:58:58.983] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:58.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:107:0) after 1m0s
[14:59:59.180] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:107:0)
[14:59:59.180] Timer tick, asking Buildbucket for the build status
[14:59:59.286] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:59.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:108:0) after 1m0s
[15:00:59.476] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:108:0)
[15:00:59.476] Timer tick, asking Buildbucket for the build status
[15:00:59.607] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:59.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:109:0) after 1m0s
[15:01:59.770] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:109:0)
[15:01:59.770] Timer tick, asking Buildbucket for the build status
[15:02:00.435] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:00.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:110:0) after 1m0s
[15:03:01.455] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:110:0)
[15:03:01.455] Timer tick, asking Buildbucket for the build status
[15:03:01.578] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:01.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:111:0) after 1m0s
[15:04:01.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:111:0)
[15:04:01.597] Timer tick, asking Buildbucket for the build status
[15:04:01.795] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:01.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:112:0) after 1m0s
[15:05:02.582] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:112:0)
[15:05:02.582] Timer tick, asking Buildbucket for the build status
[15:05:02.816] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:02.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:113:0) after 1m0s
[15:06:02.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:113:0)
[15:06:02.849] Timer tick, asking Buildbucket for the build status
[15:06:03.613] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:03.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:114:0) after 1m0s
[15:07:03.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:114:0)
[15:07:03.678] Timer tick, asking Buildbucket for the build status
[15:07:03.944] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:03.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:115:0) after 1m0s
[15:08:04.045] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:115:0)
[15:08:04.045] Timer tick, asking Buildbucket for the build status
[15:08:04.208] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:04.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:116:0) after 1m0s
[15:09:04.275] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:116:0)
[15:09:04.275] Timer tick, asking Buildbucket for the build status
[15:09:04.350] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:04.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:117:0) after 1m0s
[15:10:04.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:117:0)
[15:10:04.430] Timer tick, asking Buildbucket for the build status
[15:10:04.788] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:04.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:118:0) after 1m0s
[15:11:04.831] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:118:0)
[15:11:04.831] Timer tick, asking Buildbucket for the build status
[15:11:04.917] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:04.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:119:0) after 1m0s
[15:12:04.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:119:0)
[15:12:04.985] Timer tick, asking Buildbucket for the build status
[15:12:05.065] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:05.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:120:0) after 1m0s
[15:13:05.177] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:120:0)
[15:13:05.177] Timer tick, asking Buildbucket for the build status
[15:13:05.247] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:05.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:121:0) after 1m0s
[15:14:05.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:121:0)
[15:14:05.383] Timer tick, asking Buildbucket for the build status
[15:14:05.451] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:05.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:122:0) after 1m0s
[15:15:05.522] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:122:0)
[15:15:05.522] Timer tick, asking Buildbucket for the build status
[15:15:05.700] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:05.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:123:0) after 1m0s
[15:16:05.697] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:123:0)
[15:16:05.697] Timer tick, asking Buildbucket for the build status
[15:16:05.775] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:05.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:124:0) after 1m0s
[15:17:05.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:124:0)
[15:17:05.976] Timer tick, asking Buildbucket for the build status
[15:17:06.051] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:06.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:125:0) after 1m0s
[15:18:06.196] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:125:0)
[15:18:06.196] Timer tick, asking Buildbucket for the build status
[15:18:06.431] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:06.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:126:0) after 1m0s
[15:19:06.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:126:0)
[15:19:06.603] Timer tick, asking Buildbucket for the build status
[15:19:06.672] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:06.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:127:0) after 1m0s
[15:20:06.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:127:0)
[15:20:06.741] Timer tick, asking Buildbucket for the build status
[15:20:07.746] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:07.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:128:0) after 1m0s
[15:21:08.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:128:0)
[15:21:08.451] Timer tick, asking Buildbucket for the build status
[15:21:08.688] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:08.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:129:0) after 1m0s
[15:22:08.745] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:129:0)
[15:22:08.745] Timer tick, asking Buildbucket for the build status
[15:22:08.810] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:08.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:130:0) after 1m0s
[15:23:08.929] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:130:0)
[15:23:08.929] Timer tick, asking Buildbucket for the build status
[15:23:09.025] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:09.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:131:0) after 1m0s
[15:24:09.018] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:131:0)
[15:24:09.018] Timer tick, asking Buildbucket for the build status
[15:24:09.081] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:09.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:132:0) after 1m0s
[15:25:09.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:132:0)
[15:25:09.112] Timer tick, asking Buildbucket for the build status
[15:25:09.255] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:09.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:133:0) after 1m0s
[15:26:09.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:133:0)
[15:26:09.367] Timer tick, asking Buildbucket for the build status
[15:26:09.813] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:09.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:134:0) after 1m0s
[15:27:09.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:134:0)
[15:27:09.854] Timer tick, asking Buildbucket for the build status
[15:27:09.919] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:09.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:135:0) after 1m0s
[15:28:10.064] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:135:0)
[15:28:10.064] Timer tick, asking Buildbucket for the build status
[15:28:10.178] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:10.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:136:0) after 1m0s
[15:29:10.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:136:0)
[15:29:10.238] Timer tick, asking Buildbucket for the build status
[15:29:10.369] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:10.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:137:0) after 1m0s
[15:30:10.443] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:137:0)
[15:30:10.443] Timer tick, asking Buildbucket for the build status
[15:30:11.846] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:11.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:138:0) after 1m0s
[15:31:12.100] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:138:0)
[15:31:12.100] Timer tick, asking Buildbucket for the build status
[15:31:12.156] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:12.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:139:0) after 1m0s
[15:32:12.419] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:139:0)
[15:32:12.419] Timer tick, asking Buildbucket for the build status
[15:32:12.492] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:12.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:140:0) after 1m0s
[15:33:12.643] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:140:0)
[15:33:12.643] Timer tick, asking Buildbucket for the build status
[15:33:12.701] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:12.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:141:0) after 1m0s
[15:34:12.751] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:141:0)
[15:34:12.751] Timer tick, asking Buildbucket for the build status
[15:34:12.818] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:12.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:142:0) after 1m0s
[15:35:12.903] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:142:0)
[15:35:12.903] Timer tick, asking Buildbucket for the build status
[15:35:13.003] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:13.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:143:0) after 1m0s
[15:36:13.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:143:0)
[15:36:13.066] Timer tick, asking Buildbucket for the build status
[15:36:13.145] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:13.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:144:0) after 1m0s
[15:37:13.200] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:144:0)
[15:37:13.200] Timer tick, asking Buildbucket for the build status
[15:37:13.491] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:13.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:145:0) after 1m0s
[15:38:13.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:145:0)
[15:38:13.524] Timer tick, asking Buildbucket for the build status
[15:38:13.684] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:13.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:146:0) after 1m0s
[15:39:13.786] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:146:0)
[15:39:13.786] Timer tick, asking Buildbucket for the build status
[15:39:13.870] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:13.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:147:0) after 1m0s
[15:40:13.970] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:147:0)
[15:40:13.970] Timer tick, asking Buildbucket for the build status
[15:40:14.072] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:14.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:148:0) after 1m0s
[15:41:14.101] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:148:0)
[15:41:14.101] Timer tick, asking Buildbucket for the build status
[15:41:14.162] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:14.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:149:0) after 1m0s
[15:42:14.215] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:149:0)
[15:42:14.215] Timer tick, asking Buildbucket for the build status
[15:42:14.552] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:14.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:150:0) after 1m0s
[15:43:14.642] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:150:0)
[15:43:14.642] Timer tick, asking Buildbucket for the build status
[15:43:15.174] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:15.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:151:0) after 1m0s
[15:44:15.320] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:151:0)
[15:44:15.320] Timer tick, asking Buildbucket for the build status
[15:44:15.398] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:15.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:152:0) after 1m0s
[15:45:15.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:152:0)
[15:45:15.555] Timer tick, asking Buildbucket for the build status
[15:45:16.268] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:16.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:153:0) after 1m0s
[15:46:16.365] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:153:0)
[15:46:16.365] Timer tick, asking Buildbucket for the build status
[15:46:16.724] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:16.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:154:0) after 1m0s
[15:47:16.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:154:0)
[15:47:16.818] Timer tick, asking Buildbucket for the build status
[15:47:16.938] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:16.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:155:0) after 1m0s
[15:48:16.965] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:155:0)
[15:48:16.965] Timer tick, asking Buildbucket for the build status
[15:48:17.790] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:17.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:156:0) after 1m0s
[15:49:17.794] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:156:0)
[15:49:17.794] Timer tick, asking Buildbucket for the build status
[15:49:18.216] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:18.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:157:0) after 1m0s
[15:50:18.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:157:0)
[15:50:18.268] Timer tick, asking Buildbucket for the build status
[15:50:18.409] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:18.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:158:0) after 1m0s
[15:51:18.480] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:158:0)
[15:51:18.480] Timer tick, asking Buildbucket for the build status
[15:51:18.679] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:18.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:159:0) after 1m0s
[15:52:18.683] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:159:0)
[15:52:18.683] Timer tick, asking Buildbucket for the build status
[15:52:18.760] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:18.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:160:0) after 1m0s
[15:53:18.862] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:160:0)
[15:53:18.862] Timer tick, asking Buildbucket for the build status
[15:53:18.922] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:18.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:161:0) after 1m0s
[15:54:18.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:161:0)
[15:54:18.957] Timer tick, asking Buildbucket for the build status
[15:54:19.037] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:19.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:162:0) after 1m0s
[15:55:19.062] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:162:0)
[15:55:19.062] Timer tick, asking Buildbucket for the build status
[15:55:19.213] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:19.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:163:0) after 1m0s
[15:56:19.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:163:0)
[15:56:19.247] Timer tick, asking Buildbucket for the build status
[15:56:19.371] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:19.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:164:0) after 1m0s
[15:57:19.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:164:0)
[15:57:19.575] Timer tick, asking Buildbucket for the build status
[15:57:19.702] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:19.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:165:0) after 1m0s
[15:58:19.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:165:0)
[15:58:19.803] Timer tick, asking Buildbucket for the build status
[15:58:19.917] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:19.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:166:0) after 1m0s
[15:59:20.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:166:0)
[15:59:20.042] Timer tick, asking Buildbucket for the build status
[15:59:20.110] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:20.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:167:0) after 1m0s
[16:00:20.205] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:167:0)
[16:00:20.205] Timer tick, asking Buildbucket for the build status
[16:00:20.334] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:20.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:168:0) after 1m0s
[16:01:20.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:168:0)
[16:01:20.401] Timer tick, asking Buildbucket for the build status
[16:01:20.473] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:20.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:169:0) after 1m0s
[16:02:20.658] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:169:0)
[16:02:20.658] Timer tick, asking Buildbucket for the build status
[16:02:20.771] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:20.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:170:0) after 1m0s
[16:03:20.883] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:170:0)
[16:03:20.883] Timer tick, asking Buildbucket for the build status
[16:03:20.952] Build 8867004177183435296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:20.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032434239309787488:171:0) after 1m0s
[16:03:56.306] Received PubSub notification, asking Buildbucket for the build status
[16:03:56.509] Build 8867004177183435296: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:03:56.509] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1602173035562321",
"created_by": "project:chromium",
"created_ts": "1602162874961041",
"id": "8867004177183435296",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f0a4e6849a6b98bcf307c9ddeae042a3a33f2b12\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"f0a4e6849a6b98bcf307c9ddeae042a3a33f2b12\", \"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-0ign\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 769, \"got_angle_revision\": \"6a09a9d46097815b3df3d297f216d3d73fa479b0\", \"got_dawn_revision\": \"6b4a4a85dfd778964c48f5cec939b7da160a5db1\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"f0a4e6849a6b98bcf307c9ddeae042a3a33f2b12\", \"got_revision_cp\": \"refs/heads/master@{#815112}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"952149303d06de35920f1a3913217f572a3f611c\", \"got_v8_revision\": \"851a5e7f92465d1c8b158aad1499be7d9688f46e\", \"got_v8_revision_cp\": \"refs/heads/8.8.48@{#1}\", \"got_webrtc_revision\": \"eacbd972ab7e85e9d61851ced57aba1eb194de3d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32316}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f0a4e6849a6b98bcf307c9ddeae042a3a33f2b12\"}, \"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-0ign\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5570-fa4adf0\"], \"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": "1602162910631154",
"status": "COMPLETED",
"status_changed_ts": "1602173035956333",
"tags": [
"build_address:luci.chromium.ci/win-official/769",
"builder:win-official",
"buildset:commit/git/f0a4e6849a6b98bcf307c9ddeae042a3a33f2b12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f0a4e6849a6b98bcf307c9ddeae042a3a33f2b12",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032434239309787488",
"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/8867004177183435296/+/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:4f212ae53e0d3110",
"user_agent:luci-scheduler"
],
"updated_ts": "1602173035965455",
"url": "https://ci.chromium.org/b/8867004177183435296",
"utcnow_ts": "1602173036485886"
}
}
[16:03:56.509] Invocation finished in 2h49m22.960575192s with status SUCCEEDED