[12:26:30.670] New invocation is queued and will start shortly
[12:26:31.949] Starting the invocation (attempt 1)
[12:26:31.984] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:26:31.984] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:26:31.984] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026729653363446112",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"305d6bc623f7897c352edbc7b55598853d7585ca\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026729653363446112",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/305d6bc623f7897c352edbc7b55598853d7585ca",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/305d6bc623f7897c352edbc7b55598853d7585ca",
"gitiles_ref:refs/heads/master"
]
}
[12:26:33.082] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607603192104173",
"id": "8861299591194830896",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"305d6bc623f7897c352edbc7b55598853d7585ca\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607603192959294",
"tags": [
"build_address:luci.chromium.ci/win-asan/16139",
"builder:win-asan",
"buildset:commit/git/305d6bc623f7897c352edbc7b55598853d7585ca",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/305d6bc623f7897c352edbc7b55598853d7585ca",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026729653363446112",
"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/8861299591194830896/+/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": "1607603192959417",
"url": "https://ci.chromium.org/b/8861299591194830896",
"utcnow_ts": "1607603193074355"
}
}
[12:26:33.082] Task URL: https://ci.chromium.org/b/8861299591194830896
[12:26:33.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:2:0) after 1m0s
[12:26:35.090] Received PubSub notification, asking Buildbucket for the build status
[12:26:35.156] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:33.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:2:0)
[12:27:33.234] Timer tick, asking Buildbucket for the build status
[12:27:33.420] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:33.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:4:0) after 1m0s
[12:28:33.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:4:0)
[12:28:33.486] Timer tick, asking Buildbucket for the build status
[12:28:33.581] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:33.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:5:0) after 1m0s
[12:29:33.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:5:0)
[12:29:33.615] Timer tick, asking Buildbucket for the build status
[12:29:33.717] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:33.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:6:0) after 1m0s
[12:30:33.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:6:0)
[12:30:33.790] Timer tick, asking Buildbucket for the build status
[12:30:33.892] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:33.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:7:0) after 1m0s
[12:31:33.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:7:0)
[12:31:33.885] Timer tick, asking Buildbucket for the build status
[12:31:34.073] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:34.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:8:0) after 1m0s
[12:32:34.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:8:0)
[12:32:34.098] Timer tick, asking Buildbucket for the build status
[12:32:34.424] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:34.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:9:0) after 1m0s
[12:33:34.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:9:0)
[12:33:34.443] Timer tick, asking Buildbucket for the build status
[12:33:34.544] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:34.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:10:0) after 1m0s
[12:34:34.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:10:0)
[12:34:34.572] Timer tick, asking Buildbucket for the build status
[12:34:34.638] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:34.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:11:0) after 1m0s
[12:35:34.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:11:0)
[12:35:34.715] Timer tick, asking Buildbucket for the build status
[12:35:34.773] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:34.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:12:0) after 1m0s
[12:36:34.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:12:0)
[12:36:34.939] Timer tick, asking Buildbucket for the build status
[12:36:35.065] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:35.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:13:0) after 1m0s
[12:37:35.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:13:0)
[12:37:35.231] Timer tick, asking Buildbucket for the build status
[12:37:35.285] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:35.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:14:0) after 1m0s
[12:38:35.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:14:0)
[12:38:35.371] Timer tick, asking Buildbucket for the build status
[12:38:35.455] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:35.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:15:0) after 1m0s
[12:39:35.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:15:0)
[12:39:35.558] Timer tick, asking Buildbucket for the build status
[12:39:35.621] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:35.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:16:0) after 1m0s
[12:40:35.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:16:0)
[12:40:35.727] Timer tick, asking Buildbucket for the build status
[12:40:35.792] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:35.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:17:0) after 1m0s
[12:41:35.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:17:0)
[12:41:35.912] Timer tick, asking Buildbucket for the build status
[12:41:36.045] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:36.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:18:0) after 1m0s
[12:42:36.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:18:0)
[12:42:36.036] Timer tick, asking Buildbucket for the build status
[12:42:36.179] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:36.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:19:0) after 1m0s
[12:43:36.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:19:0)
[12:43:36.208] Timer tick, asking Buildbucket for the build status
[12:43:36.502] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:36.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:20:0) after 1m0s
[12:44:36.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:20:0)
[12:44:36.531] Timer tick, asking Buildbucket for the build status
[12:44:36.592] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:36.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:21:0) after 1m0s
[12:45:36.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:21:0)
[12:45:36.667] Timer tick, asking Buildbucket for the build status
[12:45:36.796] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:36.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:22:0) after 1m0s
[12:46:36.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:22:0)
[12:46:36.867] Timer tick, asking Buildbucket for the build status
[12:46:37.014] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:37.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:23:0) after 1m0s
[12:47:37.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:23:0)
[12:47:37.135] Timer tick, asking Buildbucket for the build status
[12:47:37.253] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:37.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:24:0) after 1m0s
[12:48:37.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:24:0)
[12:48:37.294] Timer tick, asking Buildbucket for the build status
[12:48:37.478] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:37.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:25:0) after 1m0s
[12:49:37.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:25:0)
[12:49:37.537] Timer tick, asking Buildbucket for the build status
[12:49:37.651] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:37.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:26:0) after 1m0s
[12:50:37.652] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:26:0)
[12:50:37.652] Timer tick, asking Buildbucket for the build status
[12:50:37.767] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:37.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:27:0) after 1m0s
[12:51:37.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:27:0)
[12:51:37.819] Timer tick, asking Buildbucket for the build status
[12:51:38.204] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:38.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:28:0) after 1m0s
[12:52:38.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:28:0)
[12:52:38.257] Timer tick, asking Buildbucket for the build status
[12:52:38.386] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:38.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:29:0) after 1m0s
[12:53:38.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:29:0)
[12:53:38.409] Timer tick, asking Buildbucket for the build status
[12:53:38.530] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:38.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:30:0) after 1m0s
[12:54:38.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:30:0)
[12:54:38.582] Timer tick, asking Buildbucket for the build status
[12:54:38.701] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:38.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:31:0) after 1m0s
[12:55:38.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:31:0)
[12:55:38.754] Timer tick, asking Buildbucket for the build status
[12:55:38.887] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:38.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:32:0) after 1m0s
[12:56:38.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:32:0)
[12:56:38.945] Timer tick, asking Buildbucket for the build status
[12:56:39.077] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:39.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:33:0) after 1m0s
[12:57:39.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:33:0)
[12:57:39.117] Timer tick, asking Buildbucket for the build status
[12:57:39.227] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:39.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:34:0) after 1m0s
[12:58:39.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:34:0)
[12:58:39.248] Timer tick, asking Buildbucket for the build status
[12:58:39.328] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:39.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:35:0) after 1m0s
[12:59:39.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:35:0)
[12:59:39.459] Timer tick, asking Buildbucket for the build status
[12:59:39.778] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:39.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:36:0) after 1m0s
[13:00:39.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:36:0)
[13:00:39.873] Timer tick, asking Buildbucket for the build status
[13:00:40.092] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:40.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:37:0) after 1m0s
[13:01:40.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:37:0)
[13:01:40.154] Timer tick, asking Buildbucket for the build status
[13:01:41.817] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:41.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:38:0) after 1m0s
[13:02:41.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:38:0)
[13:02:41.947] Timer tick, asking Buildbucket for the build status
[13:02:42.064] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:42.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:39:0) after 1m0s
[13:03:42.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:39:0)
[13:03:42.124] Timer tick, asking Buildbucket for the build status
[13:03:42.219] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:42.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:40:0) after 1m0s
[13:04:42.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:40:0)
[13:04:42.293] Timer tick, asking Buildbucket for the build status
[13:04:42.386] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:42.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:41:0) after 1m0s
[13:05:42.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:41:0)
[13:05:42.429] Timer tick, asking Buildbucket for the build status
[13:05:42.505] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:42.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:42:0) after 1m0s
[13:06:42.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:42:0)
[13:06:42.551] Timer tick, asking Buildbucket for the build status
[13:06:42.707] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:42.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:43:0) after 1m0s
[13:07:42.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:43:0)
[13:07:42.762] Timer tick, asking Buildbucket for the build status
[13:07:43.238] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:43.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:44:0) after 1m0s
[13:08:43.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:44:0)
[13:08:43.269] Timer tick, asking Buildbucket for the build status
[13:08:43.591] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:43.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:45:0) after 1m0s
[13:09:43.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:45:0)
[13:09:43.572] Timer tick, asking Buildbucket for the build status
[13:09:43.691] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:43.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:46:0) after 1m0s
[13:10:43.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:46:0)
[13:10:43.719] Timer tick, asking Buildbucket for the build status
[13:10:44.017] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:44.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:47:0) after 1m0s
[13:11:44.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:47:0)
[13:11:44.127] Timer tick, asking Buildbucket for the build status
[13:11:44.536] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:44.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:48:0) after 1m0s
[13:12:44.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:48:0)
[13:12:44.576] Timer tick, asking Buildbucket for the build status
[13:12:44.948] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:44.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:49:0) after 1m0s
[13:13:45.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:49:0)
[13:13:45.034] Timer tick, asking Buildbucket for the build status
[13:13:45.173] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:45.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:50:0) after 1m0s
[13:14:45.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:50:0)
[13:14:45.315] Timer tick, asking Buildbucket for the build status
[13:14:45.493] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:45.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:51:0) after 1m0s
[13:15:45.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:51:0)
[13:15:45.588] Timer tick, asking Buildbucket for the build status
[13:15:45.707] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:45.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:52:0) after 1m0s
[13:16:45.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:52:0)
[13:16:45.794] Timer tick, asking Buildbucket for the build status
[13:16:45.907] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:45.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:53:0) after 1m0s
[13:17:45.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:53:0)
[13:17:45.995] Timer tick, asking Buildbucket for the build status
[13:17:46.422] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:46.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:54:0) after 1m0s
[13:18:46.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:54:0)
[13:18:46.537] Timer tick, asking Buildbucket for the build status
[13:18:46.609] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:46.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:55:0) after 1m0s
[13:19:46.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:55:0)
[13:19:46.682] Timer tick, asking Buildbucket for the build status
[13:19:46.750] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:46.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:56:0) after 1m0s
[13:20:46.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:56:0)
[13:20:46.773] Timer tick, asking Buildbucket for the build status
[13:20:46.850] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:46.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:57:0) after 1m0s
[13:21:46.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:57:0)
[13:21:46.981] Timer tick, asking Buildbucket for the build status
[13:21:47.138] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:47.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:58:0) after 1m0s
[13:22:47.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:58:0)
[13:22:47.202] Timer tick, asking Buildbucket for the build status
[13:22:47.287] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:47.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:59:0) after 1m0s
[13:23:47.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:59:0)
[13:23:47.466] Timer tick, asking Buildbucket for the build status
[13:23:48.224] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:48.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:60:0) after 1m0s
[13:24:48.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:60:0)
[13:24:48.451] Timer tick, asking Buildbucket for the build status
[13:24:48.570] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:48.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:61:0) after 1m0s
[13:25:48.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:61:0)
[13:25:48.677] Timer tick, asking Buildbucket for the build status
[13:25:48.756] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:48.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:62:0) after 1m0s
[13:26:48.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:62:0)
[13:26:48.866] Timer tick, asking Buildbucket for the build status
[13:26:48.982] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:48.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:63:0) after 1m0s
[13:27:49.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:63:0)
[13:27:49.094] Timer tick, asking Buildbucket for the build status
[13:27:49.209] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:49.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:64:0) after 1m0s
[13:28:49.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:64:0)
[13:28:49.334] Timer tick, asking Buildbucket for the build status
[13:28:49.546] Build 8861299591194830896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:49.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026729653363446112:65:0) after 1m0s
[13:29:06.872] Received PubSub notification, asking Buildbucket for the build status
[13:29:06.967] Build 8861299591194830896: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:29:06.967] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607606945815307",
"created_by": "project:chromium",
"created_ts": "1607603192104173",
"id": "8861299591194830896",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"305d6bc623f7897c352edbc7b55598853d7585ca\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"305d6bc623f7897c352edbc7b55598853d7585ca\", \"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-3vmi\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16139, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"55b7f38f9036cc5d6b12cd77d56350799653efea\", \"got_dawn_revision\": \"29fd62989338a0d5812bc654a3afee6dc684bfc4\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"305d6bc623f7897c352edbc7b55598853d7585ca\", \"got_revision_cp\": \"refs/heads/master@{#835626}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"d94a77b304f4fcb503ea037be3e5d7f2a371c4b6\", \"got_v8_revision\": \"7e546be6b2f61eb429d51abb78e973f7f623f62f\", \"got_v8_revision_cp\": \"refs/heads/8.9.126@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"305d6bc623f7897c352edbc7b55598853d7585ca\", \"swarm_hashes_refs/heads/master(at){#835626}_without_patch\": {\"absl_hardening_tests\": \"10cb0d9cd3a969fb22162fa7a843d5a1b4368278\", \"accessibility_unittests\": \"2903deb69faf40fc4dd572d8e70a75de289d5062\", \"angle_unittests\": \"f30ad2d80ec10f0746010968646df4ff226f9dd9\", \"app_shell_unittests\": \"d7f223bb049b6b863bb9c526163ad2bd1c963983\", \"aura_unittests\": \"5c5cd0a7e2db5df4e5e602c8137e8d3f28d4c3f2\", \"base_unittests\": \"ca7737d2c4b8066bddad3951c482945928634842\", \"base_util_unittests\": \"75939606105ac499ebc7ce29068385ef0acb21dc\", \"blink_common_unittests\": \"c0bf717ee3d67e5f8ca31e17795c617d1d476538\", \"blink_fuzzer_unittests\": \"ca053e611d8ad8f6ed261bf2e18219150690539f\", \"blink_heap_unittests\": \"306e2bd4533379bf1175429bb4c39d3b579dab3e\", \"blink_platform_unittests\": \"4ab917c8f912fc1ce2c9fde2f4f6ef5474d13c0b\", \"blink_unittests\": \"49faf2cf410fd605d297a7fdd76d4cdb8da010b3\", \"boringssl_crypto_tests\": \"ab1a1e3e7f334c5b1bfbde35e2067eecfc7a7bbd\", \"boringssl_ssl_tests\": \"764ba4dc3929ab9d7905c283024963e9ee0fc975\", \"browser_switcher_bho_unittests\": \"f7170bdb9a823c80b0d3025e816a41026a9ac21d\", \"browser_tests\": \"fd0bb983ab50c2a69e46500d08d2f88431fcdc02\", \"capture_unittests\": \"f845c2dc15194f6c9da0bc04677cf4b0fdf3cafb\", \"cast_unittests\": \"92525c51055a379e5a1deb352f046649017ec67a\", \"cc_unittests\": \"fe29941ad044a5de934233937ed098b626269e4d\", \"chrome_app_unittests\": \"1a7336be66797fc1238b6615669bd62243d51f7c\", \"chrome_cleaner_unittests\": \"80f048e76c3379259f62f9296fd048a03d9b786e\", \"chrome_elf_unittests\": \"3eaab7450b7d688b7f01621e32526638c5a1fb8e\", \"chromedriver_unittests\": \"e00b7721c25b97ddac08eddd3e3ace4bef39d79b\", \"components_browsertests\": \"addc5dd70e201d2ea1e38e4fa7b9322ed659874d\", \"components_unittests\": \"9a7ec5b2d8b8683ce67dc05ef0df4bdb5180a41a\", \"compositor_unittests\": \"deb287d3d2a725d304b4db58ceac3388b637421c\", \"content_browsertests\": \"aa1dff6c4f27f401b25562f17b3650b59c38c7c8\", \"content_unittests\": \"957e4ae6a0c08fa0e0a512491a77781c7219c63f\", \"courgette_unittests\": \"5ef7f8571d89f2749c8640d63c0748b0d03d1fbd\", \"crashpad_tests\": \"19f4fa8f74370bd938cf7620c86469faaeb59397\", \"cronet_tests\": \"71648583f45a364f471556e594b5c905931112bb\", \"cronet_unittests\": \"2fcdad64e6aa4fbb15dfcf2cda10f7f995568738\", \"crypto_unittests\": \"b3659c7f9329ae23b26e54a8a4d7ef9cd200b3d4\", \"delayloads_unittests\": \"7e181841c920044a030f3132b6822cfb4d142991\", \"device_unittests\": \"5315f111e5d4f9002f0d1d12c7cbf121ae3220c8\", \"display_unittests\": \"80bac625c47ec5aa06f3fbb630fa5517c142d34b\", \"elevation_service_unittests\": \"b8f18b6704cd76221a5fc728da9f6385b025cbb1\", \"events_unittests\": \"754f8b64ac7d652e74f211f782b1ffcc852becbd\", \"extensions_browsertests\": \"3123a0154e9fbf80d71169619829010d03d41748\", \"extensions_unittests\": \"6f1de1da5267b2f694c04a9a7cfa8f561c331627\", \"filesystem_service_unittests\": \"a60d082b2002d474dc9651469379c5672e640964\", \"gcm_unit_tests\": \"6e3d2d209fe26caba8ff88ee3b4dd78ae1690a71\", \"gcp_unittests\": \"dd9717f502cb8f1e99e1bd53d740c27b997be112\", \"gfx_unittests\": \"3589926bed3c098b647b835608b5a7b54e40b183\", \"gin_unittests\": \"700e397f4826008a242dd5dbe5714e5fb9d03751\", \"google_apis_unittests\": \"6ef225558da8fe832c3a893e2a2a703abdec0086\", \"gpu_unittests\": \"2036cd6b63e49880577cb49515fb8b6c707ad594\", \"gwp_asan_unittests\": \"04c44bf29df0cab4f8f68cee76cb0e556084ed7b\", \"headless_browsertests\": \"0805006432f53948977ff9f945acccb77d47bc0b\", \"headless_unittests\": \"b6cb7193087b5b87306e94db23f7075aacf2f8f8\", \"install_static_unittests\": \"5bf5a03bffbc79652de4829959e003794d5c5261\", \"installer_util_unittests\": \"fb9eac1e2b09c87a08ef2b066bf5d6e5e9b13ccc\", \"interactive_ui_tests\": \"c5f0ee5150d8a6574087dba3476469b72bdff428\", \"ipc_tests\": \"d4727f9bb22edaa6f1fa398a1f0c70fce7430490\", \"jingle_unittests\": \"6c8425fdf29b016941e028297e8c761cc21fa5db\", \"latency_unittests\": \"2c72777ac74803f26adfeaabe196a115d123f8ac\", \"libjingle_xmpp_unittests\": \"fa8c34da6d9fb37e803d160eccbd69d863be6e03\", \"liburlpattern_unittests\": \"64e12104afff5442e7b1593975b3de1e99cf70f6\", \"media_blink_unittests\": \"8af869c1a6d8063eaa155081cfc9f1181fe004bb\", \"media_unittests\": \"8b41135d470cae96940c23415ccd0eaa048d6dd4\", \"message_center_unittests\": \"63644fce2f1422b13a4abd36442948a46d22596a\", \"midi_unittests\": \"9e5af0c6e76cb4ed3c60734fdb8766244e667b68\", \"mojo_core_unittests\": \"14c90b1ed1946cd34fa7d9a4be3b9e397dd51198\", \"mojo_unittests\": \"9b7c9db16b4d942269223b75e239030e243481db\", \"nacl_loader_unittests\": \"63dc6736244f268040d7d8e3712d68ec413398ab\", \"native_theme_unittests\": \"5529efdab896babff90075bcc3cd2e300d9f7670\", \"net_unittests\": \"00023b95695cceefcfc777e7be9abc96e3888f97\", \"notification_helper_unittests\": \"916ffbb83699777d316246b5f8ffb0b827bb1f7b\", \"pdf_unittests\": \"1c81bff53534dc76e5ac8e0ad24a2bccbc33b8de\", \"perfetto_unittests\": \"018f5d8142016c2e1183b3934859dc15177da4f3\", \"ppapi_unittests\": \"c81eabeec8398a12746bf47e48849f9f1619428b\", \"printing_unittests\": \"ded0ff5a082289caff5231b8cb71bd07e2a85adf\", \"remoting_unittests\": \"a5f08023078d001d78dd7f92193ffdf20cf959f5\", \"sbox_integration_tests\": \"235677d6689da7eef708171065db541920e94221\", \"sbox_unittests\": \"b6ce798064d254f9c5d3cee5505ac214feaf4b0e\", \"sbox_validation_tests\": \"1b72e35e3c8d7d5252f424e896dbb1fc4f70b467\", \"service_manager_unittests\": \"4e4d5b1489e1f32e2017228bc0e4fdee83ba9422\", \"services_unittests\": \"bcb81459d8be364b99e1e5a6a802f99f6f7bee8a\", \"setup_unittests\": \"44dd09d41dbf7b6331b68fefb7e07118afd264cc\", \"shell_dialogs_unittests\": \"667b07966252472a9e373bd4a8d435febdc35fa9\", \"skia_unittests\": \"2a000f1cc05031a97adcc37a8a6317cf029e05e3\", \"snapshot_unittests\": \"be7a206beaac0d6505bbb4679b2d0eabe000b1e2\", \"sql_unittests\": \"aff24703dd5684be6b5838bba4f75e747b2741b8\", \"storage_unittests\": \"b000a2940c03bc4d10269627b59ff36f9a87122e\", \"sync_integration_tests\": \"75a0b13460a1983668820d47bd761bed2f367927\", \"traffic_annotation_auditor_unittests\": \"fe7e3566bc02a4d9cd56b8ee35e580e969876046\", \"ui_base_unittests\": \"f6e30c20ace206df5d6d4aa787428ec205c29b4c\", \"ui_touch_selection_unittests\": \"ace257142f0378584cc4824df1c8cc9f23c2197c\", \"unit_tests\": \"cb02f69b661a1297b8a093d3364f9d0ea4f34b79\", \"updater_tests\": \"c2f38420e835616fb64a3ed38a100d5d64742d21\", \"url_unittests\": \"d56fdaab6c8b255bbbc700d234650e20a777158d\", \"views_unittests\": \"baf84a9d05cfd86cea881b8f27d05a33e14a377d\", \"viz_unittests\": \"eb4efbfa17b8ff2c52c0461abba80b303e849933\", \"vr_common_unittests\": \"69891853ad245d4a26a6390f2148d6a86d761a51\", \"vr_pixeltests\": \"170608bdb7d1f7ee0f4187b8824d38f8fc60b9ba\", \"weblayer_browsertests\": \"357abd8e8f09e84e61ab3c2c0d71782036b14ab8\", \"weblayer_unittests\": \"c5733ea7daf1a8d872b42d03e3393fe5e900556c\", \"wm_unittests\": \"ea89ea93d34ce09a7f31bcd3724d7f208e74b286\", \"wtf_unittests\": \"7acb3971d6de83edab09e42bd0b7370be64aad4b\", \"zlib_unittests\": \"524e025f64c2d1fb6720bd29b409750157610dff\", \"zucchini_unittests\": \"c5c4bee6c7ddfad2cc0f3b54703abc7d326eaff9\"}}, \"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-3vmi\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607603194380190",
"status": "COMPLETED",
"status_changed_ts": "1607606946177869",
"tags": [
"build_address:luci.chromium.ci/win-asan/16139",
"builder:win-asan",
"buildset:commit/git/305d6bc623f7897c352edbc7b55598853d7585ca",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/305d6bc623f7897c352edbc7b55598853d7585ca",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026729653363446112",
"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/8861299591194830896/+/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:50656f8e1f9d4910",
"user_agent:luci-scheduler"
],
"updated_ts": "1607606946182678",
"url": "https://ci.chromium.org/b/8861299591194830896",
"utcnow_ts": "1607606946954335"
}
}
[13:29:06.968] Invocation finished in 1h2m36.315671287s with status SUCCEEDED