[07:08:41.016] New invocation is queued and will start shortly
[07:08:42.109] Starting the invocation (attempt 1)
[07:08:42.148] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:08:42.148] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:08:42.148] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9035990539917995008",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"eacac19787456ec785ea902b9cf24340fa97593b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9035990539917995008",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/eacac19787456ec785ea902b9cf24340fa97593b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eacac19787456ec785ea902b9cf24340fa97593b",
"gitiles_ref:refs/heads/master"
]
}
[07:08:43.778] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1598771322290714",
"id": "8870560477916656448",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"eacac19787456ec785ea902b9cf24340fa97593b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1598771323466157",
"tags": [
"build_address:luci.chromium.ci/win-official/459",
"builder:win-official",
"buildset:commit/git/eacac19787456ec785ea902b9cf24340fa97593b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eacac19787456ec785ea902b9cf24340fa97593b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035990539917995008",
"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/8870560477916656448/+/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": "1598771323466287",
"url": "https://ci.chromium.org/b/8870560477916656448",
"utcnow_ts": "1598771323762781"
}
}
[07:08:43.778] Task URL: https://ci.chromium.org/b/8870560477916656448
[07:08:43.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:2:0) after 1m0s
[07:09:21.944] Received PubSub notification, asking Buildbucket for the build status
[07:09:22.053] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:43.975] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:2:0)
[07:09:43.975] Timer tick, asking Buildbucket for the build status
[07:09:44.199] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:44.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:4:0) after 1m0s
[07:10:44.270] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:4:0)
[07:10:44.270] Timer tick, asking Buildbucket for the build status
[07:10:44.368] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:44.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:5:0) after 1m0s
[07:11:44.355] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:5:0)
[07:11:44.355] Timer tick, asking Buildbucket for the build status
[07:11:44.459] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:44.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:6:0) after 1m0s
[07:12:44.513] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:6:0)
[07:12:44.513] Timer tick, asking Buildbucket for the build status
[07:12:45.058] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:45.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:7:0) after 1m0s
[07:13:45.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:7:0)
[07:13:45.038] Timer tick, asking Buildbucket for the build status
[07:13:45.182] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:45.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:8:0) after 1m0s
[07:14:45.207] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:8:0)
[07:14:45.207] Timer tick, asking Buildbucket for the build status
[07:14:45.375] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:45.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:9:0) after 1m0s
[07:15:45.354] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:9:0)
[07:15:45.354] Timer tick, asking Buildbucket for the build status
[07:15:45.506] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:45.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:10:0) after 1m0s
[07:16:45.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:10:0)
[07:16:45.590] Timer tick, asking Buildbucket for the build status
[07:16:45.797] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:45.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:11:0) after 1m0s
[07:17:45.816] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:11:0)
[07:17:45.816] Timer tick, asking Buildbucket for the build status
[07:17:46.017] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:46.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:12:0) after 1m0s
[07:18:46.045] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:12:0)
[07:18:46.045] Timer tick, asking Buildbucket for the build status
[07:18:46.197] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:46.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:13:0) after 1m0s
[07:19:46.253] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:13:0)
[07:19:46.253] Timer tick, asking Buildbucket for the build status
[07:19:46.388] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:46.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:14:0) after 1m0s
[07:20:46.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:14:0)
[07:20:46.529] Timer tick, asking Buildbucket for the build status
[07:20:46.664] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:46.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:15:0) after 1m0s
[07:21:46.846] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:15:0)
[07:21:46.846] Timer tick, asking Buildbucket for the build status
[07:21:46.930] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:46.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:16:0) after 1m0s
[07:22:47.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:16:0)
[07:22:47.003] Timer tick, asking Buildbucket for the build status
[07:22:47.119] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:47.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:17:0) after 1m0s
[07:23:47.093] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:17:0)
[07:23:47.093] Timer tick, asking Buildbucket for the build status
[07:23:47.491] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:47.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:18:0) after 1m0s
[07:24:47.649] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:18:0)
[07:24:47.649] Timer tick, asking Buildbucket for the build status
[07:24:47.819] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:47.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:19:0) after 1m0s
[07:25:47.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:19:0)
[07:25:47.836] Timer tick, asking Buildbucket for the build status
[07:25:47.917] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:47.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:20:0) after 1m0s
[07:26:48.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:20:0)
[07:26:48.173] Timer tick, asking Buildbucket for the build status
[07:26:48.257] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:48.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:21:0) after 1m0s
[07:27:48.326] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:21:0)
[07:27:48.326] Timer tick, asking Buildbucket for the build status
[07:27:48.410] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:48.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:22:0) after 1m0s
[07:28:48.503] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:22:0)
[07:28:48.503] Timer tick, asking Buildbucket for the build status
[07:28:49.087] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:49.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:23:0) after 1m0s
[07:29:49.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:23:0)
[07:29:49.247] Timer tick, asking Buildbucket for the build status
[07:29:49.422] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:49.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:24:0) after 1m0s
[07:30:49.394] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:24:0)
[07:30:49.394] Timer tick, asking Buildbucket for the build status
[07:30:49.543] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:49.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:25:0) after 1m0s
[07:31:49.519] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:25:0)
[07:31:49.519] Timer tick, asking Buildbucket for the build status
[07:31:49.699] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:49.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:26:0) after 1m0s
[07:32:49.724] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:26:0)
[07:32:49.724] Timer tick, asking Buildbucket for the build status
[07:32:49.802] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:49.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:27:0) after 1m0s
[07:33:50.113] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:27:0)
[07:33:50.113] Timer tick, asking Buildbucket for the build status
[07:33:50.286] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:50.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:28:0) after 1m0s
[07:34:50.479] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:28:0)
[07:34:50.479] Timer tick, asking Buildbucket for the build status
[07:34:50.666] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:50.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:29:0) after 1m0s
[07:35:50.695] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:29:0)
[07:35:50.695] Timer tick, asking Buildbucket for the build status
[07:35:50.765] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:50.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:30:0) after 1m0s
[07:36:50.792] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:30:0)
[07:36:50.792] Timer tick, asking Buildbucket for the build status
[07:36:50.985] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:50.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:31:0) after 1m0s
[07:37:51.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:31:0)
[07:37:51.005] Timer tick, asking Buildbucket for the build status
[07:37:51.078] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:51.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:32:0) after 1m0s
[07:38:51.148] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:32:0)
[07:38:51.148] Timer tick, asking Buildbucket for the build status
[07:38:51.559] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:51.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:33:0) after 1m0s
[07:39:51.599] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:33:0)
[07:39:51.599] Timer tick, asking Buildbucket for the build status
[07:39:51.708] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:51.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:34:0) after 1m0s
[07:40:51.874] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:34:0)
[07:40:51.874] Timer tick, asking Buildbucket for the build status
[07:40:51.939] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:51.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:35:0) after 1m0s
[07:41:52.894] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:35:0)
[07:41:52.894] Timer tick, asking Buildbucket for the build status
[07:41:53.006] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:53.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:36:0) after 1m0s
[07:42:53.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:36:0)
[07:42:53.249] Timer tick, asking Buildbucket for the build status
[07:42:53.309] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:53.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:37:0) after 1m0s
[07:43:53.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:37:0)
[07:43:53.452] Timer tick, asking Buildbucket for the build status
[07:43:53.793] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:53.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:38:0) after 1m0s
[07:44:53.766] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:38:0)
[07:44:53.766] Timer tick, asking Buildbucket for the build status
[07:44:53.897] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:53.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:39:0) after 1m0s
[07:45:53.969] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:39:0)
[07:45:53.969] Timer tick, asking Buildbucket for the build status
[07:45:54.085] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:54.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:40:0) after 1m0s
[07:46:54.161] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:40:0)
[07:46:54.161] Timer tick, asking Buildbucket for the build status
[07:46:54.265] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:54.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:41:0) after 1m0s
[07:47:54.329] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:41:0)
[07:47:54.329] Timer tick, asking Buildbucket for the build status
[07:47:54.410] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:54.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:42:0) after 1m0s
[07:48:54.630] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:42:0)
[07:48:54.630] Timer tick, asking Buildbucket for the build status
[07:48:54.722] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:54.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:43:0) after 1m0s
[07:49:54.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:43:0)
[07:49:54.867] Timer tick, asking Buildbucket for the build status
[07:49:54.926] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:54.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:44:0) after 1m0s
[07:50:54.952] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:44:0)
[07:50:54.952] Timer tick, asking Buildbucket for the build status
[07:50:55.201] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:55.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:45:0) after 1m0s
[07:51:55.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:45:0)
[07:51:55.348] Timer tick, asking Buildbucket for the build status
[07:51:55.416] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:55.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:46:0) after 1m0s
[07:52:55.527] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:46:0)
[07:52:55.527] Timer tick, asking Buildbucket for the build status
[07:52:55.717] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:55.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:47:0) after 1m0s
[07:53:55.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:47:0)
[07:53:55.741] Timer tick, asking Buildbucket for the build status
[07:53:55.928] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:55.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:48:0) after 1m0s
[07:54:56.064] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:48:0)
[07:54:56.064] Timer tick, asking Buildbucket for the build status
[07:54:56.136] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:56.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:49:0) after 1m0s
[07:55:56.177] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:49:0)
[07:55:56.177] Timer tick, asking Buildbucket for the build status
[07:55:56.312] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:56.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:50:0) after 1m0s
[07:56:56.292] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:50:0)
[07:56:56.292] Timer tick, asking Buildbucket for the build status
[07:56:56.372] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:56.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:51:0) after 1m0s
[07:57:56.453] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:51:0)
[07:57:56.453] Timer tick, asking Buildbucket for the build status
[07:57:56.532] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:56.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:52:0) after 1m0s
[07:58:56.584] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:52:0)
[07:58:56.584] Timer tick, asking Buildbucket for the build status
[07:58:56.732] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:56.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:53:0) after 1m0s
[07:59:56.762] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:53:0)
[07:59:56.762] Timer tick, asking Buildbucket for the build status
[07:59:56.824] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:56.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:54:0) after 1m0s
[08:00:56.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:54:0)
[08:00:56.889] Timer tick, asking Buildbucket for the build status
[08:00:56.989] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:56.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:55:0) after 1m0s
[08:01:57.059] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:55:0)
[08:01:57.059] Timer tick, asking Buildbucket for the build status
[08:01:57.208] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:57.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:56:0) after 1m0s
[08:02:57.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:56:0)
[08:02:57.274] Timer tick, asking Buildbucket for the build status
[08:02:57.347] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:57.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:57:0) after 1m0s
[08:03:57.410] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:57:0)
[08:03:57.410] Timer tick, asking Buildbucket for the build status
[08:03:57.589] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:57.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:58:0) after 1m0s
[08:04:57.660] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:58:0)
[08:04:57.660] Timer tick, asking Buildbucket for the build status
[08:04:57.733] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:57.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:59:0) after 1m0s
[08:05:57.860] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:59:0)
[08:05:57.860] Timer tick, asking Buildbucket for the build status
[08:05:58.218] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:58.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:60:0) after 1m0s
[08:06:58.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:60:0)
[08:06:58.254] Timer tick, asking Buildbucket for the build status
[08:06:58.319] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:58.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:61:0) after 1m0s
[08:07:58.368] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:61:0)
[08:07:58.368] Timer tick, asking Buildbucket for the build status
[08:07:58.531] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:58.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:62:0) after 1m0s
[08:08:58.548] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:62:0)
[08:08:58.548] Timer tick, asking Buildbucket for the build status
[08:08:58.674] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:58.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:63:0) after 1m0s
[08:09:58.700] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:63:0)
[08:09:58.700] Timer tick, asking Buildbucket for the build status
[08:09:58.824] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:58.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:64:0) after 1m0s
[08:10:58.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:64:0)
[08:10:58.854] Timer tick, asking Buildbucket for the build status
[08:10:59.141] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:59.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:65:0) after 1m0s
[08:11:59.172] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:65:0)
[08:11:59.172] Timer tick, asking Buildbucket for the build status
[08:11:59.227] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:59.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:66:0) after 1m0s
[08:12:59.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:66:0)
[08:12:59.274] Timer tick, asking Buildbucket for the build status
[08:12:59.401] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:59.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:67:0) after 1m0s
[08:13:59.421] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:67:0)
[08:13:59.421] Timer tick, asking Buildbucket for the build status
[08:13:59.521] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:59.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:68:0) after 1m0s
[08:14:59.548] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:68:0)
[08:14:59.548] Timer tick, asking Buildbucket for the build status
[08:14:59.653] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:59.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:69:0) after 1m0s
[08:15:59.674] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:69:0)
[08:15:59.674] Timer tick, asking Buildbucket for the build status
[08:15:59.789] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:59.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:70:0) after 1m0s
[08:16:59.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:70:0)
[08:16:59.868] Timer tick, asking Buildbucket for the build status
[08:17:00.056] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:00.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:71:0) after 1m0s
[08:18:00.194] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:71:0)
[08:18:00.194] Timer tick, asking Buildbucket for the build status
[08:18:00.272] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:00.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:72:0) after 1m0s
[08:19:00.615] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:72:0)
[08:19:00.615] Timer tick, asking Buildbucket for the build status
[08:19:00.738] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:00.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:73:0) after 1m0s
[08:20:01.297] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:73:0)
[08:20:01.297] Timer tick, asking Buildbucket for the build status
[08:20:01.410] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:01.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:74:0) after 1m0s
[08:21:01.432] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:74:0)
[08:21:01.432] Timer tick, asking Buildbucket for the build status
[08:21:01.505] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:01.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:75:0) after 1m0s
[08:22:01.794] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:75:0)
[08:22:01.794] Timer tick, asking Buildbucket for the build status
[08:22:02.158] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:02.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:76:0) after 1m0s
[08:23:03.294] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:76:0)
[08:23:03.294] Timer tick, asking Buildbucket for the build status
[08:23:03.382] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:03.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:77:0) after 1m0s
[08:24:03.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:77:0)
[08:24:03.409] Timer tick, asking Buildbucket for the build status
[08:24:03.587] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:03.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:78:0) after 1m0s
[08:25:03.613] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:78:0)
[08:25:03.613] Timer tick, asking Buildbucket for the build status
[08:25:03.965] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:03.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:79:0) after 1m0s
[08:26:04.095] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:79:0)
[08:26:04.095] Timer tick, asking Buildbucket for the build status
[08:26:04.167] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:04.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:80:0) after 1m0s
[08:27:04.295] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:80:0)
[08:27:04.295] Timer tick, asking Buildbucket for the build status
[08:27:04.370] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:04.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:81:0) after 1m0s
[08:28:04.448] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:81:0)
[08:28:04.448] Timer tick, asking Buildbucket for the build status
[08:28:04.547] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:04.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:82:0) after 1m0s
[08:29:04.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:82:0)
[08:29:04.618] Timer tick, asking Buildbucket for the build status
[08:29:04.740] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:04.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:83:0) after 1m0s
[08:30:04.759] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:83:0)
[08:30:04.759] Timer tick, asking Buildbucket for the build status
[08:30:04.892] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:04.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:84:0) after 1m0s
[08:31:05.019] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:84:0)
[08:31:05.019] Timer tick, asking Buildbucket for the build status
[08:31:05.100] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:05.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:85:0) after 1m0s
[08:32:05.179] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:85:0)
[08:32:05.179] Timer tick, asking Buildbucket for the build status
[08:32:05.310] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:05.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:86:0) after 1m0s
[08:33:05.469] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:86:0)
[08:33:05.469] Timer tick, asking Buildbucket for the build status
[08:33:05.582] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:05.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:87:0) after 1m0s
[08:34:05.715] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:87:0)
[08:34:05.715] Timer tick, asking Buildbucket for the build status
[08:34:06.370] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:06.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:88:0) after 1m0s
[08:35:06.599] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:88:0)
[08:35:06.599] Timer tick, asking Buildbucket for the build status
[08:35:06.688] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:06.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:89:0) after 1m0s
[08:36:06.816] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:89:0)
[08:36:06.816] Timer tick, asking Buildbucket for the build status
[08:36:06.896] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:06.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:90:0) after 1m0s
[08:37:06.961] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:90:0)
[08:37:06.961] Timer tick, asking Buildbucket for the build status
[08:37:07.043] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:07.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:91:0) after 1m0s
[08:38:07.163] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:91:0)
[08:38:07.163] Timer tick, asking Buildbucket for the build status
[08:38:07.223] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:07.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:92:0) after 1m0s
[08:39:07.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:92:0)
[08:39:07.347] Timer tick, asking Buildbucket for the build status
[08:39:07.455] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:07.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:93:0) after 1m0s
[08:40:08.212] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:93:0)
[08:40:08.212] Timer tick, asking Buildbucket for the build status
[08:40:08.274] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:08.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:94:0) after 1m0s
[08:41:08.632] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:94:0)
[08:41:08.632] Timer tick, asking Buildbucket for the build status
[08:41:08.730] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:08.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:95:0) after 1m0s
[08:42:09.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:95:0)
[08:42:09.104] Timer tick, asking Buildbucket for the build status
[08:42:09.236] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:09.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:96:0) after 1m0s
[08:43:09.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:96:0)
[08:43:09.350] Timer tick, asking Buildbucket for the build status
[08:43:09.799] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:09.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:97:0) after 1m0s
[08:44:09.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:97:0)
[08:44:09.818] Timer tick, asking Buildbucket for the build status
[08:44:09.916] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:09.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:98:0) after 1m0s
[08:45:09.940] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:98:0)
[08:45:09.940] Timer tick, asking Buildbucket for the build status
[08:45:10.085] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:10.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:99:0) after 1m0s
[08:46:10.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:99:0)
[08:46:10.117] Timer tick, asking Buildbucket for the build status
[08:46:10.196] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:10.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:100:0) after 1m0s
[08:47:10.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:100:0)
[08:47:10.283] Timer tick, asking Buildbucket for the build status
[08:47:10.366] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:10.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:101:0) after 1m0s
[08:48:10.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:101:0)
[08:48:10.452] Timer tick, asking Buildbucket for the build status
[08:48:10.549] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:10.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:102:0) after 1m0s
[08:49:10.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:102:0)
[08:49:10.666] Timer tick, asking Buildbucket for the build status
[08:49:10.868] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:10.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:103:0) after 1m0s
[08:50:11.048] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:103:0)
[08:50:11.048] Timer tick, asking Buildbucket for the build status
[08:50:11.279] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:11.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:104:0) after 1m0s
[08:51:11.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:104:0)
[08:51:11.414] Timer tick, asking Buildbucket for the build status
[08:51:11.476] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:11.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:105:0) after 1m0s
[08:52:11.588] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:105:0)
[08:52:11.588] Timer tick, asking Buildbucket for the build status
[08:52:11.702] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:11.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:106:0) after 1m0s
[08:53:11.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:106:0)
[08:53:11.757] Timer tick, asking Buildbucket for the build status
[08:53:11.843] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:11.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:107:0) after 1m0s
[08:54:11.989] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:107:0)
[08:54:11.989] Timer tick, asking Buildbucket for the build status
[08:54:12.407] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:12.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:108:0) after 1m0s
[08:55:12.449] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:108:0)
[08:55:12.449] Timer tick, asking Buildbucket for the build status
[08:55:12.594] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:12.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:109:0) after 1m0s
[08:56:12.630] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:109:0)
[08:56:12.630] Timer tick, asking Buildbucket for the build status
[08:56:12.788] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:12.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:110:0) after 1m0s
[08:57:12.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:110:0)
[08:57:12.919] Timer tick, asking Buildbucket for the build status
[08:57:13.003] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:13.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:111:0) after 1m0s
[08:58:13.205] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:111:0)
[08:58:13.205] Timer tick, asking Buildbucket for the build status
[08:58:13.397] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:13.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:112:0) after 1m0s
[08:59:13.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:112:0)
[08:59:13.524] Timer tick, asking Buildbucket for the build status
[08:59:13.896] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:13.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:113:0) after 1m0s
[09:00:14.205] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:113:0)
[09:00:14.205] Timer tick, asking Buildbucket for the build status
[09:00:14.288] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:14.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:114:0) after 1m0s
[09:01:14.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:114:0)
[09:01:14.415] Timer tick, asking Buildbucket for the build status
[09:01:14.472] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:14.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:115:0) after 1m0s
[09:02:14.541] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:115:0)
[09:02:14.541] Timer tick, asking Buildbucket for the build status
[09:02:14.727] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:14.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:116:0) after 1m0s
[09:03:14.759] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:116:0)
[09:03:14.759] Timer tick, asking Buildbucket for the build status
[09:03:14.828] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:14.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:117:0) after 1m0s
[09:04:14.989] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:117:0)
[09:04:14.989] Timer tick, asking Buildbucket for the build status
[09:04:15.398] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:15.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:118:0) after 1m0s
[09:05:15.581] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:118:0)
[09:05:15.581] Timer tick, asking Buildbucket for the build status
[09:05:15.659] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:15.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:119:0) after 1m0s
[09:06:15.739] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:119:0)
[09:06:15.739] Timer tick, asking Buildbucket for the build status
[09:06:15.856] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:15.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:120:0) after 1m0s
[09:07:15.995] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:120:0)
[09:07:15.996] Timer tick, asking Buildbucket for the build status
[09:07:16.285] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:16.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:121:0) after 1m0s
[09:08:16.405] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:121:0)
[09:08:16.405] Timer tick, asking Buildbucket for the build status
[09:08:16.514] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:16.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:122:0) after 1m0s
[09:09:16.541] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:122:0)
[09:09:16.541] Timer tick, asking Buildbucket for the build status
[09:09:16.677] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:16.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:123:0) after 1m0s
[09:10:16.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:123:0)
[09:10:16.734] Timer tick, asking Buildbucket for the build status
[09:10:16.818] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:16.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:124:0) after 1m0s
[09:11:16.826] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:124:0)
[09:11:16.826] Timer tick, asking Buildbucket for the build status
[09:11:16.910] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:16.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:125:0) after 1m0s
[09:12:17.189] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:125:0)
[09:12:17.189] Timer tick, asking Buildbucket for the build status
[09:12:17.561] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:17.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:126:0) after 1m0s
[09:13:17.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:126:0)
[09:13:17.597] Timer tick, asking Buildbucket for the build status
[09:13:17.812] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:17.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:127:0) after 1m0s
[09:14:17.846] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:127:0)
[09:14:17.846] Timer tick, asking Buildbucket for the build status
[09:14:17.994] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:17.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:128:0) after 1m0s
[09:15:18.085] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:128:0)
[09:15:18.085] Timer tick, asking Buildbucket for the build status
[09:15:18.164] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:18.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:129:0) after 1m0s
[09:16:18.352] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:129:0)
[09:16:18.352] Timer tick, asking Buildbucket for the build status
[09:16:18.509] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:18.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:130:0) after 1m0s
[09:17:18.696] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:130:0)
[09:17:18.696] Timer tick, asking Buildbucket for the build status
[09:17:18.815] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:18.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:131:0) after 1m0s
[09:18:18.961] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:131:0)
[09:18:18.961] Timer tick, asking Buildbucket for the build status
[09:18:19.030] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:19.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:132:0) after 1m0s
[09:19:19.026] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:132:0)
[09:19:19.026] Timer tick, asking Buildbucket for the build status
[09:19:19.155] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:19.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:133:0) after 1m0s
[09:20:19.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:133:0)
[09:20:19.230] Timer tick, asking Buildbucket for the build status
[09:20:19.386] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:19.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:134:0) after 1m0s
[09:21:19.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:134:0)
[09:21:19.415] Timer tick, asking Buildbucket for the build status
[09:21:19.484] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:19.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:135:0) after 1m0s
[09:22:19.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:135:0)
[09:22:19.539] Timer tick, asking Buildbucket for the build status
[09:22:19.667] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:19.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:136:0) after 1m0s
[09:23:19.656] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:136:0)
[09:23:19.656] Timer tick, asking Buildbucket for the build status
[09:23:19.830] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:19.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:137:0) after 1m0s
[09:24:19.821] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:137:0)
[09:24:19.821] Timer tick, asking Buildbucket for the build status
[09:24:20.044] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:20.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:138:0) after 1m0s
[09:25:20.193] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:138:0)
[09:25:20.193] Timer tick, asking Buildbucket for the build status
[09:25:20.401] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:20.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:139:0) after 1m0s
[09:26:20.566] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:139:0)
[09:26:20.566] Timer tick, asking Buildbucket for the build status
[09:26:20.633] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:20.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:140:0) after 1m0s
[09:27:20.711] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:140:0)
[09:27:20.711] Timer tick, asking Buildbucket for the build status
[09:27:21.066] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:21.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:141:0) after 1m0s
[09:28:21.197] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:141:0)
[09:28:21.197] Timer tick, asking Buildbucket for the build status
[09:28:21.333] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:21.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:142:0) after 1m0s
[09:29:21.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:142:0)
[09:29:21.367] Timer tick, asking Buildbucket for the build status
[09:29:21.527] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:21.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:143:0) after 1m0s
[09:30:21.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:143:0)
[09:30:21.555] Timer tick, asking Buildbucket for the build status
[09:30:21.629] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:21.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:144:0) after 1m0s
[09:31:21.698] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:144:0)
[09:31:21.698] Timer tick, asking Buildbucket for the build status
[09:31:21.764] Build 8870560477916656448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:21.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035990539917995008:145:0) after 1m0s
[09:31:49.347] Received PubSub notification, asking Buildbucket for the build status
[09:31:49.739] Build 8870560477916656448: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:31:49.739] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1598779908381872",
"created_by": "project:chromium",
"created_ts": "1598771322290714",
"id": "8870560477916656448",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"eacac19787456ec785ea902b9cf24340fa97593b\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-6-vnlw\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 459, \"got_angle_revision\": \"a0d048a4023fc385408547f5508b139ff1d2a58f\", \"got_dawn_revision\": \"0b89b27263b127a46371641b299fdf0f2cd7caff\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"eacac19787456ec785ea902b9cf24340fa97593b\", \"got_revision_cp\": \"refs/heads/master@{#802946}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"a2e6c1a149f4ee03f46e11afbb72cfa10e5526e3\", \"got_v8_revision\": \"4b3de99d978c746f47a9da42eb275d4ea3300b5a\", \"got_v8_revision_cp\": \"refs/heads/8.7.37@{#1}\", \"got_webrtc_revision\": \"e3e2618b1b058d26e73e22953d5aaea99f0d7cae\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32014}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"eacac19787456ec785ea902b9cf24340fa97593b\"}, \"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-vnlw\"], \"image\": [\"chrome-win10-1703-20081700-d9392568d25\"], \"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\": [\"5498-3454aa6\"], \"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": "1598771360494986",
"status": "COMPLETED",
"status_changed_ts": "1598779908978453",
"tags": [
"build_address:luci.chromium.ci/win-official/459",
"builder:win-official",
"buildset:commit/git/eacac19787456ec785ea902b9cf24340fa97593b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eacac19787456ec785ea902b9cf24340fa97593b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035990539917995008",
"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/8870560477916656448/+/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:4e5703ed8b59d510",
"user_agent:luci-scheduler"
],
"updated_ts": "1598779908983052",
"url": "https://ci.chromium.org/b/8870560477916656448",
"utcnow_ts": "1598779909680432"
}
}
[09:31:49.739] Invocation finished in 2h23m8.74114361s with status SUCCEEDED