[04:09:36.360] New invocation is queued and will start shortly
[04:09:37.468] Starting the invocation (attempt 1)
[04:09:37.506] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:09:37.506] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:09:37.506] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025311364470616160",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"183589d6102c9b5bda7b6c970babd889bb99aeee\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025311364470616160",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/183589d6102c9b5bda7b6c970babd889bb99aeee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/183589d6102c9b5bda7b6c970babd889bb99aeee",
"gitiles_ref:refs/heads/master"
]
}
[04:09:38.064] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608955777585889",
"id": "8859881302521370544",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"183589d6102c9b5bda7b6c970babd889bb99aeee\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608955777946101",
"tags": [
"build_address:luci.chromium.ci/win-asan/16455",
"builder:win-asan",
"buildset:commit/git/183589d6102c9b5bda7b6c970babd889bb99aeee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/183589d6102c9b5bda7b6c970babd889bb99aeee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025311364470616160",
"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/8859881302521370544/+/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": "1608955777946210",
"url": "https://ci.chromium.org/b/8859881302521370544",
"utcnow_ts": "1608955778056282"
}
}
[04:09:38.064] Task URL: https://ci.chromium.org/b/8859881302521370544
[04:09:38.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:2:0) after 1m0s
[04:10:38.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:2:0)
[04:10:38.827] Timer tick, asking Buildbucket for the build status
[04:10:39.124] Build 8859881302521370544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:39.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:3:0) after 1m0s
[04:11:39.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:3:0)
[04:11:39.148] Timer tick, asking Buildbucket for the build status
[04:11:39.212] Build 8859881302521370544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:39.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:4:0) after 1m0s
[04:12:39.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:4:0)
[04:12:39.722] Timer tick, asking Buildbucket for the build status
[04:12:40.085] Build 8859881302521370544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:40.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:5:0) after 1m0s
[04:13:40.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:5:0)
[04:13:40.581] Timer tick, asking Buildbucket for the build status
[04:13:40.711] Build 8859881302521370544: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:13:40.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:6:0) after 1m0s
[04:14:14.628] Received PubSub notification, asking Buildbucket for the build status
[04:14:14.801] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:40.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:6:0)
[04:14:40.732] Timer tick, asking Buildbucket for the build status
[04:14:40.817] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:40.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:8:0) after 1m0s
[04:15:40.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:8:0)
[04:15:40.836] Timer tick, asking Buildbucket for the build status
[04:15:40.930] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:40.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:9:0) after 1m0s
[04:16:40.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:9:0)
[04:16:40.947] Timer tick, asking Buildbucket for the build status
[04:16:41.501] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:41.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:10:0) after 1m0s
[04:17:41.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:10:0)
[04:17:41.512] Timer tick, asking Buildbucket for the build status
[04:17:41.593] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:41.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:11:0) after 1m0s
[04:18:41.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:11:0)
[04:18:41.613] Timer tick, asking Buildbucket for the build status
[04:18:41.666] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:41.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:12:0) after 1m0s
[04:19:41.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:12:0)
[04:19:41.686] Timer tick, asking Buildbucket for the build status
[04:19:42.294] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:42.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:13:0) after 1m0s
[04:20:42.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:13:0)
[04:20:42.314] Timer tick, asking Buildbucket for the build status
[04:20:42.366] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:42.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:14:0) after 1m0s
[04:21:42.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:14:0)
[04:21:42.391] Timer tick, asking Buildbucket for the build status
[04:21:42.450] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:42.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:15:0) after 1m0s
[04:22:42.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:15:0)
[04:22:42.470] Timer tick, asking Buildbucket for the build status
[04:22:42.832] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:42.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:16:0) after 1m0s
[04:23:42.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:16:0)
[04:23:42.942] Timer tick, asking Buildbucket for the build status
[04:23:42.992] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:42.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:17:0) after 1m0s
[04:24:43.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:17:0)
[04:24:43.135] Timer tick, asking Buildbucket for the build status
[04:24:43.226] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:43.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:18:0) after 1m0s
[04:25:43.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:18:0)
[04:25:43.304] Timer tick, asking Buildbucket for the build status
[04:25:43.358] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:43.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:19:0) after 1m0s
[04:26:43.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:19:0)
[04:26:43.430] Timer tick, asking Buildbucket for the build status
[04:26:43.505] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:43.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:20:0) after 1m0s
[04:27:43.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:20:0)
[04:27:43.576] Timer tick, asking Buildbucket for the build status
[04:27:43.634] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:43.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:21:0) after 1m0s
[04:28:43.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:21:0)
[04:28:43.654] Timer tick, asking Buildbucket for the build status
[04:28:44.027] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:44.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:22:0) after 1m0s
[04:29:44.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:22:0)
[04:29:44.031] Timer tick, asking Buildbucket for the build status
[04:29:44.272] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:44.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:23:0) after 1m0s
[04:30:44.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:23:0)
[04:30:44.434] Timer tick, asking Buildbucket for the build status
[04:30:44.576] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:44.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:24:0) after 1m0s
[04:31:44.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:24:0)
[04:31:44.600] Timer tick, asking Buildbucket for the build status
[04:31:44.649] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:44.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:25:0) after 1m0s
[04:32:44.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:25:0)
[04:32:44.641] Timer tick, asking Buildbucket for the build status
[04:32:44.708] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:44.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:26:0) after 1m0s
[04:33:44.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:26:0)
[04:33:44.726] Timer tick, asking Buildbucket for the build status
[04:33:44.791] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:44.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:27:0) after 1m0s
[04:34:44.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:27:0)
[04:34:44.848] Timer tick, asking Buildbucket for the build status
[04:34:44.942] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:44.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:28:0) after 1m0s
[04:35:44.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:28:0)
[04:35:44.970] Timer tick, asking Buildbucket for the build status
[04:35:45.035] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:45.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:29:0) after 1m0s
[04:36:45.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:29:0)
[04:36:45.076] Timer tick, asking Buildbucket for the build status
[04:36:45.227] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:45.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:30:0) after 1m0s
[04:37:45.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:30:0)
[04:37:45.360] Timer tick, asking Buildbucket for the build status
[04:37:45.438] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:45.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:31:0) after 1m0s
[04:38:45.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:31:0)
[04:38:45.420] Timer tick, asking Buildbucket for the build status
[04:38:45.483] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:45.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:32:0) after 1m0s
[04:39:45.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:32:0)
[04:39:45.469] Timer tick, asking Buildbucket for the build status
[04:39:45.571] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:45.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:33:0) after 1m0s
[04:40:45.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:33:0)
[04:40:45.596] Timer tick, asking Buildbucket for the build status
[04:40:45.876] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:45.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:34:0) after 1m0s
[04:41:45.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:34:0)
[04:41:45.954] Timer tick, asking Buildbucket for the build status
[04:41:46.297] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:46.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:35:0) after 1m0s
[04:42:46.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:35:0)
[04:42:46.339] Timer tick, asking Buildbucket for the build status
[04:42:46.404] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:46.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:36:0) after 1m0s
[04:43:46.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:36:0)
[04:43:46.455] Timer tick, asking Buildbucket for the build status
[04:43:46.518] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:46.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:37:0) after 1m0s
[04:44:46.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:37:0)
[04:44:46.581] Timer tick, asking Buildbucket for the build status
[04:44:46.722] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:46.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:38:0) after 1m0s
[04:45:46.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:38:0)
[04:45:46.867] Timer tick, asking Buildbucket for the build status
[04:45:47.007] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:47.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:39:0) after 1m0s
[04:46:47.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:39:0)
[04:46:47.079] Timer tick, asking Buildbucket for the build status
[04:46:47.198] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:47.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:40:0) after 1m0s
[04:47:47.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:40:0)
[04:47:47.985] Timer tick, asking Buildbucket for the build status
[04:47:48.074] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:48.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:41:0) after 1m0s
[04:48:48.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:41:0)
[04:48:48.097] Timer tick, asking Buildbucket for the build status
[04:48:48.294] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:48.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:42:0) after 1m0s
[04:49:48.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:42:0)
[04:49:48.326] Timer tick, asking Buildbucket for the build status
[04:49:50.332] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:50.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:43:0) after 1m0s
[04:50:50.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:43:0)
[04:50:50.352] Timer tick, asking Buildbucket for the build status
[04:50:50.509] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:50.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:44:0) after 1m0s
[04:51:50.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:44:0)
[04:51:50.508] Timer tick, asking Buildbucket for the build status
[04:51:50.573] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:50.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:45:0) after 1m0s
[04:52:50.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:45:0)
[04:52:50.596] Timer tick, asking Buildbucket for the build status
[04:52:50.652] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:50.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:46:0) after 1m0s
[04:53:50.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:46:0)
[04:53:50.672] Timer tick, asking Buildbucket for the build status
[04:53:50.725] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:50.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:47:0) after 1m0s
[04:54:51.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:47:0)
[04:54:51.087] Timer tick, asking Buildbucket for the build status
[04:54:51.376] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:51.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:48:0) after 1m0s
[04:55:51.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:48:0)
[04:55:51.919] Timer tick, asking Buildbucket for the build status
[04:55:51.962] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:51.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:49:0) after 1m0s
[04:56:52.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:49:0)
[04:56:52.434] Timer tick, asking Buildbucket for the build status
[04:56:52.492] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:52.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:50:0) after 1m0s
[04:57:53.090] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:50:0)
[04:57:53.090] Timer tick, asking Buildbucket for the build status
[04:57:53.156] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:53.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:51:0) after 1m0s
[04:58:53.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:51:0)
[04:58:53.784] Timer tick, asking Buildbucket for the build status
[04:58:54.014] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:54.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:52:0) after 1m0s
[04:59:54.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:52:0)
[04:59:54.839] Timer tick, asking Buildbucket for the build status
[04:59:54.998] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:54.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:53:0) after 1m0s
[05:00:55.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:53:0)
[05:00:55.701] Timer tick, asking Buildbucket for the build status
[05:00:55.792] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:55.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:54:0) after 1m0s
[05:01:56.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:54:0)
[05:01:56.759] Timer tick, asking Buildbucket for the build status
[05:01:56.942] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:56.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:55:0) after 1m0s
[05:02:57.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:55:0)
[05:02:57.494] Timer tick, asking Buildbucket for the build status
[05:02:57.554] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:57.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:56:0) after 1m0s
[05:03:58.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:56:0)
[05:03:58.233] Timer tick, asking Buildbucket for the build status
[05:03:58.288] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:58.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:57:0) after 1m0s
[05:04:59.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:57:0)
[05:04:59.364] Timer tick, asking Buildbucket for the build status
[05:04:59.807] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:59.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:58:0) after 1m0s
[05:06:00.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:58:0)
[05:06:00.499] Timer tick, asking Buildbucket for the build status
[05:06:00.605] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:00.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:59:0) after 1m0s
[05:07:01.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:59:0)
[05:07:01.290] Timer tick, asking Buildbucket for the build status
[05:07:01.379] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:01.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:60:0) after 1m0s
[05:08:02.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:60:0)
[05:08:02.166] Timer tick, asking Buildbucket for the build status
[05:08:02.224] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:02.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:61:0) after 1m0s
[05:09:03.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:61:0)
[05:09:03.039] Timer tick, asking Buildbucket for the build status
[05:09:03.424] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:03.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:62:0) after 1m0s
[05:10:04.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:62:0)
[05:10:04.079] Timer tick, asking Buildbucket for the build status
[05:10:04.213] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:04.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:63:0) after 1m0s
[05:11:05.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:63:0)
[05:11:05.166] Timer tick, asking Buildbucket for the build status
[05:11:05.292] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:05.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:64:0) after 1m0s
[05:12:06.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:64:0)
[05:12:06.425] Timer tick, asking Buildbucket for the build status
[05:12:06.497] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:06.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:65:0) after 1m0s
[05:13:07.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:65:0)
[05:13:07.324] Timer tick, asking Buildbucket for the build status
[05:13:07.799] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:07.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:66:0) after 1m0s
[05:14:08.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:66:0)
[05:14:08.373] Timer tick, asking Buildbucket for the build status
[05:14:08.698] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:08.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:67:0) after 1m0s
[05:15:09.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:67:0)
[05:15:09.987] Timer tick, asking Buildbucket for the build status
[05:15:10.247] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:10.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:68:0) after 1m0s
[05:16:11.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:68:0)
[05:16:11.023] Timer tick, asking Buildbucket for the build status
[05:16:11.199] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:11.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:69:0) after 1m0s
[05:17:12.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:69:0)
[05:17:12.481] Timer tick, asking Buildbucket for the build status
[05:17:12.610] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:12.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:70:0) after 1m0s
[05:18:13.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:70:0)
[05:18:13.453] Timer tick, asking Buildbucket for the build status
[05:18:13.651] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:13.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:71:0) after 1m0s
[05:19:14.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:71:0)
[05:19:14.766] Timer tick, asking Buildbucket for the build status
[05:19:14.854] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:14.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:72:0) after 1m0s
[05:20:16.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:72:0)
[05:20:16.006] Timer tick, asking Buildbucket for the build status
[05:20:16.311] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:16.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:73:0) after 1m0s
[05:21:17.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:73:0)
[05:21:17.221] Timer tick, asking Buildbucket for the build status
[05:21:17.334] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:17.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:74:0) after 1m0s
[05:22:18.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:74:0)
[05:22:18.308] Timer tick, asking Buildbucket for the build status
[05:22:18.497] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:18.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:75:0) after 1m0s
[05:23:19.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:75:0)
[05:23:19.313] Timer tick, asking Buildbucket for the build status
[05:23:19.427] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:19.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:76:0) after 1m0s
[05:24:20.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:76:0)
[05:24:20.378] Timer tick, asking Buildbucket for the build status
[05:24:20.517] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:20.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:77:0) after 1m0s
[05:25:21.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:77:0)
[05:25:21.457] Timer tick, asking Buildbucket for the build status
[05:25:21.570] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:21.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:78:0) after 1m0s
[05:26:22.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:78:0)
[05:26:22.136] Timer tick, asking Buildbucket for the build status
[05:26:22.250] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:22.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:79:0) after 1m0s
[05:27:23.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:79:0)
[05:27:23.348] Timer tick, asking Buildbucket for the build status
[05:27:23.760] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:23.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:80:0) after 1m0s
[05:28:23.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:80:0)
[05:28:23.795] Timer tick, asking Buildbucket for the build status
[05:28:23.911] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:23.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:81:0) after 1m0s
[05:29:24.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:81:0)
[05:29:24.014] Timer tick, asking Buildbucket for the build status
[05:29:24.131] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:24.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:82:0) after 1m0s
[05:30:24.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:82:0)
[05:30:24.163] Timer tick, asking Buildbucket for the build status
[05:30:24.259] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:24.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:83:0) after 1m0s
[05:31:24.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:83:0)
[05:31:24.404] Timer tick, asking Buildbucket for the build status
[05:31:24.567] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:24.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:84:0) after 1m0s
[05:32:24.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:84:0)
[05:32:24.584] Timer tick, asking Buildbucket for the build status
[05:32:24.696] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:24.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:85:0) after 1m0s
[05:33:24.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:85:0)
[05:33:24.712] Timer tick, asking Buildbucket for the build status
[05:33:24.801] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:24.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:86:0) after 1m0s
[05:34:24.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:86:0)
[05:34:24.826] Timer tick, asking Buildbucket for the build status
[05:34:24.916] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:24.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:87:0) after 1m0s
[05:35:24.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:87:0)
[05:35:24.978] Timer tick, asking Buildbucket for the build status
[05:35:25.106] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:25.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:88:0) after 1m0s
[05:36:25.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:88:0)
[05:36:25.128] Timer tick, asking Buildbucket for the build status
[05:36:25.897] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:25.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:89:0) after 1m0s
[05:37:25.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:89:0)
[05:37:25.919] Timer tick, asking Buildbucket for the build status
[05:37:26.016] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:26.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:90:0) after 1m0s
[05:38:26.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:90:0)
[05:38:26.039] Timer tick, asking Buildbucket for the build status
[05:38:26.130] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:26.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:91:0) after 1m0s
[05:39:26.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:91:0)
[05:39:26.131] Timer tick, asking Buildbucket for the build status
[05:39:26.233] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:26.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:92:0) after 1m0s
[05:40:26.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:92:0)
[05:40:26.302] Timer tick, asking Buildbucket for the build status
[05:40:26.404] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:26.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:93:0) after 1m0s
[05:41:26.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:93:0)
[05:41:26.492] Timer tick, asking Buildbucket for the build status
[05:41:26.611] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:26.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:94:0) after 1m0s
[05:42:26.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:94:0)
[05:42:26.648] Timer tick, asking Buildbucket for the build status
[05:42:26.738] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:26.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:95:0) after 1m0s
[05:43:26.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:95:0)
[05:43:26.758] Timer tick, asking Buildbucket for the build status
[05:43:26.875] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:26.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:96:0) after 1m0s
[05:44:26.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:96:0)
[05:44:26.920] Timer tick, asking Buildbucket for the build status
[05:44:27.018] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:27.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:97:0) after 1m0s
[05:45:27.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:97:0)
[05:45:27.109] Timer tick, asking Buildbucket for the build status
[05:45:27.230] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:27.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:98:0) after 1m0s
[05:46:27.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:98:0)
[05:46:27.246] Timer tick, asking Buildbucket for the build status
[05:46:27.426] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:27.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:99:0) after 1m0s
[05:47:27.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:99:0)
[05:47:27.504] Timer tick, asking Buildbucket for the build status
[05:47:28.116] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:28.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:100:0) after 1m0s
[05:48:28.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:100:0)
[05:48:28.127] Timer tick, asking Buildbucket for the build status
[05:48:28.197] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:28.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:101:0) after 1m0s
[05:49:28.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:101:0)
[05:49:28.220] Timer tick, asking Buildbucket for the build status
[05:49:28.291] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:28.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:102:0) after 1m0s
[05:50:28.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:102:0)
[05:50:28.311] Timer tick, asking Buildbucket for the build status
[05:50:28.396] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:28.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:103:0) after 1m0s
[05:51:28.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:103:0)
[05:51:28.419] Timer tick, asking Buildbucket for the build status
[05:51:28.517] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:28.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:104:0) after 1m0s
[05:52:28.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:104:0)
[05:52:28.539] Timer tick, asking Buildbucket for the build status
[05:52:28.637] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:28.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:105:0) after 1m0s
[05:53:28.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:105:0)
[05:53:28.658] Timer tick, asking Buildbucket for the build status
[05:53:28.727] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:28.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:106:0) after 1m0s
[05:54:28.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:106:0)
[05:54:28.751] Timer tick, asking Buildbucket for the build status
[05:54:28.931] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:28.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:107:0) after 1m0s
[05:55:28.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:107:0)
[05:55:28.953] Timer tick, asking Buildbucket for the build status
[05:55:29.058] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:29.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:108:0) after 1m0s
[05:56:29.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:108:0)
[05:56:29.158] Timer tick, asking Buildbucket for the build status
[05:56:29.551] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:29.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:109:0) after 1m0s
[05:57:29.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:109:0)
[05:57:29.574] Timer tick, asking Buildbucket for the build status
[05:57:29.785] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:29.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:110:0) after 1m0s
[05:58:29.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:110:0)
[05:58:29.807] Timer tick, asking Buildbucket for the build status
[05:58:29.872] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:29.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:111:0) after 1m0s
[05:59:29.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:111:0)
[05:59:29.909] Timer tick, asking Buildbucket for the build status
[05:59:30.371] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:30.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:112:0) after 1m0s
[06:00:30.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:112:0)
[06:00:30.412] Timer tick, asking Buildbucket for the build status
[06:00:30.624] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:30.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:113:0) after 1m0s
[06:01:39.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:113:0)
[06:01:39.251] Timer tick, asking Buildbucket for the build status
[06:01:39.890] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:39.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:114:0) after 1m0s
[06:02:40.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:114:0)
[06:02:40.707] Timer tick, asking Buildbucket for the build status
[06:02:40.907] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:40.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:115:0) after 1m0s
[06:03:41.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:115:0)
[06:03:41.953] Timer tick, asking Buildbucket for the build status
[06:03:42.015] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:42.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:116:0) after 1m0s
[06:04:42.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:116:0)
[06:04:42.576] Timer tick, asking Buildbucket for the build status
[06:04:42.649] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:42.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:117:0) after 1m0s
[06:05:43.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:117:0)
[06:05:43.561] Timer tick, asking Buildbucket for the build status
[06:05:43.667] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:43.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:118:0) after 1m0s
[06:06:44.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:118:0)
[06:06:44.612] Timer tick, asking Buildbucket for the build status
[06:06:44.748] Build 8859881302521370544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:44.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025311364470616160:119:0) after 1m0s
[06:06:49.669] Received PubSub notification, asking Buildbucket for the build status
[06:06:49.930] Build 8859881302521370544: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:06:49.930] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608962808958138",
"created_by": "project:chromium",
"created_ts": "1608955777585889",
"id": "8859881302521370544",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"183589d6102c9b5bda7b6c970babd889bb99aeee\"}}",
"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\": \"183589d6102c9b5bda7b6c970babd889bb99aeee\", \"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-8-0z0z\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16455, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"5e606e5bfce2b23c139c5289cbf1299844fa4b43\", \"got_dawn_revision\": \"c8d5277e86ee8c51b1dd7892b41e83eb26ac2ede\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"183589d6102c9b5bda7b6c970babd889bb99aeee\", \"got_revision_cp\": \"refs/heads/master@{#839341}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"c1afed6d5ce09f38a9eb7f39b34b4c087cd80503\", \"got_v8_revision_cp\": \"refs/heads/8.9.191@{#1}\", \"got_webrtc_revision\": \"a45df0b3491ea72cfcf40e21106eb046c8594223\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32879}\", \"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\": \"183589d6102c9b5bda7b6c970babd889bb99aeee\", \"swarm_hashes_refs/heads/master(at){#839341}_without_patch\": {\"absl_hardening_tests\": \"ce1f0f9b9b25e3cdf6a07a0e0b76af9716afb48f\", \"accessibility_unittests\": \"3c7cd0ca26015796722e05a4e822cba71d13e644\", \"angle_unittests\": \"f2002a5259159c8967b9ae3a38ed1e949011cf61\", \"app_shell_unittests\": \"4a53c167e23d0fa0f503d26c82ed8e0d634246ba\", \"aura_unittests\": \"72df2279f4e4982473928cfba08f0f1759fa1938\", \"base_unittests\": \"beed3742c37f005bf3a7a447976e605bb0950db6\", \"base_util_unittests\": \"5c845bf46e78057b517371484642806e1e521fd4\", \"blink_common_unittests\": \"52589532ad7592d66afd9d006f6257597448b5aa\", \"blink_fuzzer_unittests\": \"b5a83c90490c4671514afc79e41fda5bf444b328\", \"blink_heap_unittests\": \"c3d735cd1ed35613a243f9618036fc1d575f1aed\", \"blink_platform_unittests\": \"68a68140b20e55a772901d930f8b5791e65326f5\", \"blink_unittests\": \"ea8d3fc03ee94450f817906b710ac356a128f5d3\", \"boringssl_crypto_tests\": \"2504f21ea2ffdfb788e76dcef30b9541551ef265\", \"boringssl_ssl_tests\": \"cedf376879c94d77766e67635c228f52690a5ae8\", \"browser_switcher_bho_unittests\": \"a778b9e8cd53b81c550f894474346318a7bfba9d\", \"browser_tests\": \"6666aa699479dc5f1cb3e32a5e2e1b27fca71dac\", \"capture_unittests\": \"ac1bbf613156550f9208b37a7d8f8513dbdd04cf\", \"cast_unittests\": \"deb56e5e6f34ee1e2495391fb4c7f83cdc61bd18\", \"cc_unittests\": \"665d12d86e2b61403b6f506ea59f154da4cb26f6\", \"chrome_app_unittests\": \"a386ad1133e585aa5df96425c6e97db312ab2ce8\", \"chrome_cleaner_unittests\": \"ff2c4f99cc2893c5c6a2283b131c93e8de9ac8f4\", \"chrome_elf_unittests\": \"e8538e2c8d72d245ed59a54baab93972aab1c43c\", \"chromedriver_unittests\": \"a3e9ebad2d0da8e1e74b1c17b21efdfc728a4243\", \"components_browsertests\": \"934820d8192a9a5b251d3eeed45624135cd3c1ee\", \"components_unittests\": \"e05c57b9a6b871915e2ae9f923c69e8009b2ff7d\", \"compositor_unittests\": \"72105e86fee85756296934f03cc9660e8fbc1f35\", \"content_browsertests\": \"cd08ed71a9dcd4770e2cbe3fc0d003bf1734d186\", \"content_unittests\": \"b9730bfe138322c8d54bfab15ed93a611e01571c\", \"courgette_unittests\": \"9255ed270c746a4277c076afb8e4ecc0765a665e\", \"crashpad_tests\": \"505482293273a34ff74cf1fe369e89dbddb15977\", \"cronet_tests\": \"e2df6d270f2f10d18b465be8a441ce64d345c27a\", \"cronet_unittests\": \"2c69cf9e35ed02bc5e0e2bf9c0e6cbfb3e739af3\", \"crypto_unittests\": \"c48cfbbd3fe8af209aefad43c9d29265df725895\", \"delayloads_unittests\": \"f0d6b4588714deeec302992b900693eabc7ef839\", \"device_unittests\": \"e25bb35a60aafe51b8f9050438f31265570969a7\", \"display_unittests\": \"836435689115e888e8e754da3d30d1c3c45a973a\", \"elevation_service_unittests\": \"2a06287e75c2e0f97d5b64f5f1a7861c63b3d4e3\", \"events_unittests\": \"0c20adfc3661e6104ec6f456edb5819458f74a93\", \"extensions_browsertests\": \"071abd706ab0b8a023c32b025425e335f1cbeede\", \"extensions_unittests\": \"469f622085a9a6aa06124ad69b965fec79b67f7d\", \"filesystem_service_unittests\": \"b953a96661864a2c041b3dfd73a225c510b9d1a5\", \"gcm_unit_tests\": \"b8e9b424e116a8e07fb008ebec35a0533ccdbcdd\", \"gcp_unittests\": \"075b73e22ba41927a107ea5bd083e112a20ce1d4\", \"gfx_unittests\": \"a9fce929c0ddc27a3758f307d83b43401784131c\", \"gin_unittests\": \"31d4241b833b3ecaaef7514a7c8e4c0eec4e4199\", \"google_apis_unittests\": \"6231a7a63a000bd89948eef6cfbb73ae0fcacba0\", \"gpu_unittests\": \"ae5677bb6e4b7a871ff55561ddef98d75ca6df01\", \"gwp_asan_unittests\": \"4ee736f8e9eb3049d0288af289c5b9b8a75d030f\", \"headless_browsertests\": \"ac72415a995e12b4e94675fd3fef96d4cfc018cc\", \"headless_unittests\": \"c65260b2447c7f1ff9ff9bb136144943a60b4705\", \"install_static_unittests\": \"81bf6c6af80dd2dfdf9ee80bfa85b52ea9edf229\", \"installer_util_unittests\": \"37c47c18a5aa805443631681ab7243f8ae73a18a\", \"interactive_ui_tests\": \"48b76bd11ddc62c79d56cb7776be9246fef2d7ee\", \"ipc_tests\": \"3aa0beb773a4bd50440925a24e62df0668d08801\", \"jingle_unittests\": \"394a60395894cecc9617d592a4bf1e11bf6fe2c9\", \"latency_unittests\": \"c4e746a7409867ba8611c15af30bba403c06ff3f\", \"libjingle_xmpp_unittests\": \"2ca994cb2da85c1c6fd970340d7618f54a90e9c3\", \"liburlpattern_unittests\": \"e83204cc574022d43bc66ff187ae4be4bfd1d3a0\", \"media_blink_unittests\": \"bb0ec6f5fec6c7d80fa115bea90bdcf81a5db5a4\", \"media_unittests\": \"a87becdf28564d1ed1a164d4af5fa6e1aac4227f\", \"message_center_unittests\": \"d9959c2de382a2eec7ac707906999441ceb48bb2\", \"midi_unittests\": \"8184a7c7819e94277c7d0aa1b9649fff26987943\", \"mojo_core_unittests\": \"19501930761909f9a2dc66e5f038640dca795c62\", \"mojo_unittests\": \"b26293b090fe5e8f03465299ab25200194c208f6\", \"nacl_loader_unittests\": \"65ddd92307047acdb63e61b1270e7934a58500d4\", \"native_theme_unittests\": \"2aeb22edce837c83d43973488130d8255cab189b\", \"net_unittests\": \"174af46ccf001969f8170a643cf29fcf07e28d7a\", \"notification_helper_unittests\": \"2fb1a59766608e09901703873f0861867a054d8e\", \"pdf_unittests\": \"af374156d5d0bcf2a877642e1b6a3bf0b3d1900d\", \"perfetto_unittests\": \"579be7f61edfec382e75f1d9d552160b7eec9df3\", \"ppapi_unittests\": \"4f0b851441103b0434853c83a6dccf071d5c45f4\", \"printing_unittests\": \"74df67047eb064ae65c145a9db553809ca02bb9e\", \"remoting_unittests\": \"e0365f8ca93df599f7f3f6b456f85130c28f0c32\", \"sbox_integration_tests\": \"be56f3e66968a0273b92354c28e94f781ea5c76f\", \"sbox_unittests\": \"ebbdcfed84b280a25f8a8f05ce6b0571b4498c4e\", \"sbox_validation_tests\": \"5b1f6785a0e202c160a932a0d96b671710dc02e9\", \"service_manager_unittests\": \"96cbf5e94368a03e0b44e36977c4dfa6ca8ed6b3\", \"services_unittests\": \"c404cf96e94476ae856a91d914f74bc42d816fac\", \"setup_unittests\": \"05e2251142b9208d3ff10beda9cbf624a6ca9db8\", \"shell_dialogs_unittests\": \"09f80bf7e6881f95181548a238ff37099238c02c\", \"skia_unittests\": \"b1f97f82a99f1d753607577cecc6241b8c8db941\", \"snapshot_unittests\": \"3dcc63ea16aa20274bc1fb4d045bb24b42b7151b\", \"sql_unittests\": \"b45de565cabf3f41cf03e4f4dcf579a0121ba3a4\", \"storage_unittests\": \"d58b424c0995d2798cc8fc10c5cb5bc72043fdef\", \"sync_integration_tests\": \"d2e072cc485a8aad645208612b9147ba7ca90abe\", \"traffic_annotation_auditor_unittests\": \"c4466f34f3f51829340272e775d66eb361f44a5d\", \"ui_base_unittests\": \"0d2f44e1c3da6b964fe26879af3decf83fdfd380\", \"ui_touch_selection_unittests\": \"fd07c0e0a7ffd665036ab7ef9399812249c8aaaa\", \"unit_tests\": \"fe78ec2497bd319842036f4f2b60ff275cd6be65\", \"updater_tests\": \"6854d12776361ba8c753da39eb84a054131f729a\", \"url_unittests\": \"d4ae1de7467c6f06b74bb23ef4d2d9bc4b6f0238\", \"views_unittests\": \"94f262bf9315d817aa1aa113116c8da066f0f82c\", \"viz_unittests\": \"805ec7367e6b3e420913b6dfe9762e2c124f2c58\", \"vr_common_unittests\": \"6beb74e4c0cb8cb35e8f55390f2efe482b8876ed\", \"vr_pixeltests\": \"a6c265d16ea64e94166d64b76c7313f7b6a19c92\", \"weblayer_browsertests\": \"f31f6747116e307af89bb02d23fd1636b20cdc4d\", \"weblayer_unittests\": \"065cf0ecf5ed7437d4acb4c7f2c70d5b2ab8529b\", \"wm_unittests\": \"68a27fec24a76e83650e5bf42ac9f234d9c6b597\", \"wtf_unittests\": \"9cfae143d5661d3b06c3b1ba72c25e079f2dddc8\", \"zlib_unittests\": \"e628db89766469d0cdb585c401b421df55196a2b\", \"zucchini_unittests\": \"cdb5b6a1297b26bd6c43ad609c532815f0456100\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_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-8-0z0z\"], \"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\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608956053768657",
"status": "COMPLETED",
"status_changed_ts": "1608962809252846",
"tags": [
"build_address:luci.chromium.ci/win-asan/16455",
"builder:win-asan",
"buildset:commit/git/183589d6102c9b5bda7b6c970babd889bb99aeee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/183589d6102c9b5bda7b6c970babd889bb99aeee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025311364470616160",
"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/8859881302521370544/+/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:50b60e5cb16f0b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608962809256955",
"url": "https://ci.chromium.org/b/8859881302521370544",
"utcnow_ts": "1608962809921748"
}
}
[06:06:49.930] Invocation finished in 1h57m13.58837821s with status SUCCEEDED