[05:30:12.576] New invocation is queued and will start shortly
[05:30:13.739] Starting the invocation (attempt 1)
[05:30:13.777] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:30:13.777] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:30:13.777] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019055102646312928",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"de3d5f822c65e694ba64963f656de41c53b0f88e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019055102646312928",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/de3d5f822c65e694ba64963f656de41c53b0f88e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/de3d5f822c65e694ba64963f656de41c53b0f88e",
"gitiles_ref:refs/heads/master"
]
}
[05:30:14.734] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614922213902541",
"id": "8853625040594212144",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"de3d5f822c65e694ba64963f656de41c53b0f88e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614922214570555",
"tags": [
"build_address:luci.chromium.ci/win-asan/17641",
"builder:win-asan",
"buildset:commit/git/de3d5f822c65e694ba64963f656de41c53b0f88e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/de3d5f822c65e694ba64963f656de41c53b0f88e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019055102646312928",
"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/8853625040594212144/+/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": "1614922214570695",
"url": "https://ci.chromium.org/b/8853625040594212144",
"utcnow_ts": "1614922214725964"
}
}
[05:30:14.734] Task URL: https://ci.chromium.org/b/8853625040594212144
[05:30:14.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:2:0) after 1m0s
[05:31:14.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:2:0)
[05:31:14.813] Timer tick, asking Buildbucket for the build status
[05:31:14.919] Build 8853625040594212144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:31:14.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:3:0) after 1m0s
[05:32:14.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:3:0)
[05:32:14.937] Timer tick, asking Buildbucket for the build status
[05:32:15.006] Build 8853625040594212144: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:32:15.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:4:0) after 1m0s
[05:33:03.735] Received PubSub notification, asking Buildbucket for the build status
[05:33:03.997] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:15.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:4:0)
[05:33:15.058] Timer tick, asking Buildbucket for the build status
[05:33:15.139] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:15.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:6:0) after 1m0s
[05:34:15.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:6:0)
[05:34:15.280] Timer tick, asking Buildbucket for the build status
[05:34:15.353] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:15.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:7:0) after 1m0s
[05:35:15.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:7:0)
[05:35:15.408] Timer tick, asking Buildbucket for the build status
[05:35:15.496] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:15.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:8:0) after 1m0s
[05:36:15.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:8:0)
[05:36:15.536] Timer tick, asking Buildbucket for the build status
[05:36:15.621] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:15.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:9:0) after 1m0s
[05:37:15.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:9:0)
[05:37:15.768] Timer tick, asking Buildbucket for the build status
[05:37:15.867] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:15.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:10:0) after 1m0s
[05:38:15.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:10:0)
[05:38:15.936] Timer tick, asking Buildbucket for the build status
[05:38:16.044] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:16.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:11:0) after 1m0s
[05:39:16.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:11:0)
[05:39:16.071] Timer tick, asking Buildbucket for the build status
[05:39:16.269] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:16.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:12:0) after 1m0s
[05:40:16.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:12:0)
[05:40:16.311] Timer tick, asking Buildbucket for the build status
[05:40:16.467] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:16.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:13:0) after 1m0s
[05:41:16.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:13:0)
[05:41:16.527] Timer tick, asking Buildbucket for the build status
[05:41:16.611] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:16.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:14:0) after 1m0s
[05:42:16.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:14:0)
[05:42:16.699] Timer tick, asking Buildbucket for the build status
[05:42:16.762] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:16.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:15:0) after 1m0s
[05:43:16.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:15:0)
[05:43:16.817] Timer tick, asking Buildbucket for the build status
[05:43:16.966] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:16.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:16:0) after 1m0s
[05:44:17.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:16:0)
[05:44:17.057] Timer tick, asking Buildbucket for the build status
[05:44:17.131] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:17.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:17:0) after 1m0s
[05:45:17.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:17:0)
[05:45:17.375] Timer tick, asking Buildbucket for the build status
[05:45:17.564] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:17.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:18:0) after 1m0s
[05:46:17.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:18:0)
[05:46:17.587] Timer tick, asking Buildbucket for the build status
[05:46:17.658] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:17.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:19:0) after 1m0s
[05:47:17.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:19:0)
[05:47:17.694] Timer tick, asking Buildbucket for the build status
[05:47:17.873] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:17.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:20:0) after 1m0s
[05:48:17.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:20:0)
[05:48:17.897] Timer tick, asking Buildbucket for the build status
[05:48:17.982] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:17.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:21:0) after 1m0s
[05:49:18.060] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:21:0)
[05:49:18.060] Timer tick, asking Buildbucket for the build status
[05:49:18.412] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:18.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:22:0) after 1m0s
[05:50:18.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:22:0)
[05:50:18.465] Timer tick, asking Buildbucket for the build status
[05:50:18.566] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:18.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:23:0) after 1m0s
[05:51:18.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:23:0)
[05:51:18.588] Timer tick, asking Buildbucket for the build status
[05:51:19.017] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:19.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:24:0) after 1m0s
[05:52:19.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:24:0)
[05:52:19.061] Timer tick, asking Buildbucket for the build status
[05:52:19.412] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:19.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:25:0) after 1m0s
[05:53:19.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:25:0)
[05:53:19.427] Timer tick, asking Buildbucket for the build status
[05:53:19.589] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:19.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:26:0) after 1m0s
[05:54:19.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:26:0)
[05:54:19.619] Timer tick, asking Buildbucket for the build status
[05:54:19.710] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:19.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:27:0) after 1m0s
[05:55:19.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:27:0)
[05:55:19.737] Timer tick, asking Buildbucket for the build status
[05:55:19.898] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:19.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:28:0) after 1m0s
[05:56:19.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:28:0)
[05:56:19.993] Timer tick, asking Buildbucket for the build status
[05:56:20.068] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:20.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:29:0) after 1m0s
[05:57:20.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:29:0)
[05:57:20.180] Timer tick, asking Buildbucket for the build status
[05:57:20.264] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:20.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:30:0) after 1m0s
[05:58:20.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:30:0)
[05:58:20.443] Timer tick, asking Buildbucket for the build status
[05:58:20.513] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:20.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:31:0) after 1m0s
[05:59:20.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:31:0)
[05:59:20.697] Timer tick, asking Buildbucket for the build status
[05:59:20.856] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:20.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:32:0) after 1m0s
[06:00:20.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:32:0)
[06:00:20.878] Timer tick, asking Buildbucket for the build status
[06:00:20.982] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:20.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:33:0) after 1m0s
[06:01:25.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:33:0)
[06:01:25.355] Timer tick, asking Buildbucket for the build status
[06:01:25.433] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:25.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:34:0) after 1m0s
[06:02:26.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:34:0)
[06:02:26.075] Timer tick, asking Buildbucket for the build status
[06:02:26.151] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:26.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:35:0) after 1m0s
[06:03:26.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:35:0)
[06:03:26.220] Timer tick, asking Buildbucket for the build status
[06:03:26.326] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:26.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:36:0) after 1m0s
[06:04:26.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:36:0)
[06:04:26.376] Timer tick, asking Buildbucket for the build status
[06:04:26.452] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:26.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:37:0) after 1m0s
[06:05:26.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:37:0)
[06:05:26.815] Timer tick, asking Buildbucket for the build status
[06:05:26.930] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:26.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:38:0) after 1m0s
[06:06:27.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:38:0)
[06:06:27.019] Timer tick, asking Buildbucket for the build status
[06:06:27.082] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:27.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:39:0) after 1m0s
[06:07:27.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:39:0)
[06:07:27.208] Timer tick, asking Buildbucket for the build status
[06:07:27.298] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:27.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:40:0) after 1m0s
[06:08:27.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:40:0)
[06:08:27.425] Timer tick, asking Buildbucket for the build status
[06:08:27.494] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:27.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:41:0) after 1m0s
[06:09:27.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:41:0)
[06:09:27.590] Timer tick, asking Buildbucket for the build status
[06:09:27.658] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:27.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:42:0) after 1m0s
[06:10:27.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:42:0)
[06:10:27.727] Timer tick, asking Buildbucket for the build status
[06:10:27.939] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:27.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:43:0) after 1m0s
[06:11:27.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:43:0)
[06:11:27.992] Timer tick, asking Buildbucket for the build status
[06:11:28.201] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:28.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:44:0) after 1m0s
[06:12:28.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:44:0)
[06:12:28.570] Timer tick, asking Buildbucket for the build status
[06:12:28.635] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:28.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:45:0) after 1m0s
[06:13:28.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:45:0)
[06:13:28.810] Timer tick, asking Buildbucket for the build status
[06:13:28.879] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:28.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:46:0) after 1m0s
[06:14:29.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:46:0)
[06:14:29.095] Timer tick, asking Buildbucket for the build status
[06:14:29.158] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:29.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:47:0) after 1m0s
[06:15:37.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:47:0)
[06:15:37.161] Timer tick, asking Buildbucket for the build status
[06:15:37.241] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:37.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:48:0) after 1m0s
[06:16:37.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:48:0)
[06:16:37.388] Timer tick, asking Buildbucket for the build status
[06:16:37.460] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:37.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:49:0) after 1m0s
[06:17:37.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:49:0)
[06:17:37.435] Timer tick, asking Buildbucket for the build status
[06:17:38.011] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:38.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:50:0) after 1m0s
[06:18:38.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:50:0)
[06:18:38.104] Timer tick, asking Buildbucket for the build status
[06:18:38.198] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:38.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:51:0) after 1m0s
[06:19:38.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:51:0)
[06:19:38.221] Timer tick, asking Buildbucket for the build status
[06:19:38.420] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:38.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:52:0) after 1m0s
[06:20:38.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:52:0)
[06:20:38.442] Timer tick, asking Buildbucket for the build status
[06:20:38.542] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:38.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:53:0) after 1m0s
[06:21:38.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:53:0)
[06:21:38.626] Timer tick, asking Buildbucket for the build status
[06:21:38.822] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:38.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:54:0) after 1m0s
[06:22:38.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:54:0)
[06:22:38.808] Timer tick, asking Buildbucket for the build status
[06:22:38.868] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:38.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:55:0) after 1m0s
[06:23:38.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:55:0)
[06:23:38.909] Timer tick, asking Buildbucket for the build status
[06:23:38.974] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:38.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:56:0) after 1m0s
[06:24:38.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:56:0)
[06:24:38.993] Timer tick, asking Buildbucket for the build status
[06:24:39.145] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:39.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:57:0) after 1m0s
[06:25:39.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:57:0)
[06:25:39.140] Timer tick, asking Buildbucket for the build status
[06:25:39.224] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:39.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:58:0) after 1m0s
[06:26:39.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:58:0)
[06:26:39.292] Timer tick, asking Buildbucket for the build status
[06:26:39.438] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:39.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:59:0) after 1m0s
[06:27:39.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:59:0)
[06:27:39.458] Timer tick, asking Buildbucket for the build status
[06:27:39.570] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:39.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:60:0) after 1m0s
[06:28:39.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:60:0)
[06:28:39.572] Timer tick, asking Buildbucket for the build status
[06:28:39.645] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:39.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:61:0) after 1m0s
[06:29:39.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:61:0)
[06:29:39.689] Timer tick, asking Buildbucket for the build status
[06:29:39.792] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:39.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:62:0) after 1m0s
[06:30:39.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:62:0)
[06:30:39.851] Timer tick, asking Buildbucket for the build status
[06:30:39.961] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:39.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:63:0) after 1m0s
[06:31:39.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:63:0)
[06:31:39.974] Timer tick, asking Buildbucket for the build status
[06:31:40.042] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:40.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:64:0) after 1m0s
[06:32:40.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:64:0)
[06:32:40.121] Timer tick, asking Buildbucket for the build status
[06:32:40.192] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:40.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:65:0) after 1m0s
[06:33:40.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:65:0)
[06:33:40.267] Timer tick, asking Buildbucket for the build status
[06:33:40.358] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:40.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:66:0) after 1m0s
[06:34:40.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:66:0)
[06:34:40.391] Timer tick, asking Buildbucket for the build status
[06:34:40.522] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:40.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:67:0) after 1m0s
[06:35:40.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:67:0)
[06:35:40.530] Timer tick, asking Buildbucket for the build status
[06:35:40.632] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:40.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:68:0) after 1m0s
[06:36:40.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:68:0)
[06:36:40.634] Timer tick, asking Buildbucket for the build status
[06:36:40.710] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:40.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:69:0) after 1m0s
[06:37:40.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:69:0)
[06:37:40.881] Timer tick, asking Buildbucket for the build status
[06:37:40.974] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:40.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:70:0) after 1m0s
[06:38:41.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:70:0)
[06:38:41.042] Timer tick, asking Buildbucket for the build status
[06:38:41.135] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:41.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:71:0) after 1m0s
[06:39:41.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:71:0)
[06:39:41.187] Timer tick, asking Buildbucket for the build status
[06:39:41.280] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:41.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:72:0) after 1m0s
[06:40:41.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:72:0)
[06:40:41.320] Timer tick, asking Buildbucket for the build status
[06:40:41.818] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:41.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:73:0) after 1m0s
[06:41:41.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:73:0)
[06:41:41.863] Timer tick, asking Buildbucket for the build status
[06:41:41.954] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:41.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:74:0) after 1m0s
[06:42:41.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:74:0)
[06:42:41.955] Timer tick, asking Buildbucket for the build status
[06:42:42.037] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:42.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:75:0) after 1m0s
[06:43:42.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:75:0)
[06:43:42.080] Timer tick, asking Buildbucket for the build status
[06:43:42.456] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:42.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:76:0) after 1m0s
[06:44:43.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:76:0)
[06:44:43.601] Timer tick, asking Buildbucket for the build status
[06:44:43.689] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:43.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:77:0) after 1m0s
[06:45:44.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:77:0)
[06:45:44.435] Timer tick, asking Buildbucket for the build status
[06:45:44.565] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:44.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:78:0) after 1m0s
[06:46:44.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:78:0)
[06:46:44.804] Timer tick, asking Buildbucket for the build status
[06:46:45.056] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:45.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:79:0) after 1m0s
[06:47:45.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:79:0)
[06:47:45.164] Timer tick, asking Buildbucket for the build status
[06:47:45.503] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:45.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:80:0) after 1m0s
[06:48:45.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:80:0)
[06:48:45.546] Timer tick, asking Buildbucket for the build status
[06:48:45.662] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:45.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:81:0) after 1m0s
[06:49:45.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:81:0)
[06:49:45.699] Timer tick, asking Buildbucket for the build status
[06:49:45.781] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:45.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:82:0) after 1m0s
[06:50:45.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:82:0)
[06:50:45.816] Timer tick, asking Buildbucket for the build status
[06:50:49.123] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:49.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:83:0) after 1m0s
[06:51:49.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:83:0)
[06:51:49.259] Timer tick, asking Buildbucket for the build status
[06:51:49.348] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:49.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:84:0) after 1m0s
[06:52:49.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:84:0)
[06:52:49.462] Timer tick, asking Buildbucket for the build status
[06:52:49.541] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:49.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:85:0) after 1m0s
[06:53:50.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:85:0)
[06:53:50.044] Timer tick, asking Buildbucket for the build status
[06:53:50.351] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:50.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:86:0) after 1m0s
[06:54:50.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:86:0)
[06:54:50.426] Timer tick, asking Buildbucket for the build status
[06:54:50.731] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:50.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:87:0) after 1m0s
[06:55:50.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:87:0)
[06:55:50.905] Timer tick, asking Buildbucket for the build status
[06:55:51.067] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:51.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:88:0) after 1m0s
[06:56:51.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:88:0)
[06:56:51.236] Timer tick, asking Buildbucket for the build status
[06:56:51.320] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:51.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:89:0) after 1m0s
[06:57:51.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:89:0)
[06:57:51.406] Timer tick, asking Buildbucket for the build status
[06:57:51.599] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:51.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:90:0) after 1m0s
[06:58:51.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:90:0)
[06:58:51.617] Timer tick, asking Buildbucket for the build status
[06:58:51.940] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:51.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:91:0) after 1m0s
[06:59:52.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:91:0)
[06:59:52.256] Timer tick, asking Buildbucket for the build status
[06:59:52.335] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:52.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:92:0) after 1m0s
[07:00:52.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:92:0)
[07:00:52.477] Timer tick, asking Buildbucket for the build status
[07:00:52.589] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:52.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:93:0) after 1m0s
[07:01:52.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:93:0)
[07:01:52.795] Timer tick, asking Buildbucket for the build status
[07:01:52.894] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:52.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:94:0) after 1m0s
[07:02:53.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:94:0)
[07:02:53.098] Timer tick, asking Buildbucket for the build status
[07:02:53.339] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:53.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:95:0) after 1m0s
[07:03:54.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:95:0)
[07:03:54.022] Timer tick, asking Buildbucket for the build status
[07:03:54.226] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:54.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:96:0) after 1m0s
[07:04:55.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:96:0)
[07:04:55.306] Timer tick, asking Buildbucket for the build status
[07:04:55.384] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:55.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:97:0) after 1m0s
[07:05:55.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:97:0)
[07:05:55.428] Timer tick, asking Buildbucket for the build status
[07:05:55.543] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:55.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:98:0) after 1m0s
[07:06:55.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:98:0)
[07:06:55.662] Timer tick, asking Buildbucket for the build status
[07:06:55.872] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:55.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:99:0) after 1m0s
[07:07:55.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:99:0)
[07:07:55.942] Timer tick, asking Buildbucket for the build status
[07:07:56.033] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:56.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:100:0) after 1m0s
[07:08:56.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:100:0)
[07:08:56.053] Timer tick, asking Buildbucket for the build status
[07:08:56.558] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:56.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:101:0) after 1m0s
[07:09:56.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:101:0)
[07:09:56.573] Timer tick, asking Buildbucket for the build status
[07:09:56.688] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:56.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:102:0) after 1m0s
[07:10:56.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:102:0)
[07:10:56.780] Timer tick, asking Buildbucket for the build status
[07:10:56.892] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:56.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:103:0) after 1m0s
[07:11:57.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:103:0)
[07:11:57.012] Timer tick, asking Buildbucket for the build status
[07:11:57.139] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:57.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:104:0) after 1m0s
[07:12:57.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:104:0)
[07:12:57.161] Timer tick, asking Buildbucket for the build status
[07:12:57.249] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:57.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:105:0) after 1m0s
[07:13:57.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:105:0)
[07:13:57.268] Timer tick, asking Buildbucket for the build status
[07:13:57.365] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:57.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:106:0) after 1m0s
[07:14:57.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:106:0)
[07:14:57.436] Timer tick, asking Buildbucket for the build status
[07:14:57.750] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:57.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:107:0) after 1m0s
[07:15:57.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:107:0)
[07:15:57.729] Timer tick, asking Buildbucket for the build status
[07:15:58.131] Build 8853625040594212144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:58.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019055102646312928:108:0) after 1m0s
[07:16:53.635] Received PubSub notification, asking Buildbucket for the build status
[07:16:53.766] Build 8853625040594212144: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:16:53.766] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614928610824055",
"created_by": "project:chromium",
"created_ts": "1614922213902541",
"id": "8853625040594212144",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"de3d5f822c65e694ba64963f656de41c53b0f88e\"}}",
"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\": \"de3d5f822c65e694ba64963f656de41c53b0f88e\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-6-55ju\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17641, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"fe2efefac498b56334ad167f174c32dff8914f48\", \"got_dawn_revision\": \"80915849ce7d5f4c0fe834924ed420ed0002b210\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"de3d5f822c65e694ba64963f656de41c53b0f88e\", \"got_revision_cp\": \"refs/heads/master@{#860135}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"6846389f25cad4233a4bd04fd3fc6c60ae30ff35\", \"got_v8_revision\": \"3bc17d5d7e100f5ffd1b05ac33cc86a5cce961cc\", \"got_v8_revision_cp\": \"refs/heads/9.1.48@{#1}\", \"got_webrtc_revision\": \"3208bf102b75e8ec1eaa6640dfa3b545310b1e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33382}\", \"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\": \"de3d5f822c65e694ba64963f656de41c53b0f88e\", \"swarm_hashes_refs/heads/master(at){#860135}_without_patch\": {\"absl_hardening_tests\": \"4c0ff2b151fe68688fadf7cd9b3e73c50f53f113\", \"accessibility_unittests\": \"7879156cdf9014fbbba20560fdf8004a71c17d49\", \"angle_unittests\": \"8744ddedeb5edcfdf9899c6017b875857303a468\", \"app_shell_unittests\": \"ea85f3b8f4e5ffc8516956448836c8d9ae3d54a7\", \"aura_unittests\": \"7fdbd30c879c58bacce3c8f0bd11a785dc7bebc2\", \"base_unittests\": \"32d4718844b5e5b9d136f3725b7366d87bb51e21\", \"base_util_unittests\": \"d1834486dbf9e483c6fdf215877761191b15a9cc\", \"blink_common_unittests\": \"7a5b0f2a851fbc1e0150a3285ada0d6b25bad687\", \"blink_fuzzer_unittests\": \"e5f118dbe821d28129e773694117db7f75edf87d\", \"blink_heap_unittests\": \"6a9a8cda09fd5bebe1245f93cfef64f5be9b30ea\", \"blink_platform_unittests\": \"d487e0483f97181089eef805a60c1a8bafd67742\", \"blink_unittests\": \"4a5cd524dc3e4db115a22962bf897ccb0794c6e3\", \"boringssl_crypto_tests\": \"2ac06684b2c0f391cbfe668a3d6607b81142186c\", \"boringssl_ssl_tests\": \"1b4dd1c5c271ff4e55a57641fe4e699abc5a3aa6\", \"browser_switcher_bho_unittests\": \"faba96fd3db89d9b021276438cf50d4debdc0bd3\", \"browser_tests\": \"1b05c81d3411da9455204ac19c35da927ee90e32\", \"capture_unittests\": \"b07a8ac3174247569015d1ac0e3e3595da5a2012\", \"cast_unittests\": \"82eaea482d01a2ca54a10e18d7d5194de4bffcda\", \"cc_unittests\": \"5d59ef53e525ebb842a5f9097239b141b21acbe0\", \"chrome_app_unittests\": \"4b43d308dcb716e037def2d31e6fed90716581b1\", \"chrome_cleaner_unittests\": \"3509f4119c09cba4c35ffcbd9aa6b10cf397f4e3\", \"chrome_elf_unittests\": \"71fbdf85add2eee1512c12e2b65782fc1cb69b3a\", \"chromedriver_unittests\": \"562dcb3e1a3823e4318ca0f8faafd384db65c2b0\", \"color_unittests\": \"9c08edf85116fb971bba13b2e6149a18dcfa2aee\", \"components_browsertests\": \"6086d4504f8ed8526c0e5fb5bc95ba277ed55508\", \"components_unittests\": \"4664e8dafe0c91e7785fe89dc6aab0f003bba669\", \"compositor_unittests\": \"a1e31a60dee65e36f832c5150a1ce2b49e431980\", \"content_browsertests\": \"b99fb8e5eb71b2be4111a24d319c3b2ea04362e3\", \"content_unittests\": \"eb70455283d6f3d4b55d5c53788f77bc870402bb\", \"courgette_unittests\": \"e3bb8804578a0087bce78426dd330d6d6bcfae44\", \"crashpad_tests\": \"fee4713bac455aaeb6fa867eacb17476227c37a2\", \"cronet_tests\": \"41adf1146f6aa257c04aea53913b710d5765a783\", \"cronet_unittests\": \"6db6466e3ebf7cacf6d0da02f76bbb7727b4cc28\", \"crypto_unittests\": \"1b223f753a47d40d47671c3834ae7a25e279cedb\", \"delayloads_unittests\": \"30f41d7f6b075799e99d502733879b019f7fc00d\", \"device_unittests\": \"9217e4a24e998fb67d7302c97189dcf773457f6b\", \"display_unittests\": \"30aeb2beeaf71454995c1e7d981b0344ed5e82a2\", \"elevation_service_unittests\": \"ef2cee8b99f791fa2165dd3c440e760c5a82c747\", \"events_unittests\": \"a73f2e9e77db483fbbb7392ef161f8a8b35bd56b\", \"extensions_browsertests\": \"07d77db2422f14d6a7b86af3ab7e258243747b35\", \"extensions_unittests\": \"3f19cf6bf993539235f3491ccac0bbde02e510ea\", \"filesystem_service_unittests\": \"2eef3ccf459b447f3f7889bb696cc341be94d9a5\", \"gcm_unit_tests\": \"dae6159c5b26471892da79043d242af9da165df5\", \"gcp_unittests\": \"1752333c9c8b646684a695141d7f51696550ef0d\", \"gfx_unittests\": \"b8ae42c682f5e510a744fa32f7429c503f07c109\", \"gin_unittests\": \"3f1c9dec54ae8fdec6d29565e9132f7aabcccaf0\", \"google_apis_unittests\": \"e9084c4054bfa9b3960935b8a66d6c7dd45b5fbc\", \"gpu_unittests\": \"70a9d2948725ec2f16e2ee8b70b66d0a53a0d1c6\", \"gwp_asan_unittests\": \"d0f9fc5a4a9ad3b28a107fc2f3431475824c0fb9\", \"headless_browsertests\": \"d4a7a0545b5a00076a9ca67da9b0a7da080a4d84\", \"headless_unittests\": \"7782214d28d13031ac08700b90b72730a9925d8c\", \"install_static_unittests\": \"747eb50fc73af8a2b6de8122d4017408262ca6a1\", \"installer_util_unittests\": \"0df23e7610e9b6a66aec1d07ac9a1d9b389363b9\", \"interactive_ui_tests\": \"589ff71ee5cdbe80a0a094d2adfc1c663d656e90\", \"ipc_tests\": \"86e6ee1b78de58c68c4bbdbc9192e91e1e2e26ab\", \"jingle_unittests\": \"2cb95145707eeab2eca5d5645e76b85698074060\", \"latency_unittests\": \"660e362bdea875176c4d97942afbf437b0468a10\", \"libjingle_xmpp_unittests\": \"ec5ee4fa136254c51fc5b14c8b2c49dd72a66b89\", \"liburlpattern_unittests\": \"f3d3f83bdfeddb27da7d751e7cb62c565b7f7673\", \"media_blink_unittests\": \"4d9b83a0e65791048947073a8680a4f0c3cfb6e3\", \"media_unittests\": \"93c8ef9f0028c07645ef304db3198bb67c15bc76\", \"message_center_unittests\": \"99791532801471dbb4c31a192f9b6ead53446180\", \"midi_unittests\": \"638f3dfaeaef7c52158294dafe72c43bb6b0cd70\", \"mojo_core_unittests\": \"8b8854d3b5ec812fdf3eae7acef5e2ab2c631453\", \"mojo_unittests\": \"c76e6cffcaf6234962b70d8d0032264fd5fa9c65\", \"nacl_loader_unittests\": \"2304ae5d363bf4ddf24029059958afe0e77d4d7c\", \"native_theme_unittests\": \"96cc5acc175100c117121ac8bd55274e4f7818da\", \"net_unittests\": \"b5366d2c58c26d69bb85b14862967da6363fe61f\", \"notification_helper_unittests\": \"29adbbf99dd2f71fa42742dbe47814e08ee5db73\", \"pdf_unittests\": \"85e7dd49294ad2e765234e43a18ad0b27a506bd9\", \"perfetto_unittests\": \"3231ae23cf2e6f1b93979c28a3b613c76670246b\", \"ppapi_unittests\": \"cd91998ab993134bfadaa6048cb09eeb7f5ecb30\", \"printing_unittests\": \"245d84530f469548acdec3a73523964a8446646f\", \"remoting_unittests\": \"26eb741331f8c2b811c99e22fe6aa80d8ec2b38d\", \"sbox_integration_tests\": \"8fc44f46b91f29c116c9eef80537ef7b4dc44abe\", \"sbox_unittests\": \"eccd358e8fda047076d7b6dbc705d75e5b3fc9f5\", \"sbox_validation_tests\": \"d2a5f46a191a80f8dfb5ef93f0a364e4a190c50c\", \"service_manager_unittests\": \"f0514071e962b5d436269720b0154c6eade6c90a\", \"services_unittests\": \"05917f0602a30b2457abdea1dbcdd81813ddba88\", \"setup_unittests\": \"ba9954bff3f2c683fea7743063386e42ea2e5014\", \"shell_dialogs_unittests\": \"cbe975320c98e3f0dd47a704c077a83ee6500d51\", \"skia_unittests\": \"1c3679f567bada234bd046385746d0e45583f6bc\", \"snapshot_unittests\": \"62f1699f65b6b8775f0cb1cda1bcfb04d0a7dc4b\", \"sql_unittests\": \"a2f7662074eb28788c37be41674cefa87c892502\", \"storage_unittests\": \"f391a2b10c5bcc2b0c16aef8c51169b0f725b1d7\", \"sync_integration_tests\": \"de98a012b3e59915c2e9244dfbcc831e17dd62e4\", \"traffic_annotation_auditor_unittests\": \"25d8ba0c5203667bc8f3eb007985a27561505e49\", \"ui_base_unittests\": \"8baed59498fb74732137688df1caf453982a63b4\", \"ui_touch_selection_unittests\": \"4a59708b4f76f7c618162ab8085771ab7c544d41\", \"unit_tests\": \"8a090feb4bb23a0b1c5f00f6f8d11a2b5590898e\", \"updater_tests\": \"40851839835c8cf19a6ba77bd65d965cf752f4cf\", \"url_unittests\": \"568b34f35aa6c480f5a8f284c67e37c229189852\", \"views_unittests\": \"eb90ab968a906b15d573bcf1f13458fd76bb5456\", \"viz_unittests\": \"1d6e0da15493bd807eb8d00866bec61cffe90f2b\", \"vr_common_unittests\": \"8176ea2f45973d2f2fb2ffca9cbd7a0f04d58e26\", \"vr_pixeltests\": \"f10eada38c90117a86d1fefb1818d0dcad0b5171\", \"weblayer_browsertests\": \"e6721451eeffb531715424f9ea43f5ab0ac0d115\", \"weblayer_unittests\": \"42017dab3d73e46adf9c333fcce3baafe1f10320\", \"wm_unittests\": \"93796a6d420395f32081d45f38144d17bc541a75\", \"wtf_unittests\": \"26f679100ae6ed152e194ef454ee19fa8c8de4d6\", \"zlib_unittests\": \"0c19d05422c62501130102b3fe10cd80f5bd0746\", \"zucchini_unittests\": \"b765e02c2867b5e283e41d009517c330dbbec958\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-55ju\"], \"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\": [\"5725-1a0d85e\"], \"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": "1614922383138251",
"status": "COMPLETED",
"status_changed_ts": "1614928611289229",
"tags": [
"build_address:luci.chromium.ci/win-asan/17641",
"builder:win-asan",
"buildset:commit/git/de3d5f822c65e694ba64963f656de41c53b0f88e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/de3d5f822c65e694ba64963f656de41c53b0f88e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019055102646312928",
"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/8853625040594212144/+/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:5219aef687918210",
"user_agent:luci-scheduler"
],
"updated_ts": "1614928611308870",
"url": "https://ci.chromium.org/b/8853625040594212144",
"utcnow_ts": "1614928613749057"
}
}
[07:16:53.766] Invocation finished in 1h46m41.20568084s with status SUCCEEDED