[02:42:52.964] New invocation is queued and will start shortly
[02:42:54.123] Starting the invocation (attempt 1)
[02:42:54.153] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:42:54.153] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:42:54.153] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025045029726403264",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a08b1d1689c9f9d8535b27e86b66eb473130d939\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9025045029726403264",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/a08b1d1689c9f9d8535b27e86b66eb473130d939",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a08b1d1689c9f9d8535b27e86b66eb473130d939",
"gitiles_ref:refs/heads/master"
]
}
[02:42:54.663] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1609209774229429",
"id": "8859614967736762656",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a08b1d1689c9f9d8535b27e86b66eb473130d939\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609209774570945",
"tags": [
"build_address:luci.chromium.ci/win-official/1264",
"builder:win-official",
"buildset:commit/git/a08b1d1689c9f9d8535b27e86b66eb473130d939",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a08b1d1689c9f9d8535b27e86b66eb473130d939",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025045029726403264",
"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/8859614967736762656/+/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": "1609209774571050",
"url": "https://ci.chromium.org/b/8859614967736762656",
"utcnow_ts": "1609209774657477"
}
}
[02:42:54.663] Task URL: https://ci.chromium.org/b/8859614967736762656
[02:42:54.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:2:0) after 1m0s
[02:43:39.241] Received PubSub notification, asking Buildbucket for the build status
[02:43:39.319] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:54.650] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:2:0)
[02:43:54.650] Timer tick, asking Buildbucket for the build status
[02:43:54.713] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:54.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:4:0) after 1m0s
[02:44:54.737] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:4:0)
[02:44:54.737] Timer tick, asking Buildbucket for the build status
[02:44:54.828] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:54.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:5:0) after 1m0s
[02:45:55.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:5:0)
[02:45:55.451] Timer tick, asking Buildbucket for the build status
[02:45:55.529] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:55.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:6:0) after 1m0s
[02:46:55.592] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:6:0)
[02:46:55.592] Timer tick, asking Buildbucket for the build status
[02:46:55.660] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:55.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:7:0) after 1m0s
[02:47:55.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:7:0)
[02:47:55.726] Timer tick, asking Buildbucket for the build status
[02:47:55.838] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:55.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:8:0) after 1m0s
[02:48:55.999] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:8:0)
[02:48:55.999] Timer tick, asking Buildbucket for the build status
[02:48:56.085] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:56.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:9:0) after 1m0s
[02:49:56.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:9:0)
[02:49:56.233] Timer tick, asking Buildbucket for the build status
[02:49:56.413] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:56.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:10:0) after 1m0s
[02:50:56.466] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:10:0)
[02:50:56.466] Timer tick, asking Buildbucket for the build status
[02:50:56.592] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:56.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:11:0) after 1m0s
[02:51:56.692] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:11:0)
[02:51:56.692] Timer tick, asking Buildbucket for the build status
[02:51:57.046] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:57.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:12:0) after 1m0s
[02:52:57.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:12:0)
[02:52:57.102] Timer tick, asking Buildbucket for the build status
[02:52:57.187] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:57.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:13:0) after 1m0s
[02:53:57.289] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:13:0)
[02:53:57.289] Timer tick, asking Buildbucket for the build status
[02:53:57.392] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:57.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:14:0) after 1m0s
[02:54:57.457] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:14:0)
[02:54:57.457] Timer tick, asking Buildbucket for the build status
[02:54:57.555] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:57.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:15:0) after 1m0s
[02:55:57.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:15:0)
[02:55:57.600] Timer tick, asking Buildbucket for the build status
[02:55:57.682] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:57.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:16:0) after 1m0s
[02:56:57.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:16:0)
[02:56:57.757] Timer tick, asking Buildbucket for the build status
[02:56:57.855] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:57.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:17:0) after 1m0s
[02:57:57.906] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:17:0)
[02:57:57.906] Timer tick, asking Buildbucket for the build status
[02:57:58.018] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:58.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:18:0) after 1m0s
[02:58:58.018] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:18:0)
[02:58:58.018] Timer tick, asking Buildbucket for the build status
[02:58:58.077] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:58.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:19:0) after 1m0s
[02:59:58.096] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:19:0)
[02:59:58.096] Timer tick, asking Buildbucket for the build status
[02:59:58.480] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:58.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:20:0) after 1m0s
[03:00:58.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:20:0)
[03:00:58.974] Timer tick, asking Buildbucket for the build status
[03:00:59.205] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:59.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:21:0) after 1m0s
[03:01:59.235] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:21:0)
[03:01:59.235] Timer tick, asking Buildbucket for the build status
[03:01:59.404] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:59.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:22:0) after 1m0s
[03:02:59.480] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:22:0)
[03:02:59.480] Timer tick, asking Buildbucket for the build status
[03:02:59.604] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:59.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:23:0) after 1m0s
[03:03:59.633] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:23:0)
[03:03:59.633] Timer tick, asking Buildbucket for the build status
[03:03:59.693] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:59.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:24:0) after 1m0s
[03:04:59.767] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:24:0)
[03:04:59.767] Timer tick, asking Buildbucket for the build status
[03:04:59.979] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:59.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:25:0) after 1m0s
[03:06:00.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:25:0)
[03:06:00.043] Timer tick, asking Buildbucket for the build status
[03:06:00.256] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:00.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:26:0) after 1m0s
[03:07:00.285] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:26:0)
[03:07:00.285] Timer tick, asking Buildbucket for the build status
[03:07:00.415] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:00.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:27:0) after 1m0s
[03:08:00.438] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:27:0)
[03:08:00.438] Timer tick, asking Buildbucket for the build status
[03:08:00.554] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:00.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:28:0) after 1m0s
[03:09:00.700] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:28:0)
[03:09:00.700] Timer tick, asking Buildbucket for the build status
[03:09:01.274] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:01.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:29:0) after 1m0s
[03:10:01.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:29:0)
[03:10:01.863] Timer tick, asking Buildbucket for the build status
[03:10:02.080] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:02.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:30:0) after 1m0s
[03:11:02.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:30:0)
[03:11:02.249] Timer tick, asking Buildbucket for the build status
[03:11:02.431] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:02.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:31:0) after 1m0s
[03:12:02.519] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:31:0)
[03:12:02.519] Timer tick, asking Buildbucket for the build status
[03:12:02.588] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:02.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:32:0) after 1m0s
[03:13:02.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:32:0)
[03:13:02.652] Timer tick, asking Buildbucket for the build status
[03:13:02.879] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:02.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:33:0) after 1m0s
[03:14:02.884] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:33:0)
[03:14:02.884] Timer tick, asking Buildbucket for the build status
[03:14:02.946] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:02.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:34:0) after 1m0s
[03:15:02.968] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:34:0)
[03:15:02.968] Timer tick, asking Buildbucket for the build status
[03:15:03.021] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:03.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:35:0) after 1m0s
[03:16:03.069] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:35:0)
[03:16:03.070] Timer tick, asking Buildbucket for the build status
[03:16:03.134] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:03.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:36:0) after 1m0s
[03:17:03.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:36:0)
[03:17:03.155] Timer tick, asking Buildbucket for the build status
[03:17:03.366] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:03.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:37:0) after 1m0s
[03:18:03.406] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:37:0)
[03:18:03.406] Timer tick, asking Buildbucket for the build status
[03:18:03.683] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:03.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:38:0) after 1m0s
[03:19:03.713] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:38:0)
[03:19:03.713] Timer tick, asking Buildbucket for the build status
[03:19:03.831] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:03.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:39:0) after 1m0s
[03:20:03.885] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:39:0)
[03:20:03.885] Timer tick, asking Buildbucket for the build status
[03:20:04.002] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:04.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:40:0) after 1m0s
[03:21:04.040] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:40:0)
[03:21:04.040] Timer tick, asking Buildbucket for the build status
[03:21:04.273] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:04.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:41:0) after 1m0s
[03:22:04.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:41:0)
[03:22:04.314] Timer tick, asking Buildbucket for the build status
[03:22:04.429] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:04.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:42:0) after 1m0s
[03:23:04.477] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:42:0)
[03:23:04.477] Timer tick, asking Buildbucket for the build status
[03:23:04.651] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:04.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:43:0) after 1m0s
[03:24:04.710] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:43:0)
[03:24:04.710] Timer tick, asking Buildbucket for the build status
[03:24:04.800] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:04.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:44:0) after 1m0s
[03:25:04.801] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:44:0)
[03:25:04.801] Timer tick, asking Buildbucket for the build status
[03:25:04.861] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:04.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:45:0) after 1m0s
[03:26:04.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:45:0)
[03:26:04.951] Timer tick, asking Buildbucket for the build status
[03:26:05.028] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:05.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:46:0) after 1m0s
[03:27:05.083] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:46:0)
[03:27:05.083] Timer tick, asking Buildbucket for the build status
[03:27:05.143] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:05.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:47:0) after 1m0s
[03:28:05.251] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:47:0)
[03:28:05.251] Timer tick, asking Buildbucket for the build status
[03:28:05.361] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:05.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:48:0) after 1m0s
[03:29:05.459] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:48:0)
[03:29:05.459] Timer tick, asking Buildbucket for the build status
[03:29:06.211] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:06.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:49:0) after 1m0s
[03:30:06.250] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:49:0)
[03:30:06.250] Timer tick, asking Buildbucket for the build status
[03:30:06.321] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:06.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:50:0) after 1m0s
[03:31:06.398] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:50:0)
[03:31:06.398] Timer tick, asking Buildbucket for the build status
[03:31:06.516] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:06.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:51:0) after 1m0s
[03:32:06.627] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:51:0)
[03:32:06.627] Timer tick, asking Buildbucket for the build status
[03:32:06.726] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:06.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:52:0) after 1m0s
[03:33:06.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:52:0)
[03:33:06.822] Timer tick, asking Buildbucket for the build status
[03:33:07.027] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:07.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:53:0) after 1m0s
[03:34:07.072] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:53:0)
[03:34:07.072] Timer tick, asking Buildbucket for the build status
[03:34:07.129] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:07.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:54:0) after 1m0s
[03:35:07.121] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:54:0)
[03:35:07.121] Timer tick, asking Buildbucket for the build status
[03:35:07.187] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:07.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:55:0) after 1m0s
[03:36:07.206] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:55:0)
[03:36:07.206] Timer tick, asking Buildbucket for the build status
[03:36:07.316] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:07.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:56:0) after 1m0s
[03:37:07.375] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:56:0)
[03:37:07.375] Timer tick, asking Buildbucket for the build status
[03:37:08.041] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:08.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:57:0) after 1m0s
[03:38:08.157] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:57:0)
[03:38:08.157] Timer tick, asking Buildbucket for the build status
[03:38:08.260] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:08.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:58:0) after 1m0s
[03:39:08.400] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:58:0)
[03:39:08.400] Timer tick, asking Buildbucket for the build status
[03:39:08.822] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:08.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:59:0) after 1m0s
[03:40:08.886] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:59:0)
[03:40:08.886] Timer tick, asking Buildbucket for the build status
[03:40:08.965] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:08.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:60:0) after 1m0s
[03:41:08.989] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:60:0)
[03:41:08.989] Timer tick, asking Buildbucket for the build status
[03:41:09.049] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:09.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:61:0) after 1m0s
[03:42:09.073] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:61:0)
[03:42:09.073] Timer tick, asking Buildbucket for the build status
[03:42:09.135] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:09.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:62:0) after 1m0s
[03:43:09.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:62:0)
[03:43:09.118] Timer tick, asking Buildbucket for the build status
[03:43:09.226] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:09.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:63:0) after 1m0s
[03:44:09.319] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:63:0)
[03:44:09.319] Timer tick, asking Buildbucket for the build status
[03:44:09.382] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:09.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:64:0) after 1m0s
[03:45:09.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:64:0)
[03:45:09.395] Timer tick, asking Buildbucket for the build status
[03:45:10.135] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:10.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:65:0) after 1m0s
[03:46:10.172] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:65:0)
[03:46:10.172] Timer tick, asking Buildbucket for the build status
[03:46:10.306] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:10.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:66:0) after 1m0s
[03:47:10.391] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:66:0)
[03:47:10.391] Timer tick, asking Buildbucket for the build status
[03:47:10.493] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:10.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:67:0) after 1m0s
[03:48:10.594] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:67:0)
[03:48:10.594] Timer tick, asking Buildbucket for the build status
[03:48:10.750] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:10.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:68:0) after 1m0s
[03:49:10.807] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:68:0)
[03:49:10.807] Timer tick, asking Buildbucket for the build status
[03:49:10.878] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:10.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:69:0) after 1m0s
[03:50:10.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:69:0)
[03:50:10.933] Timer tick, asking Buildbucket for the build status
[03:50:11.192] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:11.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:70:0) after 1m0s
[03:51:11.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:70:0)
[03:51:11.245] Timer tick, asking Buildbucket for the build status
[03:51:11.443] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:11.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:71:0) after 1m0s
[03:52:11.468] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:71:0)
[03:52:11.468] Timer tick, asking Buildbucket for the build status
[03:52:11.548] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:11.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:72:0) after 1m0s
[03:53:11.558] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:72:0)
[03:53:11.558] Timer tick, asking Buildbucket for the build status
[03:53:11.655] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:11.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:73:0) after 1m0s
[03:54:11.712] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:73:0)
[03:54:11.712] Timer tick, asking Buildbucket for the build status
[03:54:12.471] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:12.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:74:0) after 1m0s
[03:55:12.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:74:0)
[03:55:12.491] Timer tick, asking Buildbucket for the build status
[03:55:12.557] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:12.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:75:0) after 1m0s
[03:56:12.580] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:75:0)
[03:56:12.580] Timer tick, asking Buildbucket for the build status
[03:56:12.662] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:12.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:76:0) after 1m0s
[03:57:12.723] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:76:0)
[03:57:12.723] Timer tick, asking Buildbucket for the build status
[03:57:12.825] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:12.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:77:0) after 1m0s
[03:58:13.045] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:77:0)
[03:58:13.045] Timer tick, asking Buildbucket for the build status
[03:58:13.158] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:13.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:78:0) after 1m0s
[03:59:13.207] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:78:0)
[03:59:13.207] Timer tick, asking Buildbucket for the build status
[03:59:13.254] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:13.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:79:0) after 1m0s
[04:00:13.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:79:0)
[04:00:13.447] Timer tick, asking Buildbucket for the build status
[04:00:14.449] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:14.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:80:0) after 1m0s
[04:01:15.576] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:80:0)
[04:01:15.576] Timer tick, asking Buildbucket for the build status
[04:01:15.662] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:15.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:81:0) after 1m0s
[04:02:15.644] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:81:0)
[04:02:15.644] Timer tick, asking Buildbucket for the build status
[04:02:15.713] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:15.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:82:0) after 1m0s
[04:03:15.715] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:82:0)
[04:03:15.715] Timer tick, asking Buildbucket for the build status
[04:03:15.838] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:15.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:83:0) after 1m0s
[04:04:15.858] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:83:0)
[04:04:15.858] Timer tick, asking Buildbucket for the build status
[04:04:15.926] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:15.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:84:0) after 1m0s
[04:05:16.002] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:84:0)
[04:05:16.002] Timer tick, asking Buildbucket for the build status
[04:05:16.064] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:16.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:85:0) after 1m0s
[04:06:16.163] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:85:0)
[04:06:16.164] Timer tick, asking Buildbucket for the build status
[04:06:16.357] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:16.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:86:0) after 1m0s
[04:07:16.399] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:86:0)
[04:07:16.399] Timer tick, asking Buildbucket for the build status
[04:07:16.490] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:16.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:87:0) after 1m0s
[04:08:16.523] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:87:0)
[04:08:16.523] Timer tick, asking Buildbucket for the build status
[04:08:17.018] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:17.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:88:0) after 1m0s
[04:09:17.088] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:88:0)
[04:09:17.088] Timer tick, asking Buildbucket for the build status
[04:09:17.281] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:17.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:89:0) after 1m0s
[04:10:17.338] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:89:0)
[04:10:17.338] Timer tick, asking Buildbucket for the build status
[04:10:17.420] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:17.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:90:0) after 1m0s
[04:11:17.488] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:90:0)
[04:11:17.489] Timer tick, asking Buildbucket for the build status
[04:11:17.708] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:17.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:91:0) after 1m0s
[04:12:17.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:91:0)
[04:12:17.744] Timer tick, asking Buildbucket for the build status
[04:12:17.969] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:17.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:92:0) after 1m0s
[04:13:18.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:92:0)
[04:13:18.016] Timer tick, asking Buildbucket for the build status
[04:13:18.402] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:18.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:93:0) after 1m0s
[04:14:18.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:93:0)
[04:14:18.407] Timer tick, asking Buildbucket for the build status
[04:14:18.458] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:18.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:94:0) after 1m0s
[04:15:18.542] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:94:0)
[04:15:18.542] Timer tick, asking Buildbucket for the build status
[04:15:18.605] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:18.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:95:0) after 1m0s
[04:16:18.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:95:0)
[04:16:18.665] Timer tick, asking Buildbucket for the build status
[04:16:18.751] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:18.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:96:0) after 1m0s
[04:17:18.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:96:0)
[04:17:18.808] Timer tick, asking Buildbucket for the build status
[04:17:19.148] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:19.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:97:0) after 1m0s
[04:18:19.199] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:97:0)
[04:18:19.199] Timer tick, asking Buildbucket for the build status
[04:18:19.301] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:19.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:98:0) after 1m0s
[04:19:19.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:98:0)
[04:19:19.328] Timer tick, asking Buildbucket for the build status
[04:19:19.385] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:19.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:99:0) after 1m0s
[04:20:19.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:99:0)
[04:20:19.413] Timer tick, asking Buildbucket for the build status
[04:20:19.599] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:19.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:100:0) after 1m0s
[04:21:19.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:100:0)
[04:21:19.726] Timer tick, asking Buildbucket for the build status
[04:21:19.786] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:19.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:101:0) after 1m0s
[04:22:19.838] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:101:0)
[04:22:19.838] Timer tick, asking Buildbucket for the build status
[04:22:19.901] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:19.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:102:0) after 1m0s
[04:23:19.922] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:102:0)
[04:23:19.922] Timer tick, asking Buildbucket for the build status
[04:23:20.007] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:20.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:103:0) after 1m0s
[04:24:20.051] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:103:0)
[04:24:20.051] Timer tick, asking Buildbucket for the build status
[04:24:20.282] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:20.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:104:0) after 1m0s
[04:25:20.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:104:0)
[04:25:20.309] Timer tick, asking Buildbucket for the build status
[04:25:20.588] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:20.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:105:0) after 1m0s
[04:26:20.616] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:105:0)
[04:26:20.616] Timer tick, asking Buildbucket for the build status
[04:26:21.048] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:21.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:106:0) after 1m0s
[04:27:21.163] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:106:0)
[04:27:21.163] Timer tick, asking Buildbucket for the build status
[04:27:21.246] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:21.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:107:0) after 1m0s
[04:28:21.269] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:107:0)
[04:28:21.269] Timer tick, asking Buildbucket for the build status
[04:28:21.371] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:21.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:108:0) after 1m0s
[04:29:21.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:108:0)
[04:29:21.429] Timer tick, asking Buildbucket for the build status
[04:29:21.504] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:21.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:109:0) after 1m0s
[04:30:21.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:109:0)
[04:30:21.572] Timer tick, asking Buildbucket for the build status
[04:30:21.790] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:21.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:110:0) after 1m0s
[04:31:21.847] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:110:0)
[04:31:21.847] Timer tick, asking Buildbucket for the build status
[04:31:21.990] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:21.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:111:0) after 1m0s
[04:32:21.996] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:111:0)
[04:32:21.996] Timer tick, asking Buildbucket for the build status
[04:32:22.067] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:22.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:112:0) after 1m0s
[04:33:22.093] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:112:0)
[04:33:22.093] Timer tick, asking Buildbucket for the build status
[04:33:22.229] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:22.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:113:0) after 1m0s
[04:34:22.279] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:113:0)
[04:34:22.279] Timer tick, asking Buildbucket for the build status
[04:34:22.344] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:22.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:114:0) after 1m0s
[04:35:22.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:114:0)
[04:35:22.499] Timer tick, asking Buildbucket for the build status
[04:35:22.585] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:22.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:115:0) after 1m0s
[04:36:22.736] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:115:0)
[04:36:22.736] Timer tick, asking Buildbucket for the build status
[04:36:23.036] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:23.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:116:0) after 1m0s
[04:37:23.108] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:116:0)
[04:37:23.108] Timer tick, asking Buildbucket for the build status
[04:37:23.312] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:23.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:117:0) after 1m0s
[04:38:23.354] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:117:0)
[04:38:23.354] Timer tick, asking Buildbucket for the build status
[04:38:23.691] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:23.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:118:0) after 1m0s
[04:39:23.816] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:118:0)
[04:39:23.816] Timer tick, asking Buildbucket for the build status
[04:39:24.011] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:24.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:119:0) after 1m0s
[04:40:23.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:119:0)
[04:40:23.985] Timer tick, asking Buildbucket for the build status
[04:40:24.041] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:24.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:120:0) after 1m0s
[04:41:24.101] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:120:0)
[04:41:24.101] Timer tick, asking Buildbucket for the build status
[04:41:24.177] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:24.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:121:0) after 1m0s
[04:42:24.185] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:121:0)
[04:42:24.185] Timer tick, asking Buildbucket for the build status
[04:42:24.293] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:24.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:122:0) after 1m0s
[04:43:24.319] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:122:0)
[04:43:24.319] Timer tick, asking Buildbucket for the build status
[04:43:24.443] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:24.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:123:0) after 1m0s
[04:44:24.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:123:0)
[04:44:24.451] Timer tick, asking Buildbucket for the build status
[04:44:24.512] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:24.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:124:0) after 1m0s
[04:45:24.560] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:124:0)
[04:45:24.560] Timer tick, asking Buildbucket for the build status
[04:45:24.688] Build 8859614967736762656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:24.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025045029726403264:125:0) after 1m0s
[04:45:56.750] Received PubSub notification, asking Buildbucket for the build status
[04:45:56.822] Build 8859614967736762656: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:45:56.822] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1609217155991777",
"created_by": "project:chromium",
"created_ts": "1609209774229429",
"failure_reason": "BUILD_FAILURE",
"id": "8859614967736762656",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a08b1d1689c9f9d8535b27e86b66eb473130d939\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[86498/87919] LINK extensions_unittests.exe extensions_unittests.exe.pdb\\nFAILED: extensions_unittests.exe extensions_unittests.exe.pdb\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}, \"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\": \"a08b1d1689c9f9d8535b27e86b66eb473130d939\", \"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-3-nm62\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1264, \"got_angle_revision\": \"220642a93da1a6e98b6e6c94f1c7767eb267bf58\", \"got_dawn_revision\": \"0eb61724c8f115bc5f26bd9850f14a1dfcae4216\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"a08b1d1689c9f9d8535b27e86b66eb473130d939\", \"got_revision_cp\": \"refs/heads/master@{#839531}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"48107c9aa4283ea8ae68bf18ec6213246135d1fa\", \"got_v8_revision_cp\": \"refs/heads/8.9.194@{#1}\", \"got_webrtc_revision\": \"1f0df96659aeb6447d5d8959c114f3212fc1ca04\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32884}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a08b1d1689c9f9d8535b27e86b66eb473130d939\"}, \"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-3-nm62\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[86498/87919] LINK extensions_unittests.exe extensions_unittests.exe.pdb\\nFAILED: extensions_unittests.exe extensions_unittests.exe.pdb\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609209818303389",
"status": "COMPLETED",
"status_changed_ts": "1609209818624520",
"tags": [
"build_address:luci.chromium.ci/win-official/1264",
"builder:win-official",
"buildset:commit/git/a08b1d1689c9f9d8535b27e86b66eb473130d939",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a08b1d1689c9f9d8535b27e86b66eb473130d939",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025045029726403264",
"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/8859614967736762656/+/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:50c5320aec40db10",
"user_agent:luci-scheduler"
],
"updated_ts": "1609217156211286",
"url": "https://ci.chromium.org/b/8859614967736762656",
"utcnow_ts": "1609217156815034"
}
}
[04:45:56.822] Invocation finished in 2h3m3.873605691s with status FAILED