[04:06:24.278] New invocation is queued and will start shortly
[04:06:25.362] Starting the invocation (attempt 1)
[04:06:25.392] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:06:25.393] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:06:25.393] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020962911497568608",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"76074de63fc6b08280bb2e29c089aa1aa306bfa3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020962911497568608",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/76074de63fc6b08280bb2e29c089aa1aa306bfa3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/76074de63fc6b08280bb2e29c089aa1aa306bfa3",
"gitiles_ref:refs/heads/master"
]
}
[04:06:27.069] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613102785479266",
"id": "8855532849571581984",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"76074de63fc6b08280bb2e29c089aa1aa306bfa3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613102785908665",
"tags": [
"build_address:luci.chromium.ci/win-asan/17292",
"builder:win-asan",
"buildset:commit/git/76074de63fc6b08280bb2e29c089aa1aa306bfa3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/76074de63fc6b08280bb2e29c089aa1aa306bfa3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020962911497568608",
"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/8855532849571581984/+/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": "1613102785908796",
"url": "https://ci.chromium.org/b/8855532849571581984",
"utcnow_ts": "1613102787049594"
}
}
[04:06:27.069] Task URL: https://ci.chromium.org/b/8855532849571581984
[04:06:27.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:2:0) after 1m0s
[04:06:29.715] Received PubSub notification, asking Buildbucket for the build status
[04:06:30.094] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:27.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:2:0)
[04:07:27.168] Timer tick, asking Buildbucket for the build status
[04:07:27.304] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:27.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:4:0) after 1m0s
[04:08:27.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:4:0)
[04:08:27.319] Timer tick, asking Buildbucket for the build status
[04:08:27.556] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:27.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:5:0) after 1m0s
[04:09:27.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:5:0)
[04:09:27.615] Timer tick, asking Buildbucket for the build status
[04:09:27.738] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:27.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:6:0) after 1m0s
[04:10:27.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:6:0)
[04:10:27.724] Timer tick, asking Buildbucket for the build status
[04:10:28.570] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:28.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:7:0) after 1m0s
[04:11:28.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:7:0)
[04:11:28.562] Timer tick, asking Buildbucket for the build status
[04:11:28.679] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:28.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:8:0) after 1m0s
[04:12:28.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:8:0)
[04:12:28.726] Timer tick, asking Buildbucket for the build status
[04:12:28.821] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:28.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:9:0) after 1m0s
[04:13:28.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:9:0)
[04:13:28.857] Timer tick, asking Buildbucket for the build status
[04:13:28.961] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:28.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:10:0) after 1m0s
[04:14:28.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:10:0)
[04:14:28.982] Timer tick, asking Buildbucket for the build status
[04:14:29.209] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:29.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:11:0) after 1m0s
[04:15:29.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:11:0)
[04:15:29.259] Timer tick, asking Buildbucket for the build status
[04:15:29.364] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:29.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:12:0) after 1m0s
[04:16:29.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:12:0)
[04:16:29.441] Timer tick, asking Buildbucket for the build status
[04:16:29.622] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:29.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:13:0) after 1m0s
[04:17:29.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:13:0)
[04:17:29.671] Timer tick, asking Buildbucket for the build status
[04:17:29.745] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:29.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:14:0) after 1m0s
[04:18:29.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:14:0)
[04:18:29.780] Timer tick, asking Buildbucket for the build status
[04:18:29.847] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:29.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:15:0) after 1m0s
[04:19:29.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:15:0)
[04:19:29.827] Timer tick, asking Buildbucket for the build status
[04:19:29.926] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:29.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:16:0) after 1m0s
[04:20:29.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:16:0)
[04:20:29.930] Timer tick, asking Buildbucket for the build status
[04:20:30.028] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:30.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:17:0) after 1m0s
[04:21:30.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:17:0)
[04:21:30.129] Timer tick, asking Buildbucket for the build status
[04:21:30.233] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:30.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:18:0) after 1m0s
[04:22:30.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:18:0)
[04:22:30.251] Timer tick, asking Buildbucket for the build status
[04:22:30.345] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:30.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:19:0) after 1m0s
[04:23:30.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:19:0)
[04:23:30.437] Timer tick, asking Buildbucket for the build status
[04:23:30.535] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:30.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:20:0) after 1m0s
[04:24:30.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:20:0)
[04:24:30.589] Timer tick, asking Buildbucket for the build status
[04:24:30.670] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:30.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:21:0) after 1m0s
[04:25:30.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:21:0)
[04:25:30.703] Timer tick, asking Buildbucket for the build status
[04:25:30.796] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:30.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:22:0) after 1m0s
[04:26:30.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:22:0)
[04:26:30.826] Timer tick, asking Buildbucket for the build status
[04:26:30.924] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:30.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:23:0) after 1m0s
[04:27:30.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:23:0)
[04:27:30.894] Timer tick, asking Buildbucket for the build status
[04:27:30.964] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:30.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:24:0) after 1m0s
[04:28:30.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:24:0)
[04:28:30.986] Timer tick, asking Buildbucket for the build status
[04:28:31.077] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:31.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:25:0) after 1m0s
[04:29:31.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:25:0)
[04:29:31.104] Timer tick, asking Buildbucket for the build status
[04:29:31.201] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:31.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:26:0) after 1m0s
[04:30:31.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:26:0)
[04:30:31.244] Timer tick, asking Buildbucket for the build status
[04:30:31.347] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:31.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:27:0) after 1m0s
[04:31:31.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:27:0)
[04:31:31.357] Timer tick, asking Buildbucket for the build status
[04:31:31.481] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:31.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:28:0) after 1m0s
[04:32:31.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:28:0)
[04:32:31.529] Timer tick, asking Buildbucket for the build status
[04:32:31.608] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:31.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:29:0) after 1m0s
[04:33:31.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:29:0)
[04:33:31.634] Timer tick, asking Buildbucket for the build status
[04:33:31.743] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:31.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:30:0) after 1m0s
[04:34:31.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:30:0)
[04:34:31.957] Timer tick, asking Buildbucket for the build status
[04:34:32.343] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:32.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:31:0) after 1m0s
[04:35:32.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:31:0)
[04:35:32.618] Timer tick, asking Buildbucket for the build status
[04:35:32.712] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:32.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:32:0) after 1m0s
[04:36:32.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:32:0)
[04:36:32.829] Timer tick, asking Buildbucket for the build status
[04:36:32.960] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:32.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:33:0) after 1m0s
[04:37:33.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:33:0)
[04:37:33.034] Timer tick, asking Buildbucket for the build status
[04:37:33.630] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:33.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:34:0) after 1m0s
[04:38:33.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:34:0)
[04:38:33.665] Timer tick, asking Buildbucket for the build status
[04:38:34.037] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:34.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:35:0) after 1m0s
[04:39:34.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:35:0)
[04:39:34.168] Timer tick, asking Buildbucket for the build status
[04:39:34.301] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:34.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:36:0) after 1m0s
[04:40:34.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:36:0)
[04:40:34.378] Timer tick, asking Buildbucket for the build status
[04:40:34.463] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:34.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:37:0) after 1m0s
[04:41:34.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:37:0)
[04:41:34.554] Timer tick, asking Buildbucket for the build status
[04:41:34.863] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:34.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:38:0) after 1m0s
[04:42:34.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:38:0)
[04:42:34.920] Timer tick, asking Buildbucket for the build status
[04:42:35.028] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:35.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:39:0) after 1m0s
[04:43:35.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:39:0)
[04:43:35.045] Timer tick, asking Buildbucket for the build status
[04:43:35.177] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:35.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:40:0) after 1m0s
[04:44:35.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:40:0)
[04:44:35.203] Timer tick, asking Buildbucket for the build status
[04:44:35.513] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:35.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:41:0) after 1m0s
[04:45:35.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:41:0)
[04:45:35.585] Timer tick, asking Buildbucket for the build status
[04:45:35.690] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:35.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:42:0) after 1m0s
[04:46:35.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:42:0)
[04:46:35.755] Timer tick, asking Buildbucket for the build status
[04:46:46.186] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:46.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:43:0) after 1m0s
[04:47:46.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:43:0)
[04:47:46.314] Timer tick, asking Buildbucket for the build status
[04:47:46.412] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:46.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:44:0) after 1m0s
[04:48:46.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:44:0)
[04:48:46.447] Timer tick, asking Buildbucket for the build status
[04:48:46.645] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:46.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:45:0) after 1m0s
[04:49:46.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:45:0)
[04:49:46.675] Timer tick, asking Buildbucket for the build status
[04:49:47.235] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:47.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:46:0) after 1m0s
[04:50:47.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:46:0)
[04:50:47.420] Timer tick, asking Buildbucket for the build status
[04:50:47.508] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:47.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:47:0) after 1m0s
[04:51:47.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:47:0)
[04:51:47.491] Timer tick, asking Buildbucket for the build status
[04:51:47.848] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:47.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:48:0) after 1m0s
[04:52:47.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:48:0)
[04:52:47.881] Timer tick, asking Buildbucket for the build status
[04:52:47.990] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:47.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:49:0) after 1m0s
[04:53:48.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:49:0)
[04:53:48.053] Timer tick, asking Buildbucket for the build status
[04:53:48.145] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:48.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:50:0) after 1m0s
[04:54:48.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:50:0)
[04:54:48.211] Timer tick, asking Buildbucket for the build status
[04:54:48.370] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:48.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:51:0) after 1m0s
[04:55:48.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:51:0)
[04:55:48.403] Timer tick, asking Buildbucket for the build status
[04:55:48.511] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:48.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:52:0) after 1m0s
[04:56:48.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:52:0)
[04:56:48.507] Timer tick, asking Buildbucket for the build status
[04:56:48.995] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:48.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:53:0) after 1m0s
[04:57:48.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:53:0)
[04:57:48.981] Timer tick, asking Buildbucket for the build status
[04:57:49.081] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:49.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:54:0) after 1m0s
[04:58:49.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:54:0)
[04:58:49.117] Timer tick, asking Buildbucket for the build status
[04:58:49.446] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:49.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:55:0) after 1m0s
[04:59:49.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:55:0)
[04:59:49.556] Timer tick, asking Buildbucket for the build status
[04:59:49.656] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:49.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:56:0) after 1m0s
[05:00:49.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:56:0)
[05:00:49.694] Timer tick, asking Buildbucket for the build status
[05:00:50.130] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:50.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:57:0) after 1m0s
[05:01:50.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:57:0)
[05:01:50.135] Timer tick, asking Buildbucket for the build status
[05:01:50.272] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:50.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:58:0) after 1m0s
[05:02:50.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:58:0)
[05:02:50.321] Timer tick, asking Buildbucket for the build status
[05:02:50.408] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:50.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:59:0) after 1m0s
[05:03:50.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:59:0)
[05:03:50.458] Timer tick, asking Buildbucket for the build status
[05:03:50.548] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:50.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:60:0) after 1m0s
[05:04:50.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:60:0)
[05:04:50.609] Timer tick, asking Buildbucket for the build status
[05:04:50.716] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:50.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:61:0) after 1m0s
[05:05:50.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:61:0)
[05:05:50.768] Timer tick, asking Buildbucket for the build status
[05:05:50.853] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:50.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:62:0) after 1m0s
[05:06:50.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:62:0)
[05:06:50.968] Timer tick, asking Buildbucket for the build status
[05:06:51.172] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:51.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:63:0) after 1m0s
[05:07:51.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:63:0)
[05:07:51.236] Timer tick, asking Buildbucket for the build status
[05:07:51.328] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:51.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:64:0) after 1m0s
[05:08:51.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:64:0)
[05:08:51.375] Timer tick, asking Buildbucket for the build status
[05:08:51.493] Build 8855532849571581984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:51.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020962911497568608:65:0) after 1m0s
[05:09:46.353] Received PubSub notification, asking Buildbucket for the build status
[05:09:46.452] Build 8855532849571581984: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:09:46.452] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613106585495920",
"created_by": "project:chromium",
"created_ts": "1613102785479266",
"id": "8855532849571581984",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"76074de63fc6b08280bb2e29c089aa1aa306bfa3\"}}",
"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\": \"76074de63fc6b08280bb2e29c089aa1aa306bfa3\", \"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-1-ckf5\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17292, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"d44159c23c104746dd12b35d251922d89d45f4e8\", \"got_nacl_revision\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\", \"got_revision\": \"76074de63fc6b08280bb2e29c089aa1aa306bfa3\", \"got_revision_cp\": \"refs/heads/master@{#853389}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"673a7fe5c3ff6686c98951cafb24a93ca5bd1303\", \"got_v8_revision\": \"358aa1391624d156e3a358fae69b15a0dcee52b9\", \"got_v8_revision_cp\": \"refs/heads/9.0.166@{#1}\", \"got_webrtc_revision\": \"f28d7e89b7a3c985d83f59d16b9f3c3f45ef7997\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33224}\", \"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\": \"76074de63fc6b08280bb2e29c089aa1aa306bfa3\", \"swarm_hashes_refs/heads/master(at){#853389}_without_patch\": {\"absl_hardening_tests\": \"3a039c5b9cd9a9dfb16d1981e953185ef2f42759\", \"accessibility_unittests\": \"5e25c78bc9583a8488cb5f437fe9fbc6cd7b4adf\", \"angle_unittests\": \"f60d5b6ce4bb554d08f08519285c043ff4885cfb\", \"app_shell_unittests\": \"8f33e272a752fdb8c69a5cf9af75f8c0b9bde7ff\", \"aura_unittests\": \"5d5787c2071f93ed3ed89f269d187059f04c7269\", \"base_unittests\": \"27b84f4263c564ba42bcd348066dc4ca96673baf\", \"base_util_unittests\": \"76c516e614471cd424ec8f918a44e86aef37e1a2\", \"blink_common_unittests\": \"b4ce1a670afedcac9c8ee21dcaa3465ec0ad865b\", \"blink_fuzzer_unittests\": \"e53eb50b1251d51a26fb6e04ee59cc54746b7a59\", \"blink_heap_unittests\": \"4ba7c394ba8aa98a079d3ace1275c65d42030be9\", \"blink_platform_unittests\": \"43f0f6701daa4a82932b1839f2f677f49d60e6fd\", \"blink_unittests\": \"5a274b5b4d395be2e3731cb90c3ef90dd84b8c94\", \"boringssl_crypto_tests\": \"4d18ebf91aafaf0ecae7002933ae673f40136d1f\", \"boringssl_ssl_tests\": \"d602c63e4991c8745ae651a4bb07581e3ee2db34\", \"browser_switcher_bho_unittests\": \"893e8b1b49b7c442708f35a8a8ba318477db4af2\", \"browser_tests\": \"3f358affe0a1743151435d6d5f79600bdb2e2065\", \"capture_unittests\": \"a5ad6ffbb27afd297c118c8bd67b8162657aae4c\", \"cast_unittests\": \"c5ae0b98161a2ba2a1f2a4f08573243314d7fe40\", \"cc_unittests\": \"f87265683b5bb341d32a49b39a082a85faa96a8f\", \"chrome_app_unittests\": \"20709cafab3bfd4355a7ea06247342efcc3cf094\", \"chrome_cleaner_unittests\": \"dc0be742fc1aa4874398cb3f8da8cb9f3b2f7fc2\", \"chrome_elf_unittests\": \"f06a91f01eb815a3e4841e833ce7eb14f0dbf6c8\", \"chromedriver_unittests\": \"dd6963915bb3c30b3464afd7f1d49f6601ec851d\", \"components_browsertests\": \"30fd15693ee96a556bb4b64ebabb552790035bc1\", \"components_unittests\": \"bbffd734f63f061c04e9d5a1a3946ed0c962c1b7\", \"compositor_unittests\": \"b77620594f58ae0cfe1d86e84dc21d68ad65cf13\", \"content_browsertests\": \"f62bbcc9506160ac9b96c5b305c425a81356fc07\", \"content_unittests\": \"a45f7e7e051ac2d3b8d573c349440489183fe70f\", \"courgette_unittests\": \"07bd19bdf63060c401b7d531e7fcbcf9fa4e22f5\", \"crashpad_tests\": \"cedb260da41d345883028410588e94e673afb4fe\", \"cronet_tests\": \"f82241791605c4df3485d71437f8994bbe86d93b\", \"cronet_unittests\": \"62797df31fb3774d9805499ac91206f617cc4284\", \"crypto_unittests\": \"822cb3f663ea1b18f7dcc63991368ee3f56488a8\", \"delayloads_unittests\": \"2f39b0507ca72d347d225c16cede00a7369ad343\", \"device_unittests\": \"12809bdb1d64e31a4fef9f06b6ee112b3623c40a\", \"display_unittests\": \"82568530cf21eb136037f776106219da01f76d1d\", \"elevation_service_unittests\": \"a0044e196fc42a5da73d69a6429ebb0e68c5d373\", \"events_unittests\": \"d9c8e6af972f0a6c678740f6a0088e695d0b4261\", \"extensions_browsertests\": \"56d1492cf6d383d4430d017c89b964ae7bcf7902\", \"extensions_unittests\": \"fda767e25266c260d2d494331863e8eb7702b23f\", \"filesystem_service_unittests\": \"783ed6d658bf65f0903ee53185c688a6386dd208\", \"gcm_unit_tests\": \"256f9139d8b703836519c426bda6a0bf41488ca0\", \"gcp_unittests\": \"bd95d4af92b64d62d9a2093e4dacc02114d5080a\", \"gfx_unittests\": \"6d3b083bda44a77f06c0267de89f9ab691a0c783\", \"gin_unittests\": \"e117a3446104006792051b83a0ff03e8fcf92b3d\", \"google_apis_unittests\": \"404185946a542b183142288a31103761a0dd5895\", \"gpu_unittests\": \"023747bab02e60701294c3b43ca8fa604439badf\", \"gwp_asan_unittests\": \"e8a78c59e726b6be5b8be6a3704ff084c1252c43\", \"headless_browsertests\": \"53acd91bebf643a119ad6f6a0c924e30d622eff2\", \"headless_unittests\": \"c386b62e43db067742035b325d421c9d0dfd06eb\", \"install_static_unittests\": \"069e3ffa84205486bd79beabd9e26caf6304ea7d\", \"installer_util_unittests\": \"1f8c2a9d4d4fd9adb77d9ba2e4bf774abcd626f8\", \"interactive_ui_tests\": \"55e25b625a6a21ca182343af724a973e3046bb86\", \"ipc_tests\": \"fe06b29a028e1caa9d099af9b7fa9cb494ea9154\", \"jingle_unittests\": \"8d9278e4b242e9f93b32acfcecc2f933d7e6f4ff\", \"latency_unittests\": \"642c86e19b64ae31139e0d62c4eb3efa9b79c824\", \"libjingle_xmpp_unittests\": \"d80707ed03295a18d82096e595fee82fef59fdb5\", \"liburlpattern_unittests\": \"e7d8547823d08b5090cf40274d70aa674fd0d768\", \"media_blink_unittests\": \"366afcd53380b97cb920e9cf1b3ed3ef9e3e2481\", \"media_unittests\": \"44fba83a128adb77ca2c97dba0f16663f6426a1f\", \"message_center_unittests\": \"0c1b23851102bf25bf48525e6d33bd35a9bbdeeb\", \"midi_unittests\": \"c3b96cab5cc71b2571fdc55c4d3bb19b7333d519\", \"mojo_core_unittests\": \"3e4104a4aad01a4d0fadb73746a0b011d8bdd13f\", \"mojo_unittests\": \"c599296b6107bc3ec74924c960c6e4fcb8b5cfcb\", \"nacl_loader_unittests\": \"ff7e1053917f26f3eaf8b89234ee76d26f36dd51\", \"native_theme_unittests\": \"f50bbc3006a81923ea680ad2eae84383959ed479\", \"net_unittests\": \"2e82bec3a2198656a2be53297ba17d47a3ace662\", \"notification_helper_unittests\": \"b613ed608590b03b97d2a0effb9572b33cc3a102\", \"pdf_unittests\": \"ece0a2fbc3e8c481126d12ee7b1d80488a575abb\", \"perfetto_unittests\": \"924961f43f69d7b1d1e9d3398c43c3a82df1e7fe\", \"ppapi_unittests\": \"e43cd0231aa68a6828217b7fcd93e9f086501258\", \"printing_unittests\": \"629f29e6eaae7fb927c65b2f78bd6934b37c75aa\", \"remoting_unittests\": \"495fb92b094e3b1b9d2b42028ffa69d2347be1b0\", \"sbox_integration_tests\": \"f064568b0e6a28dec66f6dc8f1517f59103db81e\", \"sbox_unittests\": \"1e4ec602cc88f7d8c2c423bd0466ad1debbbf8a4\", \"sbox_validation_tests\": \"9b630326d220022e92f0b00ba1f780f0580f30f3\", \"service_manager_unittests\": \"8632a18005763a520598f23582b74c0979b00292\", \"services_unittests\": \"ffd84811483bf79cf2312c5b132f658232fad4e9\", \"setup_unittests\": \"6b48a802532569671ef82054a80464ad8f58677b\", \"shell_dialogs_unittests\": \"7d2c45765111a1f6de5ba5762666f8077c950d8b\", \"skia_unittests\": \"9c39ea685213db8fba5363fd8ea6d91f2a2db7bc\", \"snapshot_unittests\": \"778c9497faeea08e62566789ccd64945bffbce4d\", \"sql_unittests\": \"bf632344b38eabdcdc3e985ad50aebbcf8014636\", \"storage_unittests\": \"7eb9d31124fbb44b12334cfd3fce9d8bbbc2a9d2\", \"sync_integration_tests\": \"194b261b9b6633255ae96440c693fd573d556283\", \"traffic_annotation_auditor_unittests\": \"124672dc48f0d00d408f4e441bd8c760168b8372\", \"ui_base_unittests\": \"484f7831dd81c9b09096552d51466b967111b78f\", \"ui_touch_selection_unittests\": \"eb993951bd458726cc7a3bf2e1bed4efb82cbad5\", \"unit_tests\": \"c9832703b986102a72616a6e400f59e067a9a53f\", \"updater_tests\": \"990c9b6ebe6aa2988b61e26e0d3c36a94e86438d\", \"url_unittests\": \"b84eda5f0136607bf041edc8d77372a15f67302b\", \"views_unittests\": \"1c48fb56d2f9d41aa18dd5e0b3796d9688d28955\", \"viz_unittests\": \"c09c441c442325fda231f873da073a07efd80d21\", \"vr_common_unittests\": \"0940a7c14b0846239608dbadbd583ffeb1d89997\", \"vr_pixeltests\": \"ad2bc6a35809674b1295e0faa05c1717c20c1515\", \"weblayer_browsertests\": \"419ab8163eaebf7421e59ed3c4b637682b682d3c\", \"weblayer_unittests\": \"781c4692165254f816b3ab06e577e0ed50929c4e\", \"wm_unittests\": \"c816e0aa8a4f5a6f6d83f20691e293c4ff6466ea\", \"wtf_unittests\": \"03bdbe44d595fb4bd7df83ff09075bc5cae1a0cd\", \"zlib_unittests\": \"9df684492d2162af5a75bbb4f09e9a13d9015d80\", \"zucchini_unittests\": \"79339efb835a4993ce8e1ca902387f919a78fc89\"}}, \"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-1-ckf5\"], \"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\": [\"5690-40331dd\"], \"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": "1613102787879205",
"status": "COMPLETED",
"status_changed_ts": "1613106585820588",
"tags": [
"build_address:luci.chromium.ci/win-asan/17292",
"builder:win-asan",
"buildset:commit/git/76074de63fc6b08280bb2e29c089aa1aa306bfa3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/76074de63fc6b08280bb2e29c089aa1aa306bfa3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020962911497568608",
"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/8855532849571581984/+/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:51ad3cae5f796d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613106585825123",
"url": "https://ci.chromium.org/b/8855532849571581984",
"utcnow_ts": "1613106586435264"
}
}
[05:09:46.453] Invocation finished in 1h3m22.193828767s with status SUCCEEDED