[09:57:44.848] New invocation is queued and will start shortly
[09:57:45.948] Starting the invocation (attempt 1)
[09:57:45.988] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:57:45.988] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:57:45.988] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023205731073349648",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"89565419bfff402af08584b86663c0bc5c01e73b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9023205731073349648",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/89565419bfff402af08584b86663c0bc5c01e73b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/89565419bfff402af08584b86663c0bc5c01e73b",
"gitiles_ref:refs/heads/master"
]
}
[09:57:47.583] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1610963866073420",
"id": "8857775669127135568",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"89565419bfff402af08584b86663c0bc5c01e73b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610963866463603",
"tags": [
"build_address:luci.chromium.ci/win-official/1406",
"builder:win-official",
"buildset:commit/git/89565419bfff402af08584b86663c0bc5c01e73b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/89565419bfff402af08584b86663c0bc5c01e73b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023205731073349648",
"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/8857775669127135568/+/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": "1610963866463871",
"url": "https://ci.chromium.org/b/8857775669127135568",
"utcnow_ts": "1610963867575331"
}
}
[09:57:47.583] Task URL: https://ci.chromium.org/b/8857775669127135568
[09:57:47.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:2:0) after 1m0s
[09:58:23.638] Received PubSub notification, asking Buildbucket for the build status
[09:58:23.732] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:47.680] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:2:0)
[09:58:47.680] Timer tick, asking Buildbucket for the build status
[09:58:47.795] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:47.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:4:0) after 1m0s
[09:59:47.869] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:4:0)
[09:59:47.869] Timer tick, asking Buildbucket for the build status
[09:59:48.173] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:48.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:5:0) after 1m0s
[10:00:48.331] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:5:0)
[10:00:48.331] Timer tick, asking Buildbucket for the build status
[10:00:48.407] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:48.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:6:0) after 1m0s
[10:01:48.565] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:6:0)
[10:01:48.565] Timer tick, asking Buildbucket for the build status
[10:01:48.666] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:48.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:7:0) after 1m0s
[10:02:48.786] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:7:0)
[10:02:48.786] Timer tick, asking Buildbucket for the build status
[10:02:48.901] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:48.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:8:0) after 1m0s
[10:03:49.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:8:0)
[10:03:49.034] Timer tick, asking Buildbucket for the build status
[10:03:49.331] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:49.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:9:0) after 1m0s
[10:04:49.474] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:9:0)
[10:04:49.474] Timer tick, asking Buildbucket for the build status
[10:04:49.577] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:49.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:10:0) after 1m0s
[10:05:49.627] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:10:0)
[10:05:49.627] Timer tick, asking Buildbucket for the build status
[10:05:49.867] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:49.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:11:0) after 1m0s
[10:06:49.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:11:0)
[10:06:49.987] Timer tick, asking Buildbucket for the build status
[10:06:50.051] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:50.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:12:0) after 1m0s
[10:07:50.137] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:12:0)
[10:07:50.137] Timer tick, asking Buildbucket for the build status
[10:07:50.508] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:50.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:13:0) after 1m0s
[10:08:50.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:13:0)
[10:08:50.707] Timer tick, asking Buildbucket for the build status
[10:08:50.777] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:50.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:14:0) after 1m0s
[10:09:50.869] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:14:0)
[10:09:50.870] Timer tick, asking Buildbucket for the build status
[10:09:50.986] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:50.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:15:0) after 1m0s
[10:10:51.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:15:0)
[10:10:51.118] Timer tick, asking Buildbucket for the build status
[10:10:51.368] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:51.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:16:0) after 1m0s
[10:11:51.433] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:16:0)
[10:11:51.433] Timer tick, asking Buildbucket for the build status
[10:11:51.533] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:51.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:17:0) after 1m0s
[10:12:51.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:17:0)
[10:12:51.555] Timer tick, asking Buildbucket for the build status
[10:12:51.816] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:51.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:18:0) after 1m0s
[10:13:51.834] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:18:0)
[10:13:51.834] Timer tick, asking Buildbucket for the build status
[10:13:51.917] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:51.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:19:0) after 1m0s
[10:14:52.007] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:19:0)
[10:14:52.007] Timer tick, asking Buildbucket for the build status
[10:14:52.065] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:52.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:20:0) after 1m0s
[10:15:52.132] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:20:0)
[10:15:52.132] Timer tick, asking Buildbucket for the build status
[10:15:52.194] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:52.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:21:0) after 1m0s
[10:16:52.259] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:21:0)
[10:16:52.259] Timer tick, asking Buildbucket for the build status
[10:16:52.655] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:52.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:22:0) after 1m0s
[10:17:52.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:22:0)
[10:17:52.738] Timer tick, asking Buildbucket for the build status
[10:17:52.840] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:52.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:23:0) after 1m0s
[10:18:52.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:23:0)
[10:18:52.842] Timer tick, asking Buildbucket for the build status
[10:18:52.964] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:52.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:24:0) after 1m0s
[10:19:53.002] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:24:0)
[10:19:53.002] Timer tick, asking Buildbucket for the build status
[10:19:53.335] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:53.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:25:0) after 1m0s
[10:20:53.365] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:25:0)
[10:20:53.365] Timer tick, asking Buildbucket for the build status
[10:20:54.330] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:54.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:26:0) after 1m0s
[10:21:54.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:26:0)
[10:21:54.401] Timer tick, asking Buildbucket for the build status
[10:21:54.468] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:54.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:27:0) after 1m0s
[10:22:54.493] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:27:0)
[10:22:54.493] Timer tick, asking Buildbucket for the build status
[10:22:54.678] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:54.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:28:0) after 1m0s
[10:23:54.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:28:0)
[10:23:54.763] Timer tick, asking Buildbucket for the build status
[10:23:55.011] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:55.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:29:0) after 1m0s
[10:24:55.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:29:0)
[10:24:55.066] Timer tick, asking Buildbucket for the build status
[10:24:55.377] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:55.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:30:0) after 1m0s
[10:25:55.421] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:30:0)
[10:25:55.421] Timer tick, asking Buildbucket for the build status
[10:25:55.484] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:55.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:31:0) after 1m0s
[10:26:55.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:31:0)
[10:26:55.573] Timer tick, asking Buildbucket for the build status
[10:26:55.635] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:55.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:32:0) after 1m0s
[10:27:55.668] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:32:0)
[10:27:55.668] Timer tick, asking Buildbucket for the build status
[10:27:55.786] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:55.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:33:0) after 1m0s
[10:28:55.810] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:33:0)
[10:28:55.810] Timer tick, asking Buildbucket for the build status
[10:28:55.880] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:55.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:34:0) after 1m0s
[10:29:55.956] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:34:0)
[10:29:55.956] Timer tick, asking Buildbucket for the build status
[10:29:56.079] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:56.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:35:0) after 1m0s
[10:30:56.214] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:35:0)
[10:30:56.214] Timer tick, asking Buildbucket for the build status
[10:30:56.350] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:56.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:36:0) after 1m0s
[10:31:56.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:36:0)
[10:31:56.402] Timer tick, asking Buildbucket for the build status
[10:31:56.521] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:56.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:37:0) after 1m0s
[10:32:56.667] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:37:0)
[10:32:56.667] Timer tick, asking Buildbucket for the build status
[10:32:57.016] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:57.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:38:0) after 1m0s
[10:33:57.121] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:38:0)
[10:33:57.121] Timer tick, asking Buildbucket for the build status
[10:33:57.184] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:57.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:39:0) after 1m0s
[10:34:57.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:39:0)
[10:34:57.342] Timer tick, asking Buildbucket for the build status
[10:34:57.486] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:57.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:40:0) after 1m0s
[10:35:57.586] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:40:0)
[10:35:57.586] Timer tick, asking Buildbucket for the build status
[10:35:57.819] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:57.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:41:0) after 1m0s
[10:36:58.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:41:0)
[10:36:58.077] Timer tick, asking Buildbucket for the build status
[10:36:58.207] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:58.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:42:0) after 1m0s
[10:37:58.378] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:42:0)
[10:37:58.378] Timer tick, asking Buildbucket for the build status
[10:37:58.609] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:58.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:43:0) after 1m0s
[10:38:58.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:43:0)
[10:38:58.707] Timer tick, asking Buildbucket for the build status
[10:38:58.948] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:58.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:44:0) after 1m0s
[10:39:58.968] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:44:0)
[10:39:58.968] Timer tick, asking Buildbucket for the build status
[10:39:59.190] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:59.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:45:0) after 1m0s
[10:40:59.219] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:45:0)
[10:40:59.219] Timer tick, asking Buildbucket for the build status
[10:40:59.287] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:59.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:46:0) after 1m0s
[10:41:59.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:46:0)
[10:41:59.367] Timer tick, asking Buildbucket for the build status
[10:41:59.439] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:59.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:47:0) after 1m0s
[10:42:59.550] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:47:0)
[10:42:59.550] Timer tick, asking Buildbucket for the build status
[10:42:59.650] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:59.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:48:0) after 1m0s
[10:43:59.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:48:0)
[10:43:59.823] Timer tick, asking Buildbucket for the build status
[10:43:59.945] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:59.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:49:0) after 1m0s
[10:45:00.215] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:49:0)
[10:45:00.215] Timer tick, asking Buildbucket for the build status
[10:45:00.567] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:00.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:50:0) after 1m0s
[10:46:01.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:50:0)
[10:46:01.255] Timer tick, asking Buildbucket for the build status
[10:46:01.315] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:01.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:51:0) after 1m0s
[10:47:01.511] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:51:0)
[10:47:01.511] Timer tick, asking Buildbucket for the build status
[10:47:01.691] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:01.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:52:0) after 1m0s
[10:48:01.963] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:52:0)
[10:48:01.963] Timer tick, asking Buildbucket for the build status
[10:48:02.306] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:02.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:53:0) after 1m0s
[10:49:02.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:53:0)
[10:49:02.362] Timer tick, asking Buildbucket for the build status
[10:49:02.416] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:02.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:54:0) after 1m0s
[10:50:02.695] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:54:0)
[10:50:02.695] Timer tick, asking Buildbucket for the build status
[10:50:02.805] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:02.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:55:0) after 1m0s
[10:51:02.903] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:55:0)
[10:51:02.903] Timer tick, asking Buildbucket for the build status
[10:51:02.982] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:02.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:56:0) after 1m0s
[10:52:03.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:56:0)
[10:52:03.104] Timer tick, asking Buildbucket for the build status
[10:52:03.172] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:03.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:57:0) after 1m0s
[10:53:03.242] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:57:0)
[10:53:03.242] Timer tick, asking Buildbucket for the build status
[10:53:03.379] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:03.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:58:0) after 1m0s
[10:54:03.497] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:58:0)
[10:54:03.497] Timer tick, asking Buildbucket for the build status
[10:54:03.611] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:03.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:59:0) after 1m0s
[10:55:03.644] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:59:0)
[10:55:03.644] Timer tick, asking Buildbucket for the build status
[10:55:03.824] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:03.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:60:0) after 1m0s
[10:56:03.883] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:60:0)
[10:56:03.883] Timer tick, asking Buildbucket for the build status
[10:56:04.041] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:04.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:61:0) after 1m0s
[10:57:04.097] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:61:0)
[10:57:04.097] Timer tick, asking Buildbucket for the build status
[10:57:04.293] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:04.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:62:0) after 1m0s
[10:58:04.317] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:62:0)
[10:58:04.317] Timer tick, asking Buildbucket for the build status
[10:58:04.429] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:04.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:63:0) after 1m0s
[10:59:04.448] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:63:0)
[10:59:04.448] Timer tick, asking Buildbucket for the build status
[10:59:04.514] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:04.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:64:0) after 1m0s
[11:00:04.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:64:0)
[11:00:04.571] Timer tick, asking Buildbucket for the build status
[11:00:04.813] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:04.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:65:0) after 1m0s
[11:01:05.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:65:0)
[11:01:05.388] Timer tick, asking Buildbucket for the build status
[11:01:05.519] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:05.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:66:0) after 1m0s
[11:02:05.642] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:66:0)
[11:02:05.642] Timer tick, asking Buildbucket for the build status
[11:02:06.157] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:06.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:67:0) after 1m0s
[11:03:06.229] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:67:0)
[11:03:06.229] Timer tick, asking Buildbucket for the build status
[11:03:06.375] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:06.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:68:0) after 1m0s
[11:04:06.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:68:0)
[11:04:06.555] Timer tick, asking Buildbucket for the build status
[11:04:06.637] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:06.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:69:0) after 1m0s
[11:05:06.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:69:0)
[11:05:06.678] Timer tick, asking Buildbucket for the build status
[11:05:07.233] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:07.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:70:0) after 1m0s
[11:06:07.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:70:0)
[11:06:07.238] Timer tick, asking Buildbucket for the build status
[11:06:07.316] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:07.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:71:0) after 1m0s
[11:07:07.406] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:71:0)
[11:07:07.406] Timer tick, asking Buildbucket for the build status
[11:07:07.520] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:07.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:72:0) after 1m0s
[11:08:07.561] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:72:0)
[11:08:07.561] Timer tick, asking Buildbucket for the build status
[11:08:07.863] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:07.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:73:0) after 1m0s
[11:09:07.931] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:73:0)
[11:09:07.931] Timer tick, asking Buildbucket for the build status
[11:09:08.002] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:08.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:74:0) after 1m0s
[11:10:08.053] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:74:0)
[11:10:08.053] Timer tick, asking Buildbucket for the build status
[11:10:08.646] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:08.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:75:0) after 1m0s
[11:11:08.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:75:0)
[11:11:08.640] Timer tick, asking Buildbucket for the build status
[11:11:08.719] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:08.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:76:0) after 1m0s
[11:12:08.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:76:0)
[11:12:08.825] Timer tick, asking Buildbucket for the build status
[11:12:08.909] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:08.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:77:0) after 1m0s
[11:13:08.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:77:0)
[11:13:08.930] Timer tick, asking Buildbucket for the build status
[11:13:09.162] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:09.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:78:0) after 1m0s
[11:14:09.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:78:0)
[11:14:09.247] Timer tick, asking Buildbucket for the build status
[11:14:09.317] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:09.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:79:0) after 1m0s
[11:15:09.389] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:79:0)
[11:15:09.389] Timer tick, asking Buildbucket for the build status
[11:15:09.477] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:09.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:80:0) after 1m0s
[11:16:09.544] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:80:0)
[11:16:09.544] Timer tick, asking Buildbucket for the build status
[11:16:09.819] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:09.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:81:0) after 1m0s
[11:17:09.888] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:81:0)
[11:17:09.888] Timer tick, asking Buildbucket for the build status
[11:17:10.345] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:10.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:82:0) after 1m0s
[11:18:10.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:82:0)
[11:18:10.356] Timer tick, asking Buildbucket for the build status
[11:18:10.474] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:10.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:83:0) after 1m0s
[11:19:10.530] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:83:0)
[11:19:10.530] Timer tick, asking Buildbucket for the build status
[11:19:10.608] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:10.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:84:0) after 1m0s
[11:20:10.638] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:84:0)
[11:20:10.638] Timer tick, asking Buildbucket for the build status
[11:20:12.530] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:12.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:85:0) after 1m0s
[11:21:12.576] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:85:0)
[11:21:12.576] Timer tick, asking Buildbucket for the build status
[11:21:12.667] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:12.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:86:0) after 1m0s
[11:22:12.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:86:0)
[11:22:12.734] Timer tick, asking Buildbucket for the build status
[11:22:12.986] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:12.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:87:0) after 1m0s
[11:23:13.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:87:0)
[11:23:13.029] Timer tick, asking Buildbucket for the build status
[11:23:13.176] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:13.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:88:0) after 1m0s
[11:24:13.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:88:0)
[11:24:13.238] Timer tick, asking Buildbucket for the build status
[11:24:13.295] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:13.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:89:0) after 1m0s
[11:25:13.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:89:0)
[11:25:13.356] Timer tick, asking Buildbucket for the build status
[11:25:13.429] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:13.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:90:0) after 1m0s
[11:26:13.453] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:90:0)
[11:26:13.453] Timer tick, asking Buildbucket for the build status
[11:26:13.512] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:13.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:91:0) after 1m0s
[11:27:13.672] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:91:0)
[11:27:13.672] Timer tick, asking Buildbucket for the build status
[11:27:13.733] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:13.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:92:0) after 1m0s
[11:28:13.883] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:92:0)
[11:28:13.883] Timer tick, asking Buildbucket for the build status
[11:28:14.015] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:14.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:93:0) after 1m0s
[11:29:14.156] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:93:0)
[11:29:14.156] Timer tick, asking Buildbucket for the build status
[11:29:14.244] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:14.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:94:0) after 1m0s
[11:30:14.267] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:94:0)
[11:30:14.267] Timer tick, asking Buildbucket for the build status
[11:30:14.546] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:14.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:95:0) after 1m0s
[11:31:14.684] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:95:0)
[11:31:14.684] Timer tick, asking Buildbucket for the build status
[11:31:14.757] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:14.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:96:0) after 1m0s
[11:32:14.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:96:0)
[11:32:14.938] Timer tick, asking Buildbucket for the build status
[11:32:14.988] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:14.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:97:0) after 1m0s
[11:33:15.078] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:97:0)
[11:33:15.078] Timer tick, asking Buildbucket for the build status
[11:33:15.323] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:15.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:98:0) after 1m0s
[11:34:15.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:98:0)
[11:34:15.372] Timer tick, asking Buildbucket for the build status
[11:34:15.504] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:15.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:99:0) after 1m0s
[11:35:15.617] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:99:0)
[11:35:15.617] Timer tick, asking Buildbucket for the build status
[11:35:15.760] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:15.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:100:0) after 1m0s
[11:36:15.794] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:100:0)
[11:36:15.794] Timer tick, asking Buildbucket for the build status
[11:36:15.881] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:15.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:101:0) after 1m0s
[11:37:15.923] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:101:0)
[11:37:15.923] Timer tick, asking Buildbucket for the build status
[11:37:15.993] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:15.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:102:0) after 1m0s
[11:38:16.062] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:102:0)
[11:38:16.062] Timer tick, asking Buildbucket for the build status
[11:38:16.203] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:16.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:103:0) after 1m0s
[11:39:16.272] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:103:0)
[11:39:16.272] Timer tick, asking Buildbucket for the build status
[11:39:16.337] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:16.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:104:0) after 1m0s
[11:40:16.341] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:104:0)
[11:40:16.341] Timer tick, asking Buildbucket for the build status
[11:40:16.442] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:16.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:105:0) after 1m0s
[11:41:16.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:105:0)
[11:41:16.529] Timer tick, asking Buildbucket for the build status
[11:41:16.854] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:16.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:106:0) after 1m0s
[11:42:16.945] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:106:0)
[11:42:16.945] Timer tick, asking Buildbucket for the build status
[11:42:17.086] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:17.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:107:0) after 1m0s
[11:43:17.132] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:107:0)
[11:43:17.132] Timer tick, asking Buildbucket for the build status
[11:43:17.413] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:17.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:108:0) after 1m0s
[11:44:17.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:108:0)
[11:44:17.669] Timer tick, asking Buildbucket for the build status
[11:44:18.401] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:18.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:109:0) after 1m0s
[11:45:18.632] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:109:0)
[11:45:18.632] Timer tick, asking Buildbucket for the build status
[11:45:18.701] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:18.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:110:0) after 1m0s
[11:46:18.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:110:0)
[11:46:18.949] Timer tick, asking Buildbucket for the build status
[11:46:19.026] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:19.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:111:0) after 1m0s
[11:47:19.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:111:0)
[11:47:19.238] Timer tick, asking Buildbucket for the build status
[11:47:19.534] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:19.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:112:0) after 1m0s
[11:48:20.662] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:112:0)
[11:48:20.662] Timer tick, asking Buildbucket for the build status
[11:48:20.785] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:20.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:113:0) after 1m0s
[11:49:21.064] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:113:0)
[11:49:21.064] Timer tick, asking Buildbucket for the build status
[11:49:21.128] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:21.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:114:0) after 1m0s
[11:50:21.403] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:114:0)
[11:50:21.403] Timer tick, asking Buildbucket for the build status
[11:50:21.465] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:21.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:115:0) after 1m0s
[11:51:21.638] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:115:0)
[11:51:21.638] Timer tick, asking Buildbucket for the build status
[11:51:21.940] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:21.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:116:0) after 1m0s
[11:52:22.649] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:116:0)
[11:52:22.649] Timer tick, asking Buildbucket for the build status
[11:52:22.900] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:22.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:117:0) after 1m0s
[11:53:23.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:117:0)
[11:53:23.015] Timer tick, asking Buildbucket for the build status
[11:53:23.355] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:23.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:118:0) after 1m0s
[11:54:23.416] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:118:0)
[11:54:23.416] Timer tick, asking Buildbucket for the build status
[11:54:23.468] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:23.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:119:0) after 1m0s
[11:55:23.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:119:0)
[11:55:23.707] Timer tick, asking Buildbucket for the build status
[11:55:23.781] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:23.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:120:0) after 1m0s
[11:56:23.993] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:120:0)
[11:56:23.993] Timer tick, asking Buildbucket for the build status
[11:56:24.323] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:24.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:121:0) after 1m0s
[11:57:24.458] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:121:0)
[11:57:24.458] Timer tick, asking Buildbucket for the build status
[11:57:24.539] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:24.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:122:0) after 1m0s
[11:58:24.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:122:0)
[11:58:24.701] Timer tick, asking Buildbucket for the build status
[11:58:24.890] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:24.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:123:0) after 1m0s
[11:59:24.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:123:0)
[11:59:24.987] Timer tick, asking Buildbucket for the build status
[11:59:25.059] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:25.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:124:0) after 1m0s
[12:00:25.244] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:124:0)
[12:00:25.244] Timer tick, asking Buildbucket for the build status
[12:00:25.362] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:25.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:125:0) after 1m0s
[12:01:25.562] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:125:0)
[12:01:25.562] Timer tick, asking Buildbucket for the build status
[12:01:25.635] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:25.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:126:0) after 1m0s
[12:02:25.799] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:126:0)
[12:02:25.799] Timer tick, asking Buildbucket for the build status
[12:02:25.851] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:25.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:127:0) after 1m0s
[12:03:25.908] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:127:0)
[12:03:25.908] Timer tick, asking Buildbucket for the build status
[12:03:26.062] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:26.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:128:0) after 1m0s
[12:04:26.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:128:0)
[12:04:26.152] Timer tick, asking Buildbucket for the build status
[12:04:26.220] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:26.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:129:0) after 1m0s
[12:05:26.232] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:129:0)
[12:05:26.232] Timer tick, asking Buildbucket for the build status
[12:05:26.299] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:26.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:130:0) after 1m0s
[12:06:26.399] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:130:0)
[12:06:26.399] Timer tick, asking Buildbucket for the build status
[12:06:26.457] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:26.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:131:0) after 1m0s
[12:07:26.463] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:131:0)
[12:07:26.463] Timer tick, asking Buildbucket for the build status
[12:07:26.999] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:26.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:132:0) after 1m0s
[12:08:27.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:132:0)
[12:08:27.130] Timer tick, asking Buildbucket for the build status
[12:08:27.316] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:27.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:133:0) after 1m0s
[12:09:27.497] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:133:0)
[12:09:27.497] Timer tick, asking Buildbucket for the build status
[12:09:27.622] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:27.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:134:0) after 1m0s
[12:10:27.691] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:134:0)
[12:10:27.691] Timer tick, asking Buildbucket for the build status
[12:10:27.901] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:27.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:135:0) after 1m0s
[12:11:28.009] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:135:0)
[12:11:28.009] Timer tick, asking Buildbucket for the build status
[12:11:28.074] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:28.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:136:0) after 1m0s
[12:12:28.161] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:136:0)
[12:12:28.161] Timer tick, asking Buildbucket for the build status
[12:12:28.496] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:28.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:137:0) after 1m0s
[12:13:28.562] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:137:0)
[12:13:28.562] Timer tick, asking Buildbucket for the build status
[12:13:28.676] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:28.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:138:0) after 1m0s
[12:14:28.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:138:0)
[12:14:28.756] Timer tick, asking Buildbucket for the build status
[12:14:28.942] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:28.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:139:0) after 1m0s
[12:15:28.963] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:139:0)
[12:15:28.963] Timer tick, asking Buildbucket for the build status
[12:15:29.222] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:29.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:140:0) after 1m0s
[12:16:29.285] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:140:0)
[12:16:29.285] Timer tick, asking Buildbucket for the build status
[12:16:29.349] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:29.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:141:0) after 1m0s
[12:17:29.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:141:0)
[12:17:29.362] Timer tick, asking Buildbucket for the build status
[12:17:29.441] Build 8857775669127135568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:29.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023205731073349648:142:0) after 1m0s
[12:17:37.819] Received PubSub notification, asking Buildbucket for the build status
[12:17:37.886] Build 8857775669127135568: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:17:37.886] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1610972257036714",
"created_by": "project:chromium",
"created_ts": "1610963866073420",
"id": "8857775669127135568",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"89565419bfff402af08584b86663c0bc5c01e73b\"}}",
"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\": \"89565419bfff402af08584b86663c0bc5c01e73b\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-6-rco3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1406, \"got_angle_revision\": \"bd2954a9c499381ddaf10bd5ca3d93dd7b0b9bed\", \"got_dawn_revision\": \"04772515a8b613150b28bbed19b4c261e0b50e96\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"89565419bfff402af08584b86663c0bc5c01e73b\", \"got_revision_cp\": \"refs/heads/master@{#844469}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"149733cead636de93d96c5c64f30168d5f6bb03f\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"12971a22d265c7ba1c3c388f5d487580939a1162\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33014}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"89565419bfff402af08584b86663c0bc5c01e73b\"}, \"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-6-rco3\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5666-679c9bd\"], \"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": "1610963902870982",
"status": "COMPLETED",
"status_changed_ts": "1610972257510787",
"tags": [
"build_address:luci.chromium.ci/win-official/1406",
"builder:win-official",
"buildset:commit/git/89565419bfff402af08584b86663c0bc5c01e73b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/89565419bfff402af08584b86663c0bc5c01e73b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023205731073349648",
"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/8857775669127135568/+/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:512dbf5c6b14e910",
"user_agent:luci-scheduler"
],
"updated_ts": "1610972257530206",
"url": "https://ci.chromium.org/b/8857775669127135568",
"utcnow_ts": "1610972257877488"
}
}
[12:17:37.886] Invocation finished in 2h19m53.053133774s with status SUCCEEDED