[07:44:54.474] New invocation is queued and will start shortly
[07:44:55.663] Starting the invocation (attempt 1)
[07:44:55.704] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:44:55.704] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:44:55.704] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021764537156877600",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021764537156877600",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4",
"gitiles_ref:refs/heads/master"
]
}
[07:44:57.541] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1612338295834293",
"id": "8856334475066165056",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612338297216577",
"tags": [
"build_address:luci.chromium.ci/win-asan/17144",
"builder:win-asan",
"buildset:commit/git/6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021764537156877600",
"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/8856334475066165056/+/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": "1612338297216747",
"url": "https://ci.chromium.org/b/8856334475066165056",
"utcnow_ts": "1612338297526247"
}
}
[07:44:57.541] Task URL: https://ci.chromium.org/b/8856334475066165056
[07:44:57.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:2:0) after 1m0s
[07:45:57.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:2:0)
[07:45:57.552] Timer tick, asking Buildbucket for the build status
[07:45:57.701] Build 8856334475066165056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:45:57.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:3:0) after 1m0s
[07:46:57.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:3:0)
[07:46:57.720] Timer tick, asking Buildbucket for the build status
[07:46:57.805] Build 8856334475066165056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:46:57.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:4:0) after 1m0s
[07:47:57.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:4:0)
[07:47:57.830] Timer tick, asking Buildbucket for the build status
[07:47:57.941] Build 8856334475066165056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:47:57.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:5:0) after 1m0s
[07:48:57.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:5:0)
[07:48:57.962] Timer tick, asking Buildbucket for the build status
[07:48:58.343] Build 8856334475066165056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:48:58.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:6:0) after 1m0s
[07:49:58.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:6:0)
[07:49:58.324] Timer tick, asking Buildbucket for the build status
[07:49:58.890] Build 8856334475066165056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:49:58.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:7:0) after 1m0s
[07:50:22.111] Received PubSub notification, asking Buildbucket for the build status
[07:50:22.291] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:58.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:7:0)
[07:50:58.990] Timer tick, asking Buildbucket for the build status
[07:50:59.217] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:59.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:9:0) after 1m0s
[07:51:59.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:9:0)
[07:51:59.301] Timer tick, asking Buildbucket for the build status
[07:51:59.597] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:59.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:10:0) after 1m0s
[07:52:59.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:10:0)
[07:52:59.672] Timer tick, asking Buildbucket for the build status
[07:52:59.812] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:59.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:11:0) after 1m0s
[07:53:59.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:11:0)
[07:53:59.865] Timer tick, asking Buildbucket for the build status
[07:53:59.983] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:59.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:12:0) after 1m0s
[07:55:00.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:12:0)
[07:55:00.042] Timer tick, asking Buildbucket for the build status
[07:55:00.244] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:00.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:13:0) after 1m0s
[07:56:00.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:13:0)
[07:56:00.261] Timer tick, asking Buildbucket for the build status
[07:56:00.514] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:00.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:14:0) after 1m0s
[07:57:01.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:14:0)
[07:57:01.112] Timer tick, asking Buildbucket for the build status
[07:57:01.185] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:01.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:15:0) after 1m0s
[07:58:01.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:15:0)
[07:58:01.264] Timer tick, asking Buildbucket for the build status
[07:58:01.340] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:01.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:16:0) after 1m0s
[07:59:01.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:16:0)
[07:59:01.410] Timer tick, asking Buildbucket for the build status
[07:59:01.605] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:01.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:17:0) after 1m0s
[08:00:01.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:17:0)
[08:00:01.761] Timer tick, asking Buildbucket for the build status
[08:00:02.037] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:02.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:18:0) after 1m0s
[08:01:02.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:18:0)
[08:01:02.072] Timer tick, asking Buildbucket for the build status
[08:01:02.164] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:02.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:19:0) after 1m0s
[08:02:02.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:19:0)
[08:02:02.233] Timer tick, asking Buildbucket for the build status
[08:02:02.296] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:02.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:20:0) after 1m0s
[08:03:02.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:20:0)
[08:03:02.405] Timer tick, asking Buildbucket for the build status
[08:03:02.480] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:02.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:21:0) after 1m0s
[08:04:02.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:21:0)
[08:04:02.723] Timer tick, asking Buildbucket for the build status
[08:04:03.048] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:03.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:22:0) after 1m0s
[08:05:03.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:22:0)
[08:05:03.164] Timer tick, asking Buildbucket for the build status
[08:05:03.599] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:03.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:23:0) after 1m0s
[08:06:03.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:23:0)
[08:06:03.605] Timer tick, asking Buildbucket for the build status
[08:06:03.791] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:03.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:24:0) after 1m0s
[08:07:03.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:24:0)
[08:07:03.990] Timer tick, asking Buildbucket for the build status
[08:07:04.420] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:04.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:25:0) after 1m0s
[08:08:04.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:25:0)
[08:08:04.558] Timer tick, asking Buildbucket for the build status
[08:08:04.657] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:04.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:26:0) after 1m0s
[08:09:04.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:26:0)
[08:09:04.703] Timer tick, asking Buildbucket for the build status
[08:09:04.798] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:04.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:27:0) after 1m0s
[08:10:04.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:27:0)
[08:10:04.814] Timer tick, asking Buildbucket for the build status
[08:10:05.001] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:05.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:28:0) after 1m0s
[08:11:05.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:28:0)
[08:11:05.034] Timer tick, asking Buildbucket for the build status
[08:11:05.110] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:05.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:29:0) after 1m0s
[08:12:05.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:29:0)
[08:12:05.153] Timer tick, asking Buildbucket for the build status
[08:12:05.503] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:05.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:30:0) after 1m0s
[08:13:05.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:30:0)
[08:13:05.518] Timer tick, asking Buildbucket for the build status
[08:13:05.639] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:05.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:31:0) after 1m0s
[08:14:05.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:31:0)
[08:14:05.716] Timer tick, asking Buildbucket for the build status
[08:14:05.788] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:05.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:32:0) after 1m0s
[08:15:05.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:32:0)
[08:15:05.843] Timer tick, asking Buildbucket for the build status
[08:15:07.132] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:07.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:33:0) after 1m0s
[08:16:07.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:33:0)
[08:16:07.147] Timer tick, asking Buildbucket for the build status
[08:16:07.369] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:07.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:34:0) after 1m0s
[08:17:07.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:34:0)
[08:17:07.409] Timer tick, asking Buildbucket for the build status
[08:17:07.603] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:07.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:35:0) after 1m0s
[08:18:07.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:35:0)
[08:18:07.640] Timer tick, asking Buildbucket for the build status
[08:18:08.134] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:08.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:36:0) after 1m0s
[08:19:08.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:36:0)
[08:19:08.181] Timer tick, asking Buildbucket for the build status
[08:19:08.312] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:08.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:37:0) after 1m0s
[08:20:08.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:37:0)
[08:20:08.375] Timer tick, asking Buildbucket for the build status
[08:20:08.516] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:08.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:38:0) after 1m0s
[08:21:08.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:38:0)
[08:21:08.518] Timer tick, asking Buildbucket for the build status
[08:21:08.582] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:08.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:39:0) after 1m0s
[08:22:08.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:39:0)
[08:22:08.630] Timer tick, asking Buildbucket for the build status
[08:22:08.689] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:08.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:40:0) after 1m0s
[08:23:08.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:40:0)
[08:23:08.934] Timer tick, asking Buildbucket for the build status
[08:23:09.173] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:09.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:41:0) after 1m0s
[08:24:09.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:41:0)
[08:24:09.262] Timer tick, asking Buildbucket for the build status
[08:24:09.333] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:09.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:42:0) after 1m0s
[08:25:09.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:42:0)
[08:25:09.396] Timer tick, asking Buildbucket for the build status
[08:25:09.501] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:09.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:43:0) after 1m0s
[08:26:09.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:43:0)
[08:26:09.651] Timer tick, asking Buildbucket for the build status
[08:26:09.715] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:09.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:44:0) after 1m0s
[08:27:09.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:44:0)
[08:27:09.859] Timer tick, asking Buildbucket for the build status
[08:27:09.915] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:09.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:45:0) after 1m0s
[08:28:09.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:45:0)
[08:28:09.937] Timer tick, asking Buildbucket for the build status
[08:28:09.996] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:09.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:46:0) after 1m0s
[08:29:10.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:46:0)
[08:29:10.098] Timer tick, asking Buildbucket for the build status
[08:29:10.226] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:10.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:47:0) after 1m0s
[08:30:10.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:47:0)
[08:30:10.251] Timer tick, asking Buildbucket for the build status
[08:30:10.662] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:10.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:48:0) after 1m0s
[08:31:10.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:48:0)
[08:31:10.736] Timer tick, asking Buildbucket for the build status
[08:31:11.028] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:11.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:49:0) after 1m0s
[08:32:11.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:49:0)
[08:32:11.136] Timer tick, asking Buildbucket for the build status
[08:32:11.385] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:11.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:50:0) after 1m0s
[08:33:11.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:50:0)
[08:33:11.470] Timer tick, asking Buildbucket for the build status
[08:33:11.645] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:11.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:51:0) after 1m0s
[08:34:11.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:51:0)
[08:34:11.750] Timer tick, asking Buildbucket for the build status
[08:34:11.815] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:11.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:52:0) after 1m0s
[08:35:11.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:52:0)
[08:35:11.859] Timer tick, asking Buildbucket for the build status
[08:35:11.958] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:11.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:53:0) after 1m0s
[08:36:12.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:53:0)
[08:36:12.024] Timer tick, asking Buildbucket for the build status
[08:36:12.084] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:12.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:54:0) after 1m0s
[08:37:12.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:54:0)
[08:37:12.150] Timer tick, asking Buildbucket for the build status
[08:37:12.245] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:12.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:55:0) after 1m0s
[08:38:12.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:55:0)
[08:38:12.284] Timer tick, asking Buildbucket for the build status
[08:38:12.347] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:12.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:56:0) after 1m0s
[08:39:12.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:56:0)
[08:39:12.338] Timer tick, asking Buildbucket for the build status
[08:39:12.639] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:12.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:57:0) after 1m0s
[08:40:12.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:57:0)
[08:40:12.654] Timer tick, asking Buildbucket for the build status
[08:40:12.788] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:12.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:58:0) after 1m0s
[08:41:12.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:58:0)
[08:41:12.798] Timer tick, asking Buildbucket for the build status
[08:41:12.938] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:12.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:59:0) after 1m0s
[08:42:12.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:59:0)
[08:42:12.988] Timer tick, asking Buildbucket for the build status
[08:42:13.099] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:13.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:60:0) after 1m0s
[08:43:13.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:60:0)
[08:43:13.144] Timer tick, asking Buildbucket for the build status
[08:43:13.309] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:13.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:61:0) after 1m0s
[08:44:13.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:61:0)
[08:44:13.356] Timer tick, asking Buildbucket for the build status
[08:44:13.429] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:13.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:62:0) after 1m0s
[08:45:13.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:62:0)
[08:45:13.453] Timer tick, asking Buildbucket for the build status
[08:45:13.714] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:13.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:63:0) after 1m0s
[08:46:13.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:63:0)
[08:46:13.755] Timer tick, asking Buildbucket for the build status
[08:46:13.818] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:13.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:64:0) after 1m0s
[08:47:13.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:64:0)
[08:47:13.897] Timer tick, asking Buildbucket for the build status
[08:47:14.173] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:14.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:65:0) after 1m0s
[08:48:14.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:65:0)
[08:48:14.204] Timer tick, asking Buildbucket for the build status
[08:48:14.281] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:14.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:66:0) after 1m0s
[08:49:14.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:66:0)
[08:49:14.302] Timer tick, asking Buildbucket for the build status
[08:49:14.435] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:14.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:67:0) after 1m0s
[08:50:14.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:67:0)
[08:50:14.464] Timer tick, asking Buildbucket for the build status
[08:50:14.584] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:14.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:68:0) after 1m0s
[08:51:14.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:68:0)
[08:51:14.635] Timer tick, asking Buildbucket for the build status
[08:51:14.728] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:14.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:69:0) after 1m0s
[08:52:14.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:69:0)
[08:52:14.758] Timer tick, asking Buildbucket for the build status
[08:52:14.815] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:14.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:70:0) after 1m0s
[08:53:14.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:70:0)
[08:53:14.818] Timer tick, asking Buildbucket for the build status
[08:53:14.875] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:14.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:71:0) after 1m0s
[08:54:14.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:71:0)
[08:54:14.872] Timer tick, asking Buildbucket for the build status
[08:54:15.048] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:15.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:72:0) after 1m0s
[08:55:15.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:72:0)
[08:55:15.111] Timer tick, asking Buildbucket for the build status
[08:55:15.247] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:15.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:73:0) after 1m0s
[08:56:15.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:73:0)
[08:56:15.265] Timer tick, asking Buildbucket for the build status
[08:56:15.459] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:15.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:74:0) after 1m0s
[08:57:15.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:74:0)
[08:57:15.480] Timer tick, asking Buildbucket for the build status
[08:57:15.582] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:15.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:75:0) after 1m0s
[08:58:15.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:75:0)
[08:58:15.692] Timer tick, asking Buildbucket for the build status
[08:58:15.850] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:15.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:76:0) after 1m0s
[08:59:15.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:76:0)
[08:59:15.964] Timer tick, asking Buildbucket for the build status
[08:59:16.028] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:16.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:77:0) after 1m0s
[09:00:15.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:77:0)
[09:00:15.995] Timer tick, asking Buildbucket for the build status
[09:00:16.135] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:16.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:78:0) after 1m0s
[09:01:16.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:78:0)
[09:01:16.132] Timer tick, asking Buildbucket for the build status
[09:01:16.336] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:16.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:79:0) after 1m0s
[09:02:16.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:79:0)
[09:02:16.416] Timer tick, asking Buildbucket for the build status
[09:02:16.557] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:16.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:80:0) after 1m0s
[09:03:16.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:80:0)
[09:03:16.696] Timer tick, asking Buildbucket for the build status
[09:03:16.848] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:16.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:81:0) after 1m0s
[09:04:16.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:81:0)
[09:04:16.892] Timer tick, asking Buildbucket for the build status
[09:04:16.992] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:16.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:82:0) after 1m0s
[09:05:17.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:82:0)
[09:05:17.072] Timer tick, asking Buildbucket for the build status
[09:05:17.213] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:17.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:83:0) after 1m0s
[09:06:17.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:83:0)
[09:06:17.256] Timer tick, asking Buildbucket for the build status
[09:06:17.436] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:17.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:84:0) after 1m0s
[09:07:17.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:84:0)
[09:07:17.499] Timer tick, asking Buildbucket for the build status
[09:07:17.622] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:17.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:85:0) after 1m0s
[09:08:17.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:85:0)
[09:08:17.672] Timer tick, asking Buildbucket for the build status
[09:08:17.784] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:17.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:86:0) after 1m0s
[09:09:17.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:86:0)
[09:09:17.840] Timer tick, asking Buildbucket for the build status
[09:09:17.972] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:17.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:87:0) after 1m0s
[09:10:18.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:87:0)
[09:10:18.063] Timer tick, asking Buildbucket for the build status
[09:10:18.438] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:18.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:88:0) after 1m0s
[09:11:18.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:88:0)
[09:11:18.533] Timer tick, asking Buildbucket for the build status
[09:11:18.805] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:18.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:89:0) after 1m0s
[09:12:18.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:89:0)
[09:12:18.779] Timer tick, asking Buildbucket for the build status
[09:12:18.849] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:18.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:90:0) after 1m0s
[09:13:18.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:90:0)
[09:13:18.981] Timer tick, asking Buildbucket for the build status
[09:13:19.236] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:19.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:91:0) after 1m0s
[09:14:19.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:91:0)
[09:14:19.284] Timer tick, asking Buildbucket for the build status
[09:14:19.368] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:19.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:92:0) after 1m0s
[09:15:19.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:92:0)
[09:15:19.430] Timer tick, asking Buildbucket for the build status
[09:15:19.637] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:19.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:93:0) after 1m0s
[09:16:19.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:93:0)
[09:16:19.632] Timer tick, asking Buildbucket for the build status
[09:16:20.113] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:20.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:94:0) after 1m0s
[09:17:20.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:94:0)
[09:17:20.214] Timer tick, asking Buildbucket for the build status
[09:17:20.824] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:20.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:95:0) after 1m0s
[09:18:21.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:95:0)
[09:18:21.008] Timer tick, asking Buildbucket for the build status
[09:18:21.098] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:21.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:96:0) after 1m0s
[09:19:21.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:96:0)
[09:19:21.119] Timer tick, asking Buildbucket for the build status
[09:19:21.200] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:21.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:97:0) after 1m0s
[09:20:21.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:97:0)
[09:20:21.301] Timer tick, asking Buildbucket for the build status
[09:20:21.513] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:21.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:98:0) after 1m0s
[09:21:21.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:98:0)
[09:21:21.589] Timer tick, asking Buildbucket for the build status
[09:21:21.933] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:21.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:99:0) after 1m0s
[09:22:21.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:99:0)
[09:22:21.965] Timer tick, asking Buildbucket for the build status
[09:22:22.133] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:22.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:100:0) after 1m0s
[09:23:22.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:100:0)
[09:23:22.258] Timer tick, asking Buildbucket for the build status
[09:23:22.357] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:22.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:101:0) after 1m0s
[09:24:22.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:101:0)
[09:24:22.378] Timer tick, asking Buildbucket for the build status
[09:24:22.498] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:22.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:102:0) after 1m0s
[09:25:22.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:102:0)
[09:25:22.606] Timer tick, asking Buildbucket for the build status
[09:25:22.709] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:22.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:103:0) after 1m0s
[09:26:22.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:103:0)
[09:26:22.759] Timer tick, asking Buildbucket for the build status
[09:26:23.017] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:23.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:104:0) after 1m0s
[09:27:23.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:104:0)
[09:27:23.133] Timer tick, asking Buildbucket for the build status
[09:27:23.316] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:23.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:105:0) after 1m0s
[09:28:23.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:105:0)
[09:28:23.427] Timer tick, asking Buildbucket for the build status
[09:28:23.547] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:23.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:106:0) after 1m0s
[09:29:23.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:106:0)
[09:29:23.602] Timer tick, asking Buildbucket for the build status
[09:29:23.850] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:23.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:107:0) after 1m0s
[09:30:23.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:107:0)
[09:30:23.947] Timer tick, asking Buildbucket for the build status
[09:30:24.077] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:24.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:108:0) after 1m0s
[09:31:24.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:108:0)
[09:31:24.117] Timer tick, asking Buildbucket for the build status
[09:31:24.194] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:24.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:109:0) after 1m0s
[09:32:24.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:109:0)
[09:32:24.269] Timer tick, asking Buildbucket for the build status
[09:32:24.937] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:24.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:110:0) after 1m0s
[09:33:24.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:110:0)
[09:33:24.995] Timer tick, asking Buildbucket for the build status
[09:33:25.304] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:25.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:111:0) after 1m0s
[09:34:25.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:111:0)
[09:34:25.397] Timer tick, asking Buildbucket for the build status
[09:34:25.505] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:25.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:112:0) after 1m0s
[09:35:25.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:112:0)
[09:35:25.947] Timer tick, asking Buildbucket for the build status
[09:35:26.083] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:26.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:113:0) after 1m0s
[09:36:26.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:113:0)
[09:36:26.122] Timer tick, asking Buildbucket for the build status
[09:36:26.188] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:26.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:114:0) after 1m0s
[09:37:26.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:114:0)
[09:37:26.352] Timer tick, asking Buildbucket for the build status
[09:37:26.540] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:26.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:115:0) after 1m0s
[09:38:26.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:115:0)
[09:38:26.746] Timer tick, asking Buildbucket for the build status
[09:38:26.947] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:26.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:116:0) after 1m0s
[09:39:26.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:116:0)
[09:39:26.977] Timer tick, asking Buildbucket for the build status
[09:39:27.099] Build 8856334475066165056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:27.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021764537156877600:117:0) after 1m0s
[09:40:09.403] Received PubSub notification, asking Buildbucket for the build status
[09:40:09.734] Build 8856334475066165056: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:40:09.734] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1612345208646322",
"created_by": "project:chromium",
"created_ts": "1612338295834293",
"id": "8856334475066165056",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4\"}}",
"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\": \"6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4\", \"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-0-be5b\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17144, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"6cc845bb0a603587ac36b900558d483e05f02f8c\", \"got_dawn_revision\": \"fd5fd05db039710c797ee8bac5e6d999173e5fc3\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4\", \"got_revision_cp\": \"refs/heads/master@{#850007}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"53720ace941d84ac17ae67a94166a363dcf0cacf\", \"got_v8_revision_cp\": \"refs/heads/9.0.123@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"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\": \"6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4\", \"swarm_hashes_refs/heads/master(at){#850007}_without_patch\": {\"absl_hardening_tests\": \"ef0f0cae7e28001006a2443cec323a7d24a8a4e8\", \"accessibility_unittests\": \"2a21fe16a0a07362672b62d8de28a5ca94777ca2\", \"angle_unittests\": \"4c7bad0c9a39526c8ccf232a770032962fb6d98f\", \"app_shell_unittests\": \"aab3bb4b48c90b7980c1e3ad76b6d3c548da1478\", \"aura_unittests\": \"05f1956a07da5991e0787be842ad8caf45fcf3b2\", \"base_unittests\": \"556c1c06d9ad9423bc3957018f17918b7af058f4\", \"base_util_unittests\": \"d9c740eaf7e7c6048b3a0bf5a289f266fb26845d\", \"blink_common_unittests\": \"72cb30c6e6f3b3f8e0b7c5ef34d3f95d866e3db0\", \"blink_fuzzer_unittests\": \"b7d37691c09ff70c39b28e1454ac1a435a351000\", \"blink_heap_unittests\": \"7ad2da6079eb56cd4a371e73dac74526490435d8\", \"blink_platform_unittests\": \"59fb6128ea2d38340a545e5960d91dad33f43f8f\", \"blink_unittests\": \"111fc0fa7989920b2f3d0b91cb0032f63f0cb706\", \"boringssl_crypto_tests\": \"cb4ab8d3aa503d1cf9849357cd9ba463daddb12c\", \"boringssl_ssl_tests\": \"bf53ed4d3dd905a6eec6811075e10c11b2c4bd0a\", \"browser_switcher_bho_unittests\": \"895382379f79d1b8bb4a82c67d1b006ed4cb183b\", \"browser_tests\": \"239d55c7f1ee2611b5c4ada7524263c3e798f96a\", \"capture_unittests\": \"5510b6729d77ce3b972e5345cf03c1904aedcb8d\", \"cast_unittests\": \"973b50f77e72e53535afdc7bcb4f818ff7b96f9e\", \"cc_unittests\": \"baedf8f5741d2c4f8b1a02921515519804bace7b\", \"chrome_app_unittests\": \"e14049e4d34b14a1ec5c7252559f520924f380a6\", \"chrome_cleaner_unittests\": \"fa172a3af9ff0be6dd96ad7f4e06ef10efaa2165\", \"chrome_elf_unittests\": \"5c95edb4033f2f487d3355dd3471f8399fb69d4a\", \"chromedriver_unittests\": \"e496bae68f8dbfba3a1407f0e5bdcfcfb38582ad\", \"components_browsertests\": \"e3059cf83e6cc53b4da098be686efdfbb843082c\", \"components_unittests\": \"3fdddbdbdf3591c973a6039b8dcf7db343beaf19\", \"compositor_unittests\": \"d9fc19caa323a204de6485b95fc3d929f6733dbf\", \"content_browsertests\": \"b324290a31735ca55daeacea3d17210dbbccd537\", \"content_unittests\": \"1c07a4eff9df766088b81dec18ada403ab17155a\", \"courgette_unittests\": \"06c99485e43463543152fc4491f30df936287b94\", \"crashpad_tests\": \"4c4a59fa5c52a6675a238ae6209f535b123acfc8\", \"cronet_tests\": \"45d342ec29f7424b4f03ef91f9ac3906e4b0bc28\", \"cronet_unittests\": \"275b4c913aa4471a0e0ceab3ad6c75739bf03320\", \"crypto_unittests\": \"e5fc234e041d0cb32f779233b2c3e67e2f083c12\", \"delayloads_unittests\": \"46b67147019cb2d200cb48238f9f317921d16273\", \"device_unittests\": \"a42de6679b916d5261c35b12d3a6361d905cea43\", \"display_unittests\": \"09d64d8e230d0b2b9e0c173c321a341312a0ffec\", \"elevation_service_unittests\": \"67f2875cf9d640195ea2c1830b761973fecfdc29\", \"events_unittests\": \"d016719449a092aea832897e21488a9151ae2a8e\", \"extensions_browsertests\": \"8906179d21771e598169c89dad745f4abd021569\", \"extensions_unittests\": \"d6db7649848b9054b824cd0881957c0cff324640\", \"filesystem_service_unittests\": \"ed7031f729fffd3567046db54a3362f414c7283b\", \"gcm_unit_tests\": \"4466c524108a4419112543bc2e20b57e6db21934\", \"gcp_unittests\": \"d805b5ad09988999e277a00c185efcf5b71e4dea\", \"gfx_unittests\": \"ede3c6679659c6dbfb8086bf0031e87652501f0b\", \"gin_unittests\": \"05bac4c96264d5780718eae6df476a1aae6cc680\", \"google_apis_unittests\": \"946cdc0f9e598a3cc3c4d4697b080b48b49a2662\", \"gpu_unittests\": \"f76411f3608bed3f970f32748336f7af2332d960\", \"gwp_asan_unittests\": \"2b6651f2b5ea6163ef11e13d88fe428b0cbc29ee\", \"headless_browsertests\": \"37affe4956d62cb1dc72ef0e136cabb6f64c7011\", \"headless_unittests\": \"7842289fd7514438a1e0f91bb25a1155111fefb1\", \"install_static_unittests\": \"6fb847eaf6ccc6c46183ba64fc38624fe20e059c\", \"installer_util_unittests\": \"16b363004bf550319bff258c6e1240f5e9af6a40\", \"interactive_ui_tests\": \"d4abe52490b156c5431c25fafc08c9be40a536ef\", \"ipc_tests\": \"e583722c5faa944461304e7cd2ff9c66bba4f0b2\", \"jingle_unittests\": \"9db98cdc4bb581387a2cb0282f63c4cfdc3c5c9c\", \"latency_unittests\": \"68f25751f9691946b5e13ee935aa3c091edf58aa\", \"libjingle_xmpp_unittests\": \"90b8be5ee803631065db1ca1c9d16289ecf1a945\", \"liburlpattern_unittests\": \"4096642b1adba5dac7408bbf5a351d5b34fb255c\", \"media_blink_unittests\": \"b2375db173927d854a22cb8a503df08f8abdd2aa\", \"media_unittests\": \"21fa1fc7df49b3f9248b97d36d9eaf60a5dbd9f4\", \"message_center_unittests\": \"984a8193bf6ceaf40e02babade8117617eac8890\", \"midi_unittests\": \"ab37c98b0fdd5a4c6e350f767eed919798b891bd\", \"mojo_core_unittests\": \"d6f5fddefab74ef862c799a5fe287f873c0371da\", \"mojo_unittests\": \"edc14d9e2af7e42d7102cd40f3277f94592dbadf\", \"nacl_loader_unittests\": \"f1986b2a689c5b652883ce52f245588096b18177\", \"native_theme_unittests\": \"bc3ff9d1d93c8bf3a1e227cf62284e8dcc559517\", \"net_unittests\": \"1e68d1e3a0979eb6360d97b9f0d7103b8ed0dcb2\", \"notification_helper_unittests\": \"c6209399d653c512be0cf232c41d3e53d00fd171\", \"pdf_unittests\": \"c5f20cfd507d1dff4b42ffbd40e3952e5c4d7d1e\", \"perfetto_unittests\": \"964e9ec93e61bc86c4b2b62fff0b0712e188bec7\", \"ppapi_unittests\": \"cad14534864c73a21ac2a8041ce1329495d27036\", \"printing_unittests\": \"7e1546639116b2279b20f786e52f1a43d79bebee\", \"remoting_unittests\": \"7e2a25b0edf26c9fa7658006185af8155a57dd78\", \"sbox_integration_tests\": \"8375df4cb3fbda329af263122143e9a807b50409\", \"sbox_unittests\": \"cef2d797f9e90ad27e522621bf68a22c2fb7db3b\", \"sbox_validation_tests\": \"f4587c8b5479278cfa3d0944391b5080c5ad3ea3\", \"service_manager_unittests\": \"173c68e49c131668772c34d453b05efd58821a7e\", \"services_unittests\": \"c65af5b7036c3dc9fc3e26c13dd5ce875ed175f6\", \"setup_unittests\": \"97a93cbf30481681de3979aa934d8c9d67d25f31\", \"shell_dialogs_unittests\": \"85e014a31155eeac90792de93aef67a59de9e208\", \"skia_unittests\": \"48e09d1cfaf203b0d7c02468b560deea2ebd31e0\", \"snapshot_unittests\": \"82698150191cd53e97c7092777edc2ac58783f78\", \"sql_unittests\": \"9e1e10669dda097c5f6f126ad2a98998bd3b627c\", \"storage_unittests\": \"834a18cd051da4fc06065171e693542d5ecd4ecc\", \"sync_integration_tests\": \"041debf3c81a6d4faaf277b737ee493906b7a96d\", \"traffic_annotation_auditor_unittests\": \"001d4bedd253c0b5630b8916a79cbdd6f6f89f98\", \"ui_base_unittests\": \"65cad34e2ab2b141de7f483cc0ec38f81001ccc6\", \"ui_touch_selection_unittests\": \"6353abc1f3df71c146420421cb97c02dcce332ec\", \"unit_tests\": \"e9c5b80d4f3dcc3a645212c546ad7529d182a31b\", \"updater_tests\": \"7c4faf81696b3a098106881fa122e3e056ea16f5\", \"url_unittests\": \"662f2ee333d2a1da15423b9652d930471a519b11\", \"views_unittests\": \"efb136b080151cc16d3f0195e964f870fb21b643\", \"viz_unittests\": \"854013488ed97a736f8d65bf430813a7250bf2ea\", \"vr_common_unittests\": \"94ca107d1256dee181745fc0c31f9617a5fa65dd\", \"vr_pixeltests\": \"df1a248c775162faba64f6de49ad4ceb00ede3e5\", \"weblayer_browsertests\": \"2bdfedd352c2a5909a198ed29112469cb55d7b41\", \"weblayer_unittests\": \"ecdf5d89e76ed63400fcfdfeebc6d0c1215211fe\", \"wm_unittests\": \"f8ec3018362e2b9caa074babb1be9537757a702c\", \"wtf_unittests\": \"93fb0ae5fd05a577b47c786772455262aca06837\", \"zlib_unittests\": \"9029971070ef5d8a2b031544a1208b8a3c525f65\", \"zucchini_unittests\": \"603e16d24f2aa87cb99e7c2e5c7f08a04de8d130\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_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-0-be5b\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5676-c76598c\"], \"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": "1612338620780495",
"status": "COMPLETED",
"status_changed_ts": "1612345208948063",
"tags": [
"build_address:luci.chromium.ci/win-asan/17144",
"builder:win-asan",
"buildset:commit/git/6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021764537156877600",
"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/8856334475066165056/+/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:517fab84d6d97110",
"user_agent:luci-scheduler"
],
"updated_ts": "1612345208952680",
"url": "https://ci.chromium.org/b/8856334475066165056",
"utcnow_ts": "1612345209719722"
}
}
[09:40:09.734] Invocation finished in 1h55m15.278031236s with status SUCCEEDED