[03:40:36.883] New invocation is queued and will start shortly
[03:40:37.958] Starting the invocation (attempt 1)
[03:40:37.992] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:40:37.993] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:40:37.993] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017793640230733456",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"75c4a97a2fee876f14002cb34ff917807acb4903\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9017793640230733456",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/75c4a97a2fee876f14002cb34ff917807acb4903",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/75c4a97a2fee876f14002cb34ff917807acb4903",
"gitiles_ref:refs/heads/master"
]
}
[03:40:39.804] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1616125238156424",
"id": "8852363578234081152",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"75c4a97a2fee876f14002cb34ff917807acb4903\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616125239415534",
"tags": [
"build_address:luci.chromium.ci/win-asan/17863",
"builder:win-asan",
"buildset:commit/git/75c4a97a2fee876f14002cb34ff917807acb4903",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/75c4a97a2fee876f14002cb34ff917807acb4903",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017793640230733456",
"scheduler_job_id:chromium/win-asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852363578234081152/+/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": "1616125239415645",
"url": "https://ci.chromium.org/b/8852363578234081152",
"utcnow_ts": "1616125239742157"
}
}
[03:40:39.804] Task URL: https://ci.chromium.org/b/8852363578234081152
[03:40:39.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:2:0) after 1m0s
[03:41:39.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:2:0)
[03:41:39.907] Timer tick, asking Buildbucket for the build status
[03:41:39.992] Build 8852363578234081152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:39.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:3:0) after 1m0s
[03:42:40.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:3:0)
[03:42:40.058] Timer tick, asking Buildbucket for the build status
[03:42:40.129] Build 8852363578234081152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:40.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:4:0) after 1m0s
[03:43:40.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:4:0)
[03:43:40.182] Timer tick, asking Buildbucket for the build status
[03:43:40.282] Build 8852363578234081152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:40.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:5:0) after 1m0s
[03:44:02.299] Received PubSub notification, asking Buildbucket for the build status
[03:44:02.575] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:40.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:5:0)
[03:44:40.286] Timer tick, asking Buildbucket for the build status
[03:44:40.363] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:40.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:7:0) after 1m0s
[03:45:40.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:7:0)
[03:45:40.412] Timer tick, asking Buildbucket for the build status
[03:45:40.511] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:40.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:8:0) after 1m0s
[03:46:40.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:8:0)
[03:46:40.556] Timer tick, asking Buildbucket for the build status
[03:46:40.711] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:40.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:9:0) after 1m0s
[03:47:40.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:9:0)
[03:47:40.710] Timer tick, asking Buildbucket for the build status
[03:47:40.914] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:40.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:10:0) after 1m0s
[03:48:41.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:10:0)
[03:48:41.000] Timer tick, asking Buildbucket for the build status
[03:48:41.086] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:41.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:11:0) after 1m0s
[03:49:41.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:11:0)
[03:49:41.105] Timer tick, asking Buildbucket for the build status
[03:49:41.176] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:41.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:12:0) after 1m0s
[03:50:41.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:12:0)
[03:50:41.304] Timer tick, asking Buildbucket for the build status
[03:50:41.393] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:41.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:13:0) after 1m0s
[03:51:41.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:13:0)
[03:51:41.478] Timer tick, asking Buildbucket for the build status
[03:51:41.556] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:41.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:14:0) after 1m0s
[03:52:41.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:14:0)
[03:52:41.586] Timer tick, asking Buildbucket for the build status
[03:52:41.822] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:41.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:15:0) after 1m0s
[03:53:41.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:15:0)
[03:53:41.943] Timer tick, asking Buildbucket for the build status
[03:53:42.391] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:42.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:16:0) after 1m0s
[03:54:43.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:16:0)
[03:54:43.876] Timer tick, asking Buildbucket for the build status
[03:54:44.112] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:44.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:17:0) after 1m0s
[03:55:44.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:17:0)
[03:55:44.185] Timer tick, asking Buildbucket for the build status
[03:55:44.262] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:44.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:18:0) after 1m0s
[03:56:44.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:18:0)
[03:56:44.297] Timer tick, asking Buildbucket for the build status
[03:56:44.526] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:44.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:19:0) after 1m0s
[03:57:44.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:19:0)
[03:57:44.594] Timer tick, asking Buildbucket for the build status
[03:57:44.815] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:44.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:20:0) after 1m0s
[03:58:44.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:20:0)
[03:58:44.998] Timer tick, asking Buildbucket for the build status
[03:58:45.089] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:45.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:21:0) after 1m0s
[03:59:45.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:21:0)
[03:59:45.363] Timer tick, asking Buildbucket for the build status
[03:59:45.460] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:45.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:22:0) after 1m0s
[04:00:45.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:22:0)
[04:00:45.607] Timer tick, asking Buildbucket for the build status
[04:00:45.807] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:45.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:23:0) after 1m0s
[04:01:45.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:23:0)
[04:01:45.944] Timer tick, asking Buildbucket for the build status
[04:01:46.010] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:46.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:24:0) after 1m0s
[04:02:46.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:24:0)
[04:02:46.104] Timer tick, asking Buildbucket for the build status
[04:02:46.196] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:46.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:25:0) after 1m0s
[04:03:46.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:25:0)
[04:03:46.411] Timer tick, asking Buildbucket for the build status
[04:03:46.521] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:46.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:26:0) after 1m0s
[04:04:46.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:26:0)
[04:04:46.535] Timer tick, asking Buildbucket for the build status
[04:04:46.800] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:46.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:27:0) after 1m0s
[04:05:46.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:27:0)
[04:05:46.877] Timer tick, asking Buildbucket for the build status
[04:05:46.970] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:46.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:28:0) after 1m0s
[04:06:47.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:28:0)
[04:06:47.035] Timer tick, asking Buildbucket for the build status
[04:06:47.113] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:47.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:29:0) after 1m0s
[04:07:47.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:29:0)
[04:07:47.097] Timer tick, asking Buildbucket for the build status
[04:07:47.166] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:47.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:30:0) after 1m0s
[04:08:47.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:30:0)
[04:08:47.242] Timer tick, asking Buildbucket for the build status
[04:08:47.438] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:47.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:31:0) after 1m0s
[04:09:47.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:31:0)
[04:09:47.476] Timer tick, asking Buildbucket for the build status
[04:09:47.538] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:47.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:32:0) after 1m0s
[04:10:47.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:32:0)
[04:10:47.591] Timer tick, asking Buildbucket for the build status
[04:10:47.752] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:47.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:33:0) after 1m0s
[04:11:47.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:33:0)
[04:11:47.770] Timer tick, asking Buildbucket for the build status
[04:11:47.834] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:47.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:34:0) after 1m0s
[04:12:47.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:34:0)
[04:12:47.893] Timer tick, asking Buildbucket for the build status
[04:12:48.038] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:48.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:35:0) after 1m0s
[04:13:48.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:35:0)
[04:13:48.045] Timer tick, asking Buildbucket for the build status
[04:13:48.160] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:48.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:36:0) after 1m0s
[04:14:48.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:36:0)
[04:14:48.221] Timer tick, asking Buildbucket for the build status
[04:14:48.306] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:48.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:37:0) after 1m0s
[04:15:48.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:37:0)
[04:15:48.323] Timer tick, asking Buildbucket for the build status
[04:15:48.413] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:48.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:38:0) after 1m0s
[04:16:48.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:38:0)
[04:16:48.451] Timer tick, asking Buildbucket for the build status
[04:16:48.646] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:48.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:39:0) after 1m0s
[04:17:48.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:39:0)
[04:17:48.681] Timer tick, asking Buildbucket for the build status
[04:17:48.922] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:48.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:40:0) after 1m0s
[04:18:48.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:40:0)
[04:18:48.928] Timer tick, asking Buildbucket for the build status
[04:18:49.010] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:49.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:41:0) after 1m0s
[04:19:49.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:41:0)
[04:19:49.006] Timer tick, asking Buildbucket for the build status
[04:19:49.075] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:49.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:42:0) after 1m0s
[04:20:49.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:42:0)
[04:20:49.098] Timer tick, asking Buildbucket for the build status
[04:20:49.355] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:49.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:43:0) after 1m0s
[04:21:49.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:43:0)
[04:21:49.423] Timer tick, asking Buildbucket for the build status
[04:21:49.493] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:49.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:44:0) after 1m0s
[04:22:49.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:44:0)
[04:22:49.616] Timer tick, asking Buildbucket for the build status
[04:22:49.719] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:49.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:45:0) after 1m0s
[04:23:49.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:45:0)
[04:23:49.791] Timer tick, asking Buildbucket for the build status
[04:23:49.912] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:49.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:46:0) after 1m0s
[04:24:49.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:46:0)
[04:24:49.899] Timer tick, asking Buildbucket for the build status
[04:24:49.972] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:49.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:47:0) after 1m0s
[04:25:49.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:47:0)
[04:25:49.979] Timer tick, asking Buildbucket for the build status
[04:25:50.215] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:50.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:48:0) after 1m0s
[04:26:50.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:48:0)
[04:26:50.258] Timer tick, asking Buildbucket for the build status
[04:26:50.494] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:50.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:49:0) after 1m0s
[04:27:50.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:49:0)
[04:27:50.509] Timer tick, asking Buildbucket for the build status
[04:27:50.576] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:50.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:50:0) after 1m0s
[04:28:50.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:50:0)
[04:28:50.624] Timer tick, asking Buildbucket for the build status
[04:28:50.689] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:50.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:51:0) after 1m0s
[04:29:50.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:51:0)
[04:29:50.718] Timer tick, asking Buildbucket for the build status
[04:29:50.782] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:50.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:52:0) after 1m0s
[04:30:50.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:52:0)
[04:30:50.892] Timer tick, asking Buildbucket for the build status
[04:30:51.031] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:51.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:53:0) after 1m0s
[04:31:51.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:53:0)
[04:31:51.063] Timer tick, asking Buildbucket for the build status
[04:31:51.121] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:51.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:54:0) after 1m0s
[04:32:51.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:54:0)
[04:32:51.207] Timer tick, asking Buildbucket for the build status
[04:32:51.402] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:51.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:55:0) after 1m0s
[04:33:51.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:55:0)
[04:33:51.423] Timer tick, asking Buildbucket for the build status
[04:33:51.489] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:51.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:56:0) after 1m0s
[04:34:51.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:56:0)
[04:34:51.514] Timer tick, asking Buildbucket for the build status
[04:34:51.583] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:51.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:57:0) after 1m0s
[04:35:51.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:57:0)
[04:35:51.603] Timer tick, asking Buildbucket for the build status
[04:35:51.709] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:51.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:58:0) after 1m0s
[04:36:51.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:58:0)
[04:36:51.682] Timer tick, asking Buildbucket for the build status
[04:36:51.963] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:51.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:59:0) after 1m0s
[04:37:52.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:59:0)
[04:37:52.038] Timer tick, asking Buildbucket for the build status
[04:38:00.288] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:00.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:60:0) after 1m0s
[04:39:00.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:60:0)
[04:39:00.317] Timer tick, asking Buildbucket for the build status
[04:39:00.369] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:00.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:61:0) after 1m0s
[04:40:00.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:61:0)
[04:40:00.414] Timer tick, asking Buildbucket for the build status
[04:40:00.482] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:00.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:62:0) after 1m0s
[04:41:00.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:62:0)
[04:41:00.522] Timer tick, asking Buildbucket for the build status
[04:41:00.606] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:00.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:63:0) after 1m0s
[04:42:00.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:63:0)
[04:42:00.720] Timer tick, asking Buildbucket for the build status
[04:42:01.092] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:01.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:64:0) after 1m0s
[04:43:01.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:64:0)
[04:43:01.200] Timer tick, asking Buildbucket for the build status
[04:43:01.262] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:01.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:65:0) after 1m0s
[04:44:01.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:65:0)
[04:44:01.271] Timer tick, asking Buildbucket for the build status
[04:44:01.505] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:01.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:66:0) after 1m0s
[04:45:01.543] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:66:0)
[04:45:01.543] Timer tick, asking Buildbucket for the build status
[04:45:01.606] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:01.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:67:0) after 1m0s
[04:46:01.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:67:0)
[04:46:01.614] Timer tick, asking Buildbucket for the build status
[04:46:01.873] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:01.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:68:0) after 1m0s
[04:47:01.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:68:0)
[04:47:01.875] Timer tick, asking Buildbucket for the build status
[04:47:02.091] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:02.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:69:0) after 1m0s
[04:48:02.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:69:0)
[04:48:02.125] Timer tick, asking Buildbucket for the build status
[04:48:02.452] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:02.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:70:0) after 1m0s
[04:49:02.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:70:0)
[04:49:02.505] Timer tick, asking Buildbucket for the build status
[04:49:02.737] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:02.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:71:0) after 1m0s
[04:50:02.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:71:0)
[04:50:02.769] Timer tick, asking Buildbucket for the build status
[04:50:02.832] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:02.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:72:0) after 1m0s
[04:51:02.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:72:0)
[04:51:02.855] Timer tick, asking Buildbucket for the build status
[04:51:03.046] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:03.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:73:0) after 1m0s
[04:52:03.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:73:0)
[04:52:03.048] Timer tick, asking Buildbucket for the build status
[04:52:03.129] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:03.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:74:0) after 1m0s
[04:53:03.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:74:0)
[04:53:03.152] Timer tick, asking Buildbucket for the build status
[04:53:03.286] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:03.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:75:0) after 1m0s
[04:54:03.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:75:0)
[04:54:03.338] Timer tick, asking Buildbucket for the build status
[04:54:03.424] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:03.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:76:0) after 1m0s
[04:55:03.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:76:0)
[04:55:03.515] Timer tick, asking Buildbucket for the build status
[04:55:03.700] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:03.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:77:0) after 1m0s
[04:56:03.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:77:0)
[04:56:03.744] Timer tick, asking Buildbucket for the build status
[04:56:03.829] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:03.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:78:0) after 1m0s
[04:57:03.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:78:0)
[04:57:03.873] Timer tick, asking Buildbucket for the build status
[04:57:04.073] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:04.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:79:0) after 1m0s
[04:58:04.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:79:0)
[04:58:04.108] Timer tick, asking Buildbucket for the build status
[04:58:04.306] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:04.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:80:0) after 1m0s
[04:59:04.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:80:0)
[04:59:04.324] Timer tick, asking Buildbucket for the build status
[04:59:04.631] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:04.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:81:0) after 1m0s
[05:00:04.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:81:0)
[05:00:04.731] Timer tick, asking Buildbucket for the build status
[05:00:04.827] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:04.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:82:0) after 1m0s
[05:01:05.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:82:0)
[05:01:05.829] Timer tick, asking Buildbucket for the build status
[05:01:05.929] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:05.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:83:0) after 1m0s
[05:02:06.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:83:0)
[05:02:06.043] Timer tick, asking Buildbucket for the build status
[05:02:11.817] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:11.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:84:0) after 1m0s
[05:03:11.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:84:0)
[05:03:11.944] Timer tick, asking Buildbucket for the build status
[05:03:12.038] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:12.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:85:0) after 1m0s
[05:04:12.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:85:0)
[05:04:12.065] Timer tick, asking Buildbucket for the build status
[05:04:12.488] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:12.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:86:0) after 1m0s
[05:05:12.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:86:0)
[05:05:12.537] Timer tick, asking Buildbucket for the build status
[05:05:12.661] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:12.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:87:0) after 1m0s
[05:06:12.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:87:0)
[05:06:12.738] Timer tick, asking Buildbucket for the build status
[05:06:12.920] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:12.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:88:0) after 1m0s
[05:07:12.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:88:0)
[05:07:12.976] Timer tick, asking Buildbucket for the build status
[05:07:13.115] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:13.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:89:0) after 1m0s
[05:08:13.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:89:0)
[05:08:13.203] Timer tick, asking Buildbucket for the build status
[05:08:13.279] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:13.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:90:0) after 1m0s
[05:09:13.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:90:0)
[05:09:13.475] Timer tick, asking Buildbucket for the build status
[05:09:13.558] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:13.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:91:0) after 1m0s
[05:10:13.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:91:0)
[05:10:13.627] Timer tick, asking Buildbucket for the build status
[05:10:13.891] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:13.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:92:0) after 1m0s
[05:11:13.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:92:0)
[05:11:13.972] Timer tick, asking Buildbucket for the build status
[05:11:14.246] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:14.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:93:0) after 1m0s
[05:12:14.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:93:0)
[05:12:14.286] Timer tick, asking Buildbucket for the build status
[05:12:14.540] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:14.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:94:0) after 1m0s
[05:13:14.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:94:0)
[05:13:14.587] Timer tick, asking Buildbucket for the build status
[05:13:14.935] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:14.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:95:0) after 1m0s
[05:14:14.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:95:0)
[05:14:14.953] Timer tick, asking Buildbucket for the build status
[05:14:15.208] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:15.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:96:0) after 1m0s
[05:15:15.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:96:0)
[05:15:15.234] Timer tick, asking Buildbucket for the build status
[05:15:15.491] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:15.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:97:0) after 1m0s
[05:16:15.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:97:0)
[05:16:15.530] Timer tick, asking Buildbucket for the build status
[05:16:16.058] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:16.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:98:0) after 1m0s
[05:17:16.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:98:0)
[05:17:16.079] Timer tick, asking Buildbucket for the build status
[05:17:16.158] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:16.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:99:0) after 1m0s
[05:18:16.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:99:0)
[05:18:16.182] Timer tick, asking Buildbucket for the build status
[05:18:16.280] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:16.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:100:0) after 1m0s
[05:19:16.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:100:0)
[05:19:16.295] Timer tick, asking Buildbucket for the build status
[05:19:16.379] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:16.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:101:0) after 1m0s
[05:20:16.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:101:0)
[05:20:16.513] Timer tick, asking Buildbucket for the build status
[05:20:16.654] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:16.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:102:0) after 1m0s
[05:21:16.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:102:0)
[05:21:16.738] Timer tick, asking Buildbucket for the build status
[05:21:16.888] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:16.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:103:0) after 1m0s
[05:22:16.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:103:0)
[05:22:16.914] Timer tick, asking Buildbucket for the build status
[05:22:17.439] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:17.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:104:0) after 1m0s
[05:23:17.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:104:0)
[05:23:17.481] Timer tick, asking Buildbucket for the build status
[05:23:17.608] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:17.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:105:0) after 1m0s
[05:24:17.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:105:0)
[05:24:17.711] Timer tick, asking Buildbucket for the build status
[05:24:17.810] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:17.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:106:0) after 1m0s
[05:25:17.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:106:0)
[05:25:17.842] Timer tick, asking Buildbucket for the build status
[05:25:17.926] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:17.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:107:0) after 1m0s
[05:26:17.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:107:0)
[05:26:17.969] Timer tick, asking Buildbucket for the build status
[05:26:18.533] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:18.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:108:0) after 1m0s
[05:27:18.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:108:0)
[05:27:18.615] Timer tick, asking Buildbucket for the build status
[05:27:18.882] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:18.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:109:0) after 1m0s
[05:28:18.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:109:0)
[05:28:18.907] Timer tick, asking Buildbucket for the build status
[05:28:19.172] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:19.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:110:0) after 1m0s
[05:29:19.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:110:0)
[05:29:19.221] Timer tick, asking Buildbucket for the build status
[05:29:19.313] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:19.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:111:0) after 1m0s
[05:30:19.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:111:0)
[05:30:19.453] Timer tick, asking Buildbucket for the build status
[05:30:20.108] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:20.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:112:0) after 1m0s
[05:31:20.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:112:0)
[05:31:20.143] Timer tick, asking Buildbucket for the build status
[05:31:20.239] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:20.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:113:0) after 1m0s
[05:32:20.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:113:0)
[05:32:20.272] Timer tick, asking Buildbucket for the build status
[05:32:20.411] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:20.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:114:0) after 1m0s
[05:33:20.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:114:0)
[05:33:20.426] Timer tick, asking Buildbucket for the build status
[05:33:20.495] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:20.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:115:0) after 1m0s
[05:34:20.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:115:0)
[05:34:20.575] Timer tick, asking Buildbucket for the build status
[05:34:20.658] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:20.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:116:0) after 1m0s
[05:35:20.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:116:0)
[05:35:20.685] Timer tick, asking Buildbucket for the build status
[05:35:20.765] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:20.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:117:0) after 1m0s
[05:36:20.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:117:0)
[05:36:20.837] Timer tick, asking Buildbucket for the build status
[05:36:21.219] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:21.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:118:0) after 1m0s
[05:37:21.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:118:0)
[05:37:21.230] Timer tick, asking Buildbucket for the build status
[05:37:21.338] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:21.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:119:0) after 1m0s
[05:38:21.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:119:0)
[05:38:21.363] Timer tick, asking Buildbucket for the build status
[05:38:21.521] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:21.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:120:0) after 1m0s
[05:39:21.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:120:0)
[05:39:21.505] Timer tick, asking Buildbucket for the build status
[05:39:21.779] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:21.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:121:0) after 1m0s
[05:40:21.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:121:0)
[05:40:21.871] Timer tick, asking Buildbucket for the build status
[05:40:22.265] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:22.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:122:0) after 1m0s
[05:41:22.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:122:0)
[05:41:22.397] Timer tick, asking Buildbucket for the build status
[05:41:22.554] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:22.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:123:0) after 1m0s
[05:42:22.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:123:0)
[05:42:22.628] Timer tick, asking Buildbucket for the build status
[05:42:22.878] Build 8852363578234081152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:22.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017793640230733456:124:0) after 1m0s
[05:42:44.021] Received PubSub notification, asking Buildbucket for the build status
[05:42:44.096] Build 8852363578234081152: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:42:44.096] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616132562581172",
"created_by": "project:chromium",
"created_ts": "1616125238156424",
"id": "8852363578234081152",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"75c4a97a2fee876f14002cb34ff917807acb4903\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"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\": \"75c4a97a2fee876f14002cb34ff917807acb4903\", \"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\": \"luci-chromium-ci-win10-32-6-xj00\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17863, \"clang_revision\": \"llvmorg-13-init-4720-g7bafe336-1\", \"got_angle_revision\": \"b717952ee77647ff7fc2fa31a1b9dfc344228dca\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"75c4a97a2fee876f14002cb34ff917807acb4903\", \"got_revision_cp\": \"refs/heads/master@{#864552}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"43315277e45e866e927722377d36b04ca3c1014b\", \"got_v8_revision_cp\": \"refs/heads/9.1.158@{#1}\", \"got_webrtc_revision\": \"92a768ad66f54ca57002f6f2e87c7a732e3fec39\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33488}\", \"is_cached\": true, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"75c4a97a2fee876f14002cb34ff917807acb4903\", \"swarm_hashes_refs/heads/master(at){#864552}_without_patch\": {\"absl_hardening_tests\": \"6656101804efd55c28e6a3db22296f22e79f636c\", \"accessibility_unittests\": \"a410df49c1496331cdc2dd8bc85f7be8ce41861f\", \"angle_unittests\": \"9371940501bd5c2626716549254bacdc79118c9e\", \"app_shell_unittests\": \"9cc8f011617b1e94bb6460fafc053dbb5c4c0c99\", \"aura_unittests\": \"91590ac42117a746c0314a113d5c18a14b6f34ee\", \"base_unittests\": \"31183f58190012c2d2fa426217dd7f32fa3fb1fa\", \"base_util_unittests\": \"7a71c447b01fbb4275a3bcf71d58adfb09aafd4a\", \"blink_common_unittests\": \"13d598000af0195bcbb4b14a86b35c33872348a8\", \"blink_fuzzer_unittests\": \"d64a67116a11842637acb50b6c4588912c12afa2\", \"blink_heap_unittests\": \"ce9eae1d66174b47fc602e0fdba1a16739120086\", \"blink_platform_unittests\": \"bf9d4d519715f3ea750646c5160a2272f578d0f4\", \"blink_unittests\": \"084488817e0657f9bc9e4397ec5eb8a83c8b1c7e\", \"boringssl_crypto_tests\": \"e374d911c7c9aad1e0d310d0ac8dde1df7d2f848\", \"boringssl_ssl_tests\": \"b756d29609f1594ea8ecfda19eead9c8540670dc\", \"browser_switcher_bho_unittests\": \"704c52c473255d6abc1b5271473eb96e32299e2d\", \"browser_tests\": \"671e671ae089e6ac9eee4de15bd77115b267569a\", \"capture_unittests\": \"c2a167ee78385197c5772e874f46aeecee3644cf\", \"cast_unittests\": \"d8565fd207ed66a5dfb9a1c8a065c18f55cf90f4\", \"cc_unittests\": \"451f2140edcd985960d27a8bde3fc76528c4f606\", \"chrome_app_unittests\": \"397b704525d697d1e312f5bcf13b3d1a36c29f7c\", \"chrome_cleaner_unittests\": \"561e57a796105173a28bccc517e709468a15fdfc\", \"chrome_elf_unittests\": \"2b4fbf624e87fca3d92fdefd48c8f013d6615fa3\", \"chromedriver_unittests\": \"2ef99c08777dc9f0d39ad1d5ebc1f3ffc40bafe1\", \"color_unittests\": \"0a3e85cfb3f7075aa83e1e39a2c2a89fbe10b68b\", \"components_browsertests\": \"ece7b696743f511462d31e3a139645fa3b6b8796\", \"components_unittests\": \"ac99e38dd23b3bcecdd54e1f0112da38b3e4b8be\", \"compositor_unittests\": \"6d506456ce82171fd5ebe7eac50b3c3c2318dda7\", \"content_browsertests\": \"f989b89877bea8d6c9fb501e52aa990b0deb481f\", \"content_unittests\": \"22c68810409ec8a07ba3be101b39862c51892dd4\", \"courgette_unittests\": \"4ba17b6d553b60e74a04cbc06caec7a50a3c9586\", \"crashpad_tests\": \"148b2ae7bb46b709ecbd72e416ab20c63a12c272\", \"cronet_tests\": \"6d747bdd825d4098c10606f1f65f35e70396c295\", \"cronet_unittests\": \"28c1180892dbba5a1e456c926ca25559bee3f953\", \"crypto_unittests\": \"00e48d38a991fe778ae9617b12d9e14c73fdc1f2\", \"delayloads_unittests\": \"3147fb20ce7688f753984b9513771671927b768b\", \"device_unittests\": \"4b45421171c3d6c5cf78890d704d46d485114053\", \"display_unittests\": \"2ca58ed287edeeaeaec95bf602467b306e0b7a04\", \"elevation_service_unittests\": \"e2c1726c3fab2e0c1eb7e8920dee1a5b48aeec3d\", \"events_unittests\": \"63721cade2ead5aab509bae54c24d13cd1cf5080\", \"extensions_browsertests\": \"bbcec001eccfc071b2e2237dbbe7afdf68050e94\", \"extensions_unittests\": \"ad0c9db5fbbba675786a57755cec27d2c877afc2\", \"filesystem_service_unittests\": \"23ea759d9f038dfe029ae428e88c765664cb6f05\", \"gcm_unit_tests\": \"77990447328a9bbf0f16fdc701697841a1bde2ae\", \"gcp_unittests\": \"9418988c5a90468b47bca90b7285055cfb60616d\", \"gfx_unittests\": \"d6fb4ba1e9e5c93c74b22ea6b42b47ea6cda491b\", \"gin_unittests\": \"5f04c5d7e6c28a06952d76bfaa96ea16bf253f17\", \"google_apis_unittests\": \"773b5676727ce08fc210f113570e7c96e491a4d8\", \"gpu_unittests\": \"73a354c595b41c662a75ccb7326a2d5ec15fb02d\", \"gwp_asan_unittests\": \"79c01d4d84f2cb2d19d09d3140421517088fa409\", \"headless_browsertests\": \"af6a19a92fc4bc5f5835cd4278c05b45f73a0cb4\", \"headless_unittests\": \"565da843b9a946dfe2586a9d910f677bc057c642\", \"install_static_unittests\": \"49ff86b08681f6e462394c080138054da30c2377\", \"installer_util_unittests\": \"b38939cb54962e77bb11a3739aa44c083cbd4796\", \"interactive_ui_tests\": \"9b7163a82601f60b77e27f16b40c7f147d55cca3\", \"ipc_tests\": \"3a0c03437150a783622ffca2b3800449cd6158e5\", \"jingle_unittests\": \"964703645a912f94b6a1a9673af0e4f293520db8\", \"latency_unittests\": \"3f48a9f5c88121903df6b030c32433b62f240605\", \"libjingle_xmpp_unittests\": \"4e747560bc418e5a992f14f0b0ae9dead120d5ed\", \"liburlpattern_unittests\": \"7c86ba950e340ee818d154cbf339b93cffcde772\", \"media_blink_unittests\": \"ed88d11037534f87a5cb23a518517f08a20d8075\", \"media_unittests\": \"d91b82c6cfbdc2147cc3f1bac6bd4ac662da50ae\", \"message_center_unittests\": \"7560b739a2772acc4086175367c22588363f6509\", \"midi_unittests\": \"ee06ab5207811042b4c11439cee9905559841a62\", \"mojo_core_unittests\": \"dc3cffc684bfc98cdd1e51ebc7f32ecadef9bb12\", \"mojo_unittests\": \"8cd178780f07b06218fcd14e07de756cc6c59757\", \"nacl_loader_unittests\": \"2d36e6cbc74d441d5b7a23e451b24bdebd44b559\", \"native_theme_unittests\": \"d6e6c34f47198aab7efcb0ea0d2f48620b4c9c86\", \"net_unittests\": \"899741a68433f7bb4388f84912e0069ce972be14\", \"notification_helper_unittests\": \"ae16d0d47c8cbe18f8c5d658f45da7e4d2724467\", \"pdf_unittests\": \"497594c0f1677304d059e4454af7036cdb6361ed\", \"perfetto_unittests\": \"7891b64fe3a3a16fb5e79b13c6fd4dd2da34a2b3\", \"ppapi_unittests\": \"91ade1a06ef94fe78f7e59cc93abdaddc886e3d7\", \"printing_unittests\": \"a56e852eb57860b2a7a8f2c00c77cc9fe258b482\", \"remoting_unittests\": \"2877882b781f882ec561b32122081e86ce5a0203\", \"sbox_integration_tests\": \"63e79d034a77a954e8ce1fc3f9858d09155094ec\", \"sbox_unittests\": \"69f824c0019318553f82a8c7c23b36ae82a28182\", \"sbox_validation_tests\": \"722fa6bd48fd7ad9a573cd24615ef03d60d5b914\", \"service_manager_unittests\": \"411ca58cf21c9530dabbd5e17ded312494fb3d12\", \"services_unittests\": \"b9cb2febbe7e05a2f128c48ad210bd43c75c10f7\", \"setup_unittests\": \"d41126ffe61424fbdeccde03fdb4154692a8457e\", \"shell_dialogs_unittests\": \"f14013af9ec36d26f55c84ce6c775a2de425fe7f\", \"skia_unittests\": \"3172e6d35da78eb726a939009eac30839e6ff8b4\", \"snapshot_unittests\": \"44da5067de6e276239b470c011de488a58977f52\", \"sql_unittests\": \"df7fdf59effd1cf4226b08cfcf47741eb32bfa93\", \"storage_unittests\": \"b608754fbe4a12124d6bfc6f0fbb65bf585fed1e\", \"sync_integration_tests\": \"b8742afeea427749ec63f8a0982ac067a4c8e312\", \"traffic_annotation_auditor_unittests\": \"44fc95a0fbf9b75a817f5f12107cbf08addc0c0c\", \"ui_base_unittests\": \"b254376e7b8918ea5757f36650560b8a45ed5c57\", \"ui_touch_selection_unittests\": \"0679002f04ba644484fe01cb2690903df2eb9b89\", \"unit_tests\": \"69cbcf24654db884cc9cba0e97dc85ec957c09a5\", \"updater_tests\": \"2f6a8d87257100cffe9f3db06f71de85e66eee91\", \"url_unittests\": \"54a76524aa2091a99a3ecc8649f18834b2c945a1\", \"views_unittests\": \"667c2b775da4955e384d7fd2c5eef3d0427f585c\", \"viz_unittests\": \"c65250f90ee052c05cc4d10b12a1814e24a778e4\", \"vr_common_unittests\": \"9271466fd7fe964bcf5d3e434e3ec0fd8067d7d8\", \"vr_pixeltests\": \"6293eb2da2b8ea7d4cdfd3c7544b68932ebe22ec\", \"weblayer_browsertests\": \"37719c70814846b4e19af59f11dfc7b9cfd0c66a\", \"weblayer_unittests\": \"e6832414b010cea06f91fdfb58456d456c07e131\", \"wm_unittests\": \"387f38bd65a7dde74fe262aa5706d7677d40147d\", \"wtf_unittests\": \"30ff2737acea2c06352b50161f4855ae120468b6\", \"zlib_unittests\": \"5d5e6da781646a455943022473a0be2b0f92e342\", \"zucchini_unittests\": \"6b50616c2b28863bb29be725e383cdb4c550ec32\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-xj00\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2\", \"2.7\", \"2.7.13\"], \"server_version\": [\"5776-cb9e157\"], \"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": "1616125441110097",
"status": "COMPLETED",
"status_changed_ts": "1616132562914156",
"tags": [
"build_address:luci.chromium.ci/win-asan/17863",
"builder:win-asan",
"buildset:commit/git/75c4a97a2fee876f14002cb34ff917807acb4903",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/75c4a97a2fee876f14002cb34ff917807acb4903",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017793640230733456",
"scheduler_job_id:chromium/win-asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852363578234081152/+/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:526163a9f644f110",
"user_agent:luci-scheduler"
],
"updated_ts": "1616132562966122",
"url": "https://ci.chromium.org/b/8852363578234081152",
"utcnow_ts": "1616132564087011"
}
}
[05:42:44.096] Invocation finished in 2h2m7.228538709s with status SUCCEEDED