[00:12:38.379] New invocation is queued and will start shortly
[00:12:39.568] Starting the invocation (attempt 1)
[00:12:39.625] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:12:39.625] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:12:39.625] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018350306691138432",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dcba975a478f9d869626b1f6c962823da2287555\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018350306691138432",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/dcba975a478f9d869626b1f6c962823da2287555",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dcba975a478f9d869626b1f6c962823da2287555",
"gitiles_ref:refs/heads/master"
]
}
[00:12:40.663] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615594359731200",
"id": "8852920244609223456",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dcba975a478f9d869626b1f6c962823da2287555\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615594360441780",
"tags": [
"build_address:luci.chromium.ci/win-asan/17780",
"builder:win-asan",
"buildset:commit/git/dcba975a478f9d869626b1f6c962823da2287555",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dcba975a478f9d869626b1f6c962823da2287555",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018350306691138432",
"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/8852920244609223456/+/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": "1615594360441899",
"url": "https://ci.chromium.org/b/8852920244609223456",
"utcnow_ts": "1615594360651923"
}
}
[00:12:40.663] Task URL: https://ci.chromium.org/b/8852920244609223456
[00:12:40.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:2:0) after 1m0s
[00:13:40.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:2:0)
[00:13:40.669] Timer tick, asking Buildbucket for the build status
[00:13:40.844] Build 8852920244609223456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:40.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:3:0) after 1m0s
[00:14:37.017] Received PubSub notification, asking Buildbucket for the build status
[00:14:37.099] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:40.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:3:0)
[00:14:40.894] Timer tick, asking Buildbucket for the build status
[00:14:40.958] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:40.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:5:0) after 1m0s
[00:15:40.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:5:0)
[00:15:40.976] Timer tick, asking Buildbucket for the build status
[00:15:41.046] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:41.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:6:0) after 1m0s
[00:16:41.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:6:0)
[00:16:41.140] Timer tick, asking Buildbucket for the build status
[00:16:41.211] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:41.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:7:0) after 1m0s
[00:17:43.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:7:0)
[00:17:43.061] Timer tick, asking Buildbucket for the build status
[00:17:43.133] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:43.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:8:0) after 1m0s
[00:18:43.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:8:0)
[00:18:43.305] Timer tick, asking Buildbucket for the build status
[00:18:43.463] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:43.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:9:0) after 1m0s
[00:19:43.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:9:0)
[00:19:43.479] Timer tick, asking Buildbucket for the build status
[00:19:43.630] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:43.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:10:0) after 1m0s
[00:20:43.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:10:0)
[00:20:43.695] Timer tick, asking Buildbucket for the build status
[00:20:43.971] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:43.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:11:0) after 1m0s
[00:21:44.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:11:0)
[00:21:44.018] Timer tick, asking Buildbucket for the build status
[00:21:44.120] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:44.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:12:0) after 1m0s
[00:22:44.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:12:0)
[00:22:44.190] Timer tick, asking Buildbucket for the build status
[00:22:44.322] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:44.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:13:0) after 1m0s
[00:23:44.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:13:0)
[00:23:44.299] Timer tick, asking Buildbucket for the build status
[00:23:44.470] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:44.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:14:0) after 1m0s
[00:24:44.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:14:0)
[00:24:44.488] Timer tick, asking Buildbucket for the build status
[00:24:44.841] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:44.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:15:0) after 1m0s
[00:25:44.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:15:0)
[00:25:44.843] Timer tick, asking Buildbucket for the build status
[00:25:44.922] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:44.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:16:0) after 1m0s
[00:26:44.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:16:0)
[00:26:44.899] Timer tick, asking Buildbucket for the build status
[00:26:44.966] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:44.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:17:0) after 1m0s
[00:27:44.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:17:0)
[00:27:44.994] Timer tick, asking Buildbucket for the build status
[00:27:45.070] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:45.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:18:0) after 1m0s
[00:28:45.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:18:0)
[00:28:45.119] Timer tick, asking Buildbucket for the build status
[00:28:45.251] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:45.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:19:0) after 1m0s
[00:29:45.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:19:0)
[00:29:45.321] Timer tick, asking Buildbucket for the build status
[00:29:45.422] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:45.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:20:0) after 1m0s
[00:30:45.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:20:0)
[00:30:45.429] Timer tick, asking Buildbucket for the build status
[00:30:45.505] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:45.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:21:0) after 1m0s
[00:31:45.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:21:0)
[00:31:45.588] Timer tick, asking Buildbucket for the build status
[00:31:45.655] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:45.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:22:0) after 1m0s
[00:32:45.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:22:0)
[00:32:45.647] Timer tick, asking Buildbucket for the build status
[00:32:45.926] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:45.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:23:0) after 1m0s
[00:33:45.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:23:0)
[00:33:45.939] Timer tick, asking Buildbucket for the build status
[00:33:46.027] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:46.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:24:0) after 1m0s
[00:34:46.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:24:0)
[00:34:46.055] Timer tick, asking Buildbucket for the build status
[00:34:46.265] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:46.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:25:0) after 1m0s
[00:35:46.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:25:0)
[00:35:46.313] Timer tick, asking Buildbucket for the build status
[00:35:46.376] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:46.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:26:0) after 1m0s
[00:36:46.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:26:0)
[00:36:46.417] Timer tick, asking Buildbucket for the build status
[00:36:46.501] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:46.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:27:0) after 1m0s
[00:37:46.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:27:0)
[00:37:46.530] Timer tick, asking Buildbucket for the build status
[00:37:46.628] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:46.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:28:0) after 1m0s
[00:38:46.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:28:0)
[00:38:46.675] Timer tick, asking Buildbucket for the build status
[00:38:46.747] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:46.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:29:0) after 1m0s
[00:39:46.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:29:0)
[00:39:46.732] Timer tick, asking Buildbucket for the build status
[00:39:46.805] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:46.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:30:0) after 1m0s
[00:40:46.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:30:0)
[00:40:46.920] Timer tick, asking Buildbucket for the build status
[00:40:46.987] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:46.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:31:0) after 1m0s
[00:41:47.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:31:0)
[00:41:47.012] Timer tick, asking Buildbucket for the build status
[00:41:47.077] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:47.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:32:0) after 1m0s
[00:42:47.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:32:0)
[00:42:47.138] Timer tick, asking Buildbucket for the build status
[00:42:47.222] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:47.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:33:0) after 1m0s
[00:43:47.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:33:0)
[00:43:47.259] Timer tick, asking Buildbucket for the build status
[00:43:47.333] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:47.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:34:0) after 1m0s
[00:44:47.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:34:0)
[00:44:47.351] Timer tick, asking Buildbucket for the build status
[00:44:47.475] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:47.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:35:0) after 1m0s
[00:45:47.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:35:0)
[00:45:47.438] Timer tick, asking Buildbucket for the build status
[00:45:47.509] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:47.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:36:0) after 1m0s
[00:46:47.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:36:0)
[00:46:47.554] Timer tick, asking Buildbucket for the build status
[00:46:47.806] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:47.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:37:0) after 1m0s
[00:47:47.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:37:0)
[00:47:47.926] Timer tick, asking Buildbucket for the build status
[00:47:48.120] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:48.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:38:0) after 1m0s
[00:48:48.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:38:0)
[00:48:48.196] Timer tick, asking Buildbucket for the build status
[00:48:49.045] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:49.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:39:0) after 1m0s
[00:49:49.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:39:0)
[00:49:49.144] Timer tick, asking Buildbucket for the build status
[00:49:49.217] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:49.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:40:0) after 1m0s
[00:50:49.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:40:0)
[00:50:49.289] Timer tick, asking Buildbucket for the build status
[00:50:49.359] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:49.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:41:0) after 1m0s
[00:51:49.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:41:0)
[00:51:49.477] Timer tick, asking Buildbucket for the build status
[00:51:49.540] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:49.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:42:0) after 1m0s
[00:52:49.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:42:0)
[00:52:49.615] Timer tick, asking Buildbucket for the build status
[00:52:49.759] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:49.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:43:0) after 1m0s
[00:53:49.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:43:0)
[00:53:49.859] Timer tick, asking Buildbucket for the build status
[00:53:49.957] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:49.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:44:0) after 1m0s
[00:54:50.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:44:0)
[00:54:50.052] Timer tick, asking Buildbucket for the build status
[00:54:50.116] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:50.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:45:0) after 1m0s
[00:55:50.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:45:0)
[00:55:50.110] Timer tick, asking Buildbucket for the build status
[00:55:50.452] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:50.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:46:0) after 1m0s
[00:56:50.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:46:0)
[00:56:50.490] Timer tick, asking Buildbucket for the build status
[00:56:50.559] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:50.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:47:0) after 1m0s
[00:57:50.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:47:0)
[00:57:50.583] Timer tick, asking Buildbucket for the build status
[00:57:50.666] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:50.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:48:0) after 1m0s
[00:58:50.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:48:0)
[00:58:50.717] Timer tick, asking Buildbucket for the build status
[00:58:50.802] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:50.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:49:0) after 1m0s
[00:59:50.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:49:0)
[00:59:50.863] Timer tick, asking Buildbucket for the build status
[00:59:50.955] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:50.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:50:0) after 1m0s
[01:00:51.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:50:0)
[01:00:51.122] Timer tick, asking Buildbucket for the build status
[01:00:51.307] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:51.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:51:0) after 1m0s
[01:01:51.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:51:0)
[01:01:51.495] Timer tick, asking Buildbucket for the build status
[01:01:51.609] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:51.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:52:0) after 1m0s
[01:02:51.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:52:0)
[01:02:51.719] Timer tick, asking Buildbucket for the build status
[01:02:51.822] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:51.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:53:0) after 1m0s
[01:03:51.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:53:0)
[01:03:51.962] Timer tick, asking Buildbucket for the build status
[01:03:52.035] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:52.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:54:0) after 1m0s
[01:04:52.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:54:0)
[01:04:52.113] Timer tick, asking Buildbucket for the build status
[01:04:52.176] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:52.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:55:0) after 1m0s
[01:05:52.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:55:0)
[01:05:52.225] Timer tick, asking Buildbucket for the build status
[01:05:52.291] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:52.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:56:0) after 1m0s
[01:06:52.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:56:0)
[01:06:52.418] Timer tick, asking Buildbucket for the build status
[01:06:52.483] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:52.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:57:0) after 1m0s
[01:07:52.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:57:0)
[01:07:52.499] Timer tick, asking Buildbucket for the build status
[01:07:52.577] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:52.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:58:0) after 1m0s
[01:08:52.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:58:0)
[01:08:52.687] Timer tick, asking Buildbucket for the build status
[01:08:52.755] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:52.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:59:0) after 1m0s
[01:09:52.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:59:0)
[01:09:52.964] Timer tick, asking Buildbucket for the build status
[01:09:53.045] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:53.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:60:0) after 1m0s
[01:10:53.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:60:0)
[01:10:53.162] Timer tick, asking Buildbucket for the build status
[01:10:53.231] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:53.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:61:0) after 1m0s
[01:11:53.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:61:0)
[01:11:53.315] Timer tick, asking Buildbucket for the build status
[01:11:53.482] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:53.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:62:0) after 1m0s
[01:12:53.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:62:0)
[01:12:53.530] Timer tick, asking Buildbucket for the build status
[01:12:53.621] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:53.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:63:0) after 1m0s
[01:13:53.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:63:0)
[01:13:53.640] Timer tick, asking Buildbucket for the build status
[01:13:53.707] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:53.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:64:0) after 1m0s
[01:14:53.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:64:0)
[01:14:53.819] Timer tick, asking Buildbucket for the build status
[01:14:53.985] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:53.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:65:0) after 1m0s
[01:15:54.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:65:0)
[01:15:54.048] Timer tick, asking Buildbucket for the build status
[01:15:54.131] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:54.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:66:0) after 1m0s
[01:16:54.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:66:0)
[01:16:54.217] Timer tick, asking Buildbucket for the build status
[01:16:54.526] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:54.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:67:0) after 1m0s
[01:17:54.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:67:0)
[01:17:54.619] Timer tick, asking Buildbucket for the build status
[01:17:54.703] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:54.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:68:0) after 1m0s
[01:18:54.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:68:0)
[01:18:54.758] Timer tick, asking Buildbucket for the build status
[01:18:54.834] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:54.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:69:0) after 1m0s
[01:19:54.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:69:0)
[01:19:54.877] Timer tick, asking Buildbucket for the build status
[01:19:55.167] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:55.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:70:0) after 1m0s
[01:20:55.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:70:0)
[01:20:55.238] Timer tick, asking Buildbucket for the build status
[01:20:55.642] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:55.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:71:0) after 1m0s
[01:21:55.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:71:0)
[01:21:55.749] Timer tick, asking Buildbucket for the build status
[01:21:56.104] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:56.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:72:0) after 1m0s
[01:22:56.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:72:0)
[01:22:56.128] Timer tick, asking Buildbucket for the build status
[01:22:56.211] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:56.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:73:0) after 1m0s
[01:23:56.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:73:0)
[01:23:56.211] Timer tick, asking Buildbucket for the build status
[01:23:56.299] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:56.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:74:0) after 1m0s
[01:24:56.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:74:0)
[01:24:56.349] Timer tick, asking Buildbucket for the build status
[01:24:56.414] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:56.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:75:0) after 1m0s
[01:25:56.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:75:0)
[01:25:56.488] Timer tick, asking Buildbucket for the build status
[01:25:57.383] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:57.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:76:0) after 1m0s
[01:26:57.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:76:0)
[01:26:57.439] Timer tick, asking Buildbucket for the build status
[01:26:57.905] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:57.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:77:0) after 1m0s
[01:27:57.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:77:0)
[01:27:57.952] Timer tick, asking Buildbucket for the build status
[01:27:58.045] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:58.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:78:0) after 1m0s
[01:28:58.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:78:0)
[01:28:58.063] Timer tick, asking Buildbucket for the build status
[01:28:58.139] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:58.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:79:0) after 1m0s
[01:29:58.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:79:0)
[01:29:58.187] Timer tick, asking Buildbucket for the build status
[01:29:58.317] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:58.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:80:0) after 1m0s
[01:30:58.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:80:0)
[01:30:58.385] Timer tick, asking Buildbucket for the build status
[01:30:58.577] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:58.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:81:0) after 1m0s
[01:31:58.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:81:0)
[01:31:58.632] Timer tick, asking Buildbucket for the build status
[01:31:58.715] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:58.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:82:0) after 1m0s
[01:32:58.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:82:0)
[01:32:58.858] Timer tick, asking Buildbucket for the build status
[01:32:58.965] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:58.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:83:0) after 1m0s
[01:33:59.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:83:0)
[01:33:59.442] Timer tick, asking Buildbucket for the build status
[01:33:59.515] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:59.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:84:0) after 1m0s
[01:34:59.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:84:0)
[01:34:59.769] Timer tick, asking Buildbucket for the build status
[01:34:59.860] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:59.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:85:0) after 1m0s
[01:36:00.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:85:0)
[01:36:00.000] Timer tick, asking Buildbucket for the build status
[01:36:00.192] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:00.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:86:0) after 1m0s
[01:37:00.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:86:0)
[01:37:00.195] Timer tick, asking Buildbucket for the build status
[01:37:00.266] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:00.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:87:0) after 1m0s
[01:38:00.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:87:0)
[01:38:00.918] Timer tick, asking Buildbucket for the build status
[01:38:01.177] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:01.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:88:0) after 1m0s
[01:39:01.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:88:0)
[01:39:01.194] Timer tick, asking Buildbucket for the build status
[01:39:01.328] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:01.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:89:0) after 1m0s
[01:40:01.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:89:0)
[01:40:01.375] Timer tick, asking Buildbucket for the build status
[01:40:01.437] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:01.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:90:0) after 1m0s
[01:41:01.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:90:0)
[01:41:01.526] Timer tick, asking Buildbucket for the build status
[01:41:01.611] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:01.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:91:0) after 1m0s
[01:42:01.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:91:0)
[01:42:01.583] Timer tick, asking Buildbucket for the build status
[01:42:01.657] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:01.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:92:0) after 1m0s
[01:43:01.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:92:0)
[01:43:01.865] Timer tick, asking Buildbucket for the build status
[01:43:01.957] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:01.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:93:0) after 1m0s
[01:44:02.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:93:0)
[01:44:02.081] Timer tick, asking Buildbucket for the build status
[01:44:02.166] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:02.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:94:0) after 1m0s
[01:45:02.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:94:0)
[01:45:02.187] Timer tick, asking Buildbucket for the build status
[01:45:02.261] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:02.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:95:0) after 1m0s
[01:46:02.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:95:0)
[01:46:02.365] Timer tick, asking Buildbucket for the build status
[01:46:02.445] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:02.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:96:0) after 1m0s
[01:47:02.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:96:0)
[01:47:02.581] Timer tick, asking Buildbucket for the build status
[01:47:02.663] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:02.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:97:0) after 1m0s
[01:48:02.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:97:0)
[01:48:02.635] Timer tick, asking Buildbucket for the build status
[01:48:02.771] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:02.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:98:0) after 1m0s
[01:49:02.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:98:0)
[01:49:02.810] Timer tick, asking Buildbucket for the build status
[01:49:02.888] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:02.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:99:0) after 1m0s
[01:50:02.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:99:0)
[01:50:02.915] Timer tick, asking Buildbucket for the build status
[01:50:02.998] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:02.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:100:0) after 1m0s
[01:51:03.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:100:0)
[01:51:03.041] Timer tick, asking Buildbucket for the build status
[01:51:03.438] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:03.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:101:0) after 1m0s
[01:52:03.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:101:0)
[01:52:03.452] Timer tick, asking Buildbucket for the build status
[01:52:04.215] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:04.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:102:0) after 1m0s
[01:53:04.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:102:0)
[01:53:04.256] Timer tick, asking Buildbucket for the build status
[01:53:04.493] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:04.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:103:0) after 1m0s
[01:54:04.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:103:0)
[01:54:04.537] Timer tick, asking Buildbucket for the build status
[01:54:04.620] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:04.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:104:0) after 1m0s
[01:55:04.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:104:0)
[01:55:04.741] Timer tick, asking Buildbucket for the build status
[01:55:05.329] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:05.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:105:0) after 1m0s
[01:56:05.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:105:0)
[01:56:05.430] Timer tick, asking Buildbucket for the build status
[01:56:05.519] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:05.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:106:0) after 1m0s
[01:57:05.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:106:0)
[01:57:05.638] Timer tick, asking Buildbucket for the build status
[01:57:05.848] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:05.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:107:0) after 1m0s
[01:58:05.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:107:0)
[01:58:05.886] Timer tick, asking Buildbucket for the build status
[01:58:05.969] Build 8852920244609223456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:05.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018350306691138432:108:0) after 1m0s
[01:58:37.887] Received PubSub notification, asking Buildbucket for the build status
[01:58:37.968] Build 8852920244609223456: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:58:37.968] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615600716827981",
"created_by": "project:chromium",
"created_ts": "1615594359731200",
"id": "8852920244609223456",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dcba975a478f9d869626b1f6c962823da2287555\"}}",
"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\": \"dcba975a478f9d869626b1f6c962823da2287555\", \"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-2-zecp\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17780, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"41a2672f43fb125e83b4942133e487995da9bf7c\", \"got_dawn_revision\": \"c53ea046a603d5085268584f16bfdf61586f4c4c\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"dcba975a478f9d869626b1f6c962823da2287555\", \"got_revision_cp\": \"refs/heads/master@{#862600}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a5619c014b66c2a5c693c5777684da012f55d190\", \"got_v8_revision\": \"9d12b666c3e113f5cd61e2b2eb384aae242ac840\", \"got_v8_revision_cp\": \"refs/heads/9.1.105@{#1}\", \"got_webrtc_revision\": \"86473404362e38131203537b8a8c8ec7c1abc31a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33444}\", \"is_cached\": true, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dcba975a478f9d869626b1f6c962823da2287555\", \"swarm_hashes_refs/heads/master(at){#862600}_without_patch\": {\"absl_hardening_tests\": \"936d0dd7c64c0f1b38033254e100c2b023fec043\", \"accessibility_unittests\": \"0518e6781834b666f85cc0f154c1b61a652bb6bc\", \"angle_unittests\": \"6fbb2810f9454b6ab1cc01938b9a2caee02c8d55\", \"app_shell_unittests\": \"f1f637bc627c48d4653bf1e48ef3de6cbb0732b0\", \"aura_unittests\": \"822c482d3175654d53de640b040d59577f013b32\", \"base_unittests\": \"e4b1e177006071993abe650fbc06b64a8d18e1e3\", \"base_util_unittests\": \"ed3f460df789604e33e7aa33ba0250e65781b775\", \"blink_common_unittests\": \"d6db4fee465d2bc2921fb6e78baf995e85636ac0\", \"blink_fuzzer_unittests\": \"503f900a157775ded88aa68d909aa332c915eaed\", \"blink_heap_unittests\": \"3067062ca6d9e43d3e8ec5153eb38d864d780961\", \"blink_platform_unittests\": \"3de68083507c19011492a02769d840683c0bfc64\", \"blink_unittests\": \"7db7f9b5eb9f6d7a9c2dc8176ddca62e59aa5a7d\", \"boringssl_crypto_tests\": \"48116f73155803b3adbfbff3cb583975da7f25c8\", \"boringssl_ssl_tests\": \"b03c50f14055d33e112a72355586775436c4a7ff\", \"browser_switcher_bho_unittests\": \"5ef088f8f246dad48142fe7fa207f649a5718e7b\", \"browser_tests\": \"b6f0a3688c8d0fc329c09c9088e2d4c9681fa96b\", \"capture_unittests\": \"9a510a4623b8787bc1da74f0fb640b751e440fcc\", \"cast_unittests\": \"75a4c3963d5bf6257f4a19831c6d1b042879c3e4\", \"cc_unittests\": \"61627a4eb11b0229ff79c3793f0262e993c40c08\", \"chrome_app_unittests\": \"6bebecadd18ae9a93d73f30236e98d27ee8ff643\", \"chrome_cleaner_unittests\": \"99fa3f8d410b5643078620e43cbd1240897f933b\", \"chrome_elf_unittests\": \"8a1c729b623324d5f999e8fcf64f3caaa68fc611\", \"chromedriver_unittests\": \"c85114d524de04eca16f31659a46d711f5910dd9\", \"color_unittests\": \"d98babaa7975354b586f42bc8d99c3ab51da0a4e\", \"components_browsertests\": \"080ea1dd6ff94dd3998ec921e994619ba721257b\", \"components_unittests\": \"a0062f61d8da5b15a04f77a916db67f76373f7ab\", \"compositor_unittests\": \"5c757f8be007d1a503b2ef65220ba486536d4e5a\", \"content_browsertests\": \"a8f8b86a3f77653cd91d8791cb36f0f4d376fb74\", \"content_unittests\": \"7bb565036f1262c5f9ce63f9310f744cfd7e6eba\", \"courgette_unittests\": \"49d75bdf43f6b751ea90f397254ec6783366377f\", \"crashpad_tests\": \"1fb36ba13007be949bd34a62187628a0553a2a79\", \"cronet_tests\": \"a6a6488b60db72981087098b41f37c2a4dd3a81c\", \"cronet_unittests\": \"60f8369bb7300364897d653c8e685939f77f3141\", \"crypto_unittests\": \"bf2e08c4e92bc611b47d57881f522a6f2c5b6d7d\", \"delayloads_unittests\": \"059839871c98a9d8586773cabffc83d03e96f904\", \"device_unittests\": \"e320fbe6422e2a13eb626a931a47e88bfb0e7528\", \"display_unittests\": \"b7aa46fa7563d28134e30335000717587df3e92d\", \"elevation_service_unittests\": \"c7a5739e1dd4c2e5935d09a91519010434574776\", \"events_unittests\": \"0aa4e60e4f76f9e7f06ba26cc32df061d88cdf04\", \"extensions_browsertests\": \"9a7723097aecbc14ef9748b033ac66bbd4e17cd1\", \"extensions_unittests\": \"5f2d8b2575f8a04f118b82257d158277c040655a\", \"filesystem_service_unittests\": \"44e2818b97840fbf3ff5a55c4b628e3d0bbad00a\", \"gcm_unit_tests\": \"2b3080e5cac981f837a0ae1c6e1fc9d5a14f4f25\", \"gcp_unittests\": \"7eca0154bda820105c9741fa309b989383daf019\", \"gfx_unittests\": \"117470e6320462cb9a3d04de06872e06c8623631\", \"gin_unittests\": \"440c36d64641528ccd8360a8655759ac332db5db\", \"google_apis_unittests\": \"372cb13ee996ef03e9569d150c0a71503844cd7c\", \"gpu_unittests\": \"3f3427ff505fcd50da447232316a71f7c541e09b\", \"gwp_asan_unittests\": \"4a234ce89939697e2b86eb806c2dd073f71d4ed0\", \"headless_browsertests\": \"51a68cd2f2ba2849b8aa761832132fff7ed094a8\", \"headless_unittests\": \"df698d0a0bf2c239f45bbea42386643ad0152403\", \"install_static_unittests\": \"317facf15094077d66257477ad7a416f3f2c4486\", \"installer_util_unittests\": \"48ddedfba5da373d55fd8681eac90cc8bcddadd2\", \"interactive_ui_tests\": \"3667ef65893d7bc4bb0da70dadcb104af4ac1475\", \"ipc_tests\": \"9c320ffb2945e88832b3b4cf2694c9a77f6056c1\", \"jingle_unittests\": \"b4af69dd91a996844d9f52f536eb1e73446e69fb\", \"latency_unittests\": \"661eea2c4a940480a100708d3346ed507d0dc8b8\", \"libjingle_xmpp_unittests\": \"1b73f3ea8c879dc1250e80f25c9a50e14cef73cd\", \"liburlpattern_unittests\": \"6433462f30a880c19bd0e8c45e9d1dccc2884be4\", \"media_blink_unittests\": \"dd38c5892fbd2b9351bb8e92bb0424e4172e22ea\", \"media_unittests\": \"1358cb41d4b2d443eeb56fe09a7b20f86914303f\", \"message_center_unittests\": \"5e26d59318b3d57b0b16e5ce9c07a407a9284748\", \"midi_unittests\": \"74f234ace5a36edfced7844963f7f8dba6c4331c\", \"mojo_core_unittests\": \"de06a3440909647503e9d40caef1ec81e7bc1e26\", \"mojo_unittests\": \"224a3b77a4d536fc061e2f27120da5788c96d9ff\", \"nacl_loader_unittests\": \"2e5b0a68cc339938c547e6bd86259fa71b11ab6c\", \"native_theme_unittests\": \"52adf084d75172902f2945ee8d9753e0d260250e\", \"net_unittests\": \"22956c68bec88abaa8603857e716cc007eff838e\", \"notification_helper_unittests\": \"8bf46b5b3adfed0a3de14ca457f61269fce7eab7\", \"pdf_unittests\": \"a2c7455470348e652e1726d9f08fa096bb09dc2b\", \"perfetto_unittests\": \"c71a85c5f9c575bd063d6d79c285d1e95947cbdb\", \"ppapi_unittests\": \"a48882533a9b629402b1f07e97bc1646a91913d8\", \"printing_unittests\": \"cbff22757fcf588370f10c26795a5db2a1a7f9b2\", \"remoting_unittests\": \"f0ad9c0069a5fb1b6a2a937111ce75e984342d22\", \"sbox_integration_tests\": \"48f038212544dcd93c2535d3937c73358a5430e0\", \"sbox_unittests\": \"58c41596f7257d3377087ecb2586f36894edfe73\", \"sbox_validation_tests\": \"fde24e102bd7947044e113ef1f6515bc5700ac37\", \"service_manager_unittests\": \"09b273247026f1a9cd179e0850069e9ade0816f9\", \"services_unittests\": \"7e6c9593da3ec6416dffe01a656d853e04c65edc\", \"setup_unittests\": \"feac415d1aaf4b6e60b834ca196f64f5e7588af5\", \"shell_dialogs_unittests\": \"c615031177c8330bc6d1fc14e51ce4ce370b83d1\", \"skia_unittests\": \"d3a821da3c797ee528b48342618f198f37a46351\", \"snapshot_unittests\": \"7d0f808b8bf68f33f6b573248e8b8c7f830fbb9b\", \"sql_unittests\": \"551259d0d96367b3b08401703f2a07c22a3fcff9\", \"storage_unittests\": \"cd0229a5def32a21b8f34f1efe3193e93dcb1045\", \"sync_integration_tests\": \"bf1186a868647920d30c92eb1b53196f8e2449cd\", \"traffic_annotation_auditor_unittests\": \"8a37abac64bbe7d553b55f61562626b1fc5c1520\", \"ui_base_unittests\": \"c8514d4ee66c220217356c869bd7dd129247bca8\", \"ui_touch_selection_unittests\": \"3f6367a81734150cfa52ef37efabd2a959cecb4a\", \"unit_tests\": \"f91401dc31d8f45ced3caa95a3d56a05bac0b860\", \"updater_tests\": \"384a91ac89f3ea1ac5dc1edf7960e1a0a780e45e\", \"url_unittests\": \"07c998e6808ffcc4e2cb10f99b3644675a9316c4\", \"views_unittests\": \"9b9c4adb9afc0ac2748a1ccc72d8185af8957adf\", \"viz_unittests\": \"30a8d2648c62d438a6dd2d309f2ef90d148484f2\", \"vr_common_unittests\": \"b1e361961af7f0cbbb30a9a4bf60f0f4075e41e1\", \"vr_pixeltests\": \"7addab2b0e9e144bca6e5ee6e59546945eb11fe8\", \"weblayer_browsertests\": \"f970233bbab7fbe1c93be135e62591646562db23\", \"weblayer_unittests\": \"25954f802c9212eb43c1345dd7e3754e10c4735c\", \"wm_unittests\": \"1661d1f450c038e710e4bee40602d25f57f40690\", \"wtf_unittests\": \"4af72989f988326b69973b067742fcdb039177e0\", \"zlib_unittests\": \"c340da81e5fb0a6f815974026c10b56fa5c153d6\", \"zucchini_unittests\": \"25004f2a1b8e10536ea7709d8efd7d2007db9cac\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-2-zecp\"], \"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\": [\"5754-8c4f760\"], \"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": "1615594474821353",
"status": "COMPLETED",
"status_changed_ts": "1615600717088782",
"tags": [
"build_address:luci.chromium.ci/win-asan/17780",
"builder:win-asan",
"buildset:commit/git/dcba975a478f9d869626b1f6c962823da2287555",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dcba975a478f9d869626b1f6c962823da2287555",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018350306691138432",
"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/8852920244609223456/+/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:5241bf17d8d4dc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615600717092789",
"url": "https://ci.chromium.org/b/8852920244609223456",
"utcnow_ts": "1615600717959794"
}
}
[01:58:37.968] Invocation finished in 1h45m59.605733396s with status SUCCEEDED