[15:29:57.450] New invocation is queued and will start shortly
[15:29:58.641] Starting the invocation (attempt 1)
[15:29:58.675] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:29:58.675] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:29:58.675] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027171096747543824",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"abf76d0d5ebfcd4be715a53b914512156d524c60\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027171096747543824",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/abf76d0d5ebfcd4be715a53b914512156d524c60",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/abf76d0d5ebfcd4be715a53b914512156d524c60",
"gitiles_ref:refs/heads/master"
]
}
[15:29:59.974] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607182198817906",
"id": "8861741034651613856",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"abf76d0d5ebfcd4be715a53b914512156d524c60\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607182199849951",
"tags": [
"build_address:luci.chromium.ci/win-asan/16044",
"builder:win-asan",
"buildset:commit/git/abf76d0d5ebfcd4be715a53b914512156d524c60",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/abf76d0d5ebfcd4be715a53b914512156d524c60",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027171096747543824",
"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/8861741034651613856/+/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": "1607182199850071",
"url": "https://ci.chromium.org/b/8861741034651613856",
"utcnow_ts": "1607182199962134"
}
}
[15:29:59.974] Task URL: https://ci.chromium.org/b/8861741034651613856
[15:29:59.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:2:0) after 1m0s
[15:30:05.892] Received PubSub notification, asking Buildbucket for the build status
[15:30:06.372] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:00.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:2:0)
[15:31:00.009] Timer tick, asking Buildbucket for the build status
[15:31:00.254] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:00.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:4:0) after 1m0s
[15:32:00.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:4:0)
[15:32:00.296] Timer tick, asking Buildbucket for the build status
[15:32:11.668] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:11.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:5:0) after 1m0s
[15:33:11.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:5:0)
[15:33:11.686] Timer tick, asking Buildbucket for the build status
[15:33:11.790] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:11.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:6:0) after 1m0s
[15:34:11.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:6:0)
[15:34:11.789] Timer tick, asking Buildbucket for the build status
[15:34:12.102] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:12.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:7:0) after 1m0s
[15:35:12.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:7:0)
[15:35:12.077] Timer tick, asking Buildbucket for the build status
[15:35:13.632] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:13.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:8:0) after 1m0s
[15:36:13.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:8:0)
[15:36:13.660] Timer tick, asking Buildbucket for the build status
[15:36:13.752] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:13.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:9:0) after 1m0s
[15:37:13.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:9:0)
[15:37:13.778] Timer tick, asking Buildbucket for the build status
[15:37:13.917] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:13.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:10:0) after 1m0s
[15:38:14.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:10:0)
[15:38:14.020] Timer tick, asking Buildbucket for the build status
[15:38:15.534] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:15.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:11:0) after 1m0s
[15:39:15.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:11:0)
[15:39:15.633] Timer tick, asking Buildbucket for the build status
[15:39:17.800] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:17.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:12:0) after 1m0s
[15:40:17.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:12:0)
[15:40:17.831] Timer tick, asking Buildbucket for the build status
[15:40:17.959] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:17.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:13:0) after 1m0s
[15:41:18.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:13:0)
[15:41:18.031] Timer tick, asking Buildbucket for the build status
[15:41:18.096] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:18.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:14:0) after 1m0s
[15:42:18.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:14:0)
[15:42:18.213] Timer tick, asking Buildbucket for the build status
[15:42:18.834] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:18.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:15:0) after 1m0s
[15:43:18.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:15:0)
[15:43:18.936] Timer tick, asking Buildbucket for the build status
[15:43:19.125] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:19.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:16:0) after 1m0s
[15:44:19.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:16:0)
[15:44:19.142] Timer tick, asking Buildbucket for the build status
[15:44:19.284] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:19.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:17:0) after 1m0s
[15:45:19.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:17:0)
[15:45:19.333] Timer tick, asking Buildbucket for the build status
[15:45:19.487] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:19.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:18:0) after 1m0s
[15:46:19.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:18:0)
[15:46:19.555] Timer tick, asking Buildbucket for the build status
[15:46:20.550] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:20.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:19:0) after 1m0s
[15:47:20.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:19:0)
[15:47:20.593] Timer tick, asking Buildbucket for the build status
[15:47:20.709] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:20.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:20:0) after 1m0s
[15:48:20.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:20:0)
[15:48:20.753] Timer tick, asking Buildbucket for the build status
[15:48:21.178] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:21.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:21:0) after 1m0s
[15:49:21.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:21:0)
[15:49:21.231] Timer tick, asking Buildbucket for the build status
[15:49:23.381] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:23.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:22:0) after 1m0s
[15:50:23.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:22:0)
[15:50:23.491] Timer tick, asking Buildbucket for the build status
[15:50:23.974] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:23.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:23:0) after 1m0s
[15:51:24.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:23:0)
[15:51:24.035] Timer tick, asking Buildbucket for the build status
[15:51:24.215] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:24.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:24:0) after 1m0s
[15:52:24.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:24:0)
[15:52:24.283] Timer tick, asking Buildbucket for the build status
[15:52:24.942] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:24.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:25:0) after 1m0s
[15:53:25.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:25:0)
[15:53:25.035] Timer tick, asking Buildbucket for the build status
[15:53:35.490] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:35.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:26:0) after 1m0s
[15:54:35.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:26:0)
[15:54:35.562] Timer tick, asking Buildbucket for the build status
[15:54:35.783] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:35.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:27:0) after 1m0s
[15:55:35.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:27:0)
[15:55:35.809] Timer tick, asking Buildbucket for the build status
[15:55:36.459] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:36.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:28:0) after 1m0s
[15:56:36.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:28:0)
[15:56:36.667] Timer tick, asking Buildbucket for the build status
[15:56:37.905] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:37.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:29:0) after 1m0s
[15:57:38.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:29:0)
[15:57:38.055] Timer tick, asking Buildbucket for the build status
[15:57:50.034] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:50.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:30:0) after 1m0s
[15:58:50.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:30:0)
[15:58:50.125] Timer tick, asking Buildbucket for the build status
[15:58:52.525] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:52.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:31:0) after 1m0s
[15:59:52.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:31:0)
[15:59:52.596] Timer tick, asking Buildbucket for the build status
[15:59:52.729] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:52.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:32:0) after 1m0s
[16:00:53.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:32:0)
[16:00:53.172] Timer tick, asking Buildbucket for the build status
[16:00:53.420] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:53.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:33:0) after 1m0s
[16:01:53.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:33:0)
[16:01:53.820] Timer tick, asking Buildbucket for the build status
[16:01:54.219] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:54.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:34:0) after 1m0s
[16:02:54.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:34:0)
[16:02:54.299] Timer tick, asking Buildbucket for the build status
[16:03:03.086] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:03.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:35:0) after 1m0s
[16:04:03.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:35:0)
[16:04:03.546] Timer tick, asking Buildbucket for the build status
[16:04:03.639] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:03.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:36:0) after 1m0s
[16:05:03.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:36:0)
[16:05:03.948] Timer tick, asking Buildbucket for the build status
[16:05:04.213] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:04.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:37:0) after 1m0s
[16:06:04.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:37:0)
[16:06:04.207] Timer tick, asking Buildbucket for the build status
[16:06:04.642] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:04.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:38:0) after 1m0s
[16:07:04.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:38:0)
[16:07:04.661] Timer tick, asking Buildbucket for the build status
[16:07:04.882] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:04.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:39:0) after 1m0s
[16:08:04.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:39:0)
[16:08:04.864] Timer tick, asking Buildbucket for the build status
[16:08:05.111] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:05.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:40:0) after 1m0s
[16:09:05.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:40:0)
[16:09:05.171] Timer tick, asking Buildbucket for the build status
[16:09:10.420] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:10.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:41:0) after 1m0s
[16:10:10.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:41:0)
[16:10:10.454] Timer tick, asking Buildbucket for the build status
[16:10:12.023] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:12.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:42:0) after 1m0s
[16:11:12.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:42:0)
[16:11:12.051] Timer tick, asking Buildbucket for the build status
[16:11:12.148] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:12.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:43:0) after 1m0s
[16:12:12.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:43:0)
[16:12:12.182] Timer tick, asking Buildbucket for the build status
[16:12:12.335] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:12.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:44:0) after 1m0s
[16:13:12.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:44:0)
[16:13:12.362] Timer tick, asking Buildbucket for the build status
[16:13:15.177] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:15.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:45:0) after 1m0s
[16:14:15.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:45:0)
[16:14:15.254] Timer tick, asking Buildbucket for the build status
[16:14:16.173] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:16.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:46:0) after 1m0s
[16:15:16.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:46:0)
[16:15:16.201] Timer tick, asking Buildbucket for the build status
[16:15:16.291] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:16.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:47:0) after 1m0s
[16:16:16.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:47:0)
[16:16:16.331] Timer tick, asking Buildbucket for the build status
[16:16:16.460] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:16.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:48:0) after 1m0s
[16:17:16.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:48:0)
[16:17:16.479] Timer tick, asking Buildbucket for the build status
[16:17:16.621] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:16.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:49:0) after 1m0s
[16:18:16.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:49:0)
[16:18:16.866] Timer tick, asking Buildbucket for the build status
[16:18:19.055] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:19.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:50:0) after 1m0s
[16:19:19.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:50:0)
[16:19:19.124] Timer tick, asking Buildbucket for the build status
[16:19:19.353] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:19.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:51:0) after 1m0s
[16:20:19.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:51:0)
[16:20:19.412] Timer tick, asking Buildbucket for the build status
[16:20:19.701] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:19.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:52:0) after 1m0s
[16:21:19.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:52:0)
[16:21:19.824] Timer tick, asking Buildbucket for the build status
[16:21:20.696] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:20.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:53:0) after 1m0s
[16:22:20.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:53:0)
[16:22:20.736] Timer tick, asking Buildbucket for the build status
[16:22:20.978] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:20.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:54:0) after 1m0s
[16:23:21.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:54:0)
[16:23:21.019] Timer tick, asking Buildbucket for the build status
[16:23:21.277] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:21.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:55:0) after 1m0s
[16:24:21.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:55:0)
[16:24:21.330] Timer tick, asking Buildbucket for the build status
[16:24:21.680] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:21.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:56:0) after 1m0s
[16:25:21.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:56:0)
[16:25:21.713] Timer tick, asking Buildbucket for the build status
[16:25:21.831] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:21.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:57:0) after 1m0s
[16:26:21.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:57:0)
[16:26:21.843] Timer tick, asking Buildbucket for the build status
[16:26:22.816] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:22.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:58:0) after 1m0s
[16:27:23.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:58:0)
[16:27:23.793] Timer tick, asking Buildbucket for the build status
[16:27:24.480] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:24.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:59:0) after 1m0s
[16:28:24.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:59:0)
[16:28:24.733] Timer tick, asking Buildbucket for the build status
[16:28:25.982] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:25.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:60:0) after 1m0s
[16:29:26.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:60:0)
[16:29:26.114] Timer tick, asking Buildbucket for the build status
[16:29:33.774] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:33.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:61:0) after 1m0s
[16:30:33.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:61:0)
[16:30:33.822] Timer tick, asking Buildbucket for the build status
[16:30:33.984] Build 8861741034651613856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:33.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027171096747543824:62:0) after 1m0s
[16:30:58.283] Received PubSub notification, asking Buildbucket for the build status
[16:30:58.387] Build 8861741034651613856: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:30:58.387] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607185855511974",
"created_by": "project:chromium",
"created_ts": "1607182198817906",
"id": "8861741034651613856",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"abf76d0d5ebfcd4be715a53b914512156d524c60\"}}",
"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\": \"abf76d0d5ebfcd4be715a53b914512156d524c60\", \"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-7lfd\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16044, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"251ba5cb119ff2fed0e861cbc9b096c45004c1fa\", \"got_dawn_revision\": \"c532048062befdee8bb57d18f01d3a3c403d49de\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"abf76d0d5ebfcd4be715a53b914512156d524c60\", \"got_revision_cp\": \"refs/heads/master@{#834024}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"f36e874ade1dbc6f5ddfc857753a892a8bb358c3\", \"got_v8_revision\": \"61f3e61408129afc0d9e1268d3354a283ea3c881\", \"got_v8_revision_cp\": \"refs/heads/8.9.100@{#1}\", \"got_webrtc_revision\": \"6c80aebd00360ee00c84c7b14422c5fe475d8916\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32774}\", \"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\": \"abf76d0d5ebfcd4be715a53b914512156d524c60\", \"swarm_hashes_refs/heads/master(at){#834024}_without_patch\": {\"absl_hardening_tests\": \"b7756871b76c5c8f76958c3b65646b96710a3dd1\", \"accessibility_unittests\": \"ad49ae7e38e70e752ff64c114432e8efd94c66d2\", \"angle_unittests\": \"b1485a29af17d361aec21fe0249230f2f510b4b0\", \"app_shell_unittests\": \"db3b61216f392eba71e304a606bb8f0683b1222e\", \"aura_unittests\": \"2e79ce7e59a5952569fe84d14653611f642e1205\", \"base_unittests\": \"470147db8cd2774dc39c47a92a85a9f7ee7a99a1\", \"base_util_unittests\": \"1699349db27a792e99eb8fc6ebb11a12a3bf5e29\", \"blink_common_unittests\": \"978840fcddd74acfeeb863bfc04d731794ebe478\", \"blink_fuzzer_unittests\": \"802b4676045480c1858dac992cb41b04dd05ab5c\", \"blink_heap_unittests\": \"9cc774ad8c43a7e16c4a900bb01d85794dbb94f0\", \"blink_platform_unittests\": \"29b55bbd7d091290b6d05afb827b436d8477d4e1\", \"blink_unittests\": \"15cd868589ab1c11b1187cd87e7b1f1d8383b905\", \"boringssl_crypto_tests\": \"2a99d76fb3aa71918e26b158a081fb316dc1fc01\", \"boringssl_ssl_tests\": \"31d5e8b91d72b678d61592480e3cf8833a9a50ff\", \"browser_switcher_bho_unittests\": \"87964c28a7f623db64c5a68c90ef1bcab6e3b070\", \"browser_tests\": \"75e213962409634e3b247b1ce35a1ca78ad5c5a3\", \"capture_unittests\": \"206a4c5a1a59da81cc129ea37022038738d65537\", \"cast_unittests\": \"48bee35bc2c61a024199349863c9690fff45d288\", \"cc_unittests\": \"bdbc51ef54161708865cef51b2845d1fd567828a\", \"chrome_app_unittests\": \"98efeab653393a412589e23c095964b0259c9517\", \"chrome_cleaner_unittests\": \"18dcb7e69469e53e9819614cdaf892c4dbc36453\", \"chrome_elf_unittests\": \"90b307a9871e29d6f165fbbb0d4eb7e1df49b985\", \"chromedriver_unittests\": \"e6e7dddb3371c492abcc280aadc153b7ea97eaf5\", \"components_browsertests\": \"90efea33c9921028fb3f10ec871a74a43a13e65d\", \"components_unittests\": \"81ee9045f281457b7cecc7b1c9c61a961f5066c4\", \"compositor_unittests\": \"e477c59ec71f4d2f509bc796be3178fa3f22d0ea\", \"content_browsertests\": \"ff4b71ed1687fec6a18d579a108fd8e62bb74363\", \"content_unittests\": \"4a393839174d8e17e9cb39421be2ec5803091f64\", \"courgette_unittests\": \"ecd1a3d3372684f2322baa183813c64a0438d2f1\", \"crashpad_tests\": \"a6d764f38b76722e3e5170d9b26abbac3f0ff477\", \"cronet_tests\": \"4565c0080c0172ea91e84a84ff4a741f630e8892\", \"cronet_unittests\": \"03caadc32f28e399f48e5d2340b2306a52c914e4\", \"crypto_unittests\": \"796e65fb3824b4989ad7e61b47c4fe26f4e2bdbd\", \"delayloads_unittests\": \"84eebe0f49192b4defc99c9c7e4a7f0bcaab595e\", \"device_unittests\": \"a27cd06a91f72cc2bc8342bb4423a8575784c0d4\", \"display_unittests\": \"d84b670b74fb98205884ca4994ebdc283097ef47\", \"elevation_service_unittests\": \"6a1f360413a8ca6138af5432a4356ed665c4d769\", \"events_unittests\": \"265aa501c3a8832fdda9ef4d6f1c105d68e5fec4\", \"extensions_browsertests\": \"6cf634cfb907ac436e80ba6d2e4fb7279de38f0e\", \"extensions_unittests\": \"c9fc441abc8cc21b3662cd39334b03e8fc603c48\", \"filesystem_service_unittests\": \"87c588402629f4887312cd409b1dfd56b9f0cf4e\", \"gcm_unit_tests\": \"fd57c1a67e0c934968abdb388236ad2da5e4f6eb\", \"gcp_unittests\": \"2227f3355c91cfbf9fff64392b982c12a3489fbb\", \"gfx_unittests\": \"c4046080910713aefe5aab1615546c3050746f0f\", \"gin_unittests\": \"fbf3943c36bed4e1976df797b070566cc15a4047\", \"google_apis_unittests\": \"d5f68ce071865e830f0111fdee39beb5f3a614e1\", \"gpu_unittests\": \"8fb5b72b5b873c35257e968e5eaec6528ae72fca\", \"gwp_asan_unittests\": \"93cac1d852cbcaa6e4223d3961c80836796dc6d3\", \"headless_browsertests\": \"e729172bbc812910a8da0d73b6c47e6ff538e3f1\", \"headless_unittests\": \"c2aaa4b82a0272b397b14474eee865a1915ae1a8\", \"install_static_unittests\": \"9438137441b9277db93d9e173a68dd473eb18a0a\", \"installer_util_unittests\": \"914d685fee5d68c9f1a2810828ca59db4af1f909\", \"interactive_ui_tests\": \"2fcf7620cdc051e4e5066d75f9be25233896d5f4\", \"ipc_tests\": \"81d059792b909665c1bee45a1fe7267026dcf87c\", \"jingle_unittests\": \"b7eef6c86607f10c7d94fbc4a26956992fb3f2be\", \"latency_unittests\": \"3922f1bc775be43c7e249dea74b28042d88a40ac\", \"libjingle_xmpp_unittests\": \"855ecd4d8fbe212ec0aa2618e308d5a26a3bdf95\", \"liburlpattern_unittests\": \"21de48be7e51314cd70b29136416a837f3610650\", \"media_blink_unittests\": \"a75ec0b7ee7e0fc716a494cdc7ff0a69974230d9\", \"media_unittests\": \"1ed5f290de225d2d3108b5e18155bb137a76cf96\", \"message_center_unittests\": \"ff3743d96c9a0035a552490f2802f33fcc85a197\", \"midi_unittests\": \"bd5a85b3ce8a1038d4de01df2e69d2574b15ec17\", \"mojo_core_unittests\": \"ae18bc36b3563c5abe80832c2005c84c63263c4f\", \"mojo_unittests\": \"5d342bd34c474a26fb15ea1ab66998359c6b8ced\", \"nacl_loader_unittests\": \"ffe51053061f08ad630993d25527e0c678f9ffb0\", \"native_theme_unittests\": \"d76b2603623f68ec83a39a3129c303cb92260d23\", \"net_unittests\": \"7b6897edd6ef6e2ad7df8852194c8a3ed01080d7\", \"notification_helper_unittests\": \"154b9c17320c183a51da9113ca945e2088a1bee0\", \"pdf_unittests\": \"eb124f8b8ad43c22e95d123e9e8948830e387ea2\", \"perfetto_unittests\": \"e56fdd95c28e1e1de8b96ec3f09d5e95dee8822c\", \"ppapi_unittests\": \"258d647e0db3c578479ead0883b0818ee0fe1c2a\", \"printing_unittests\": \"3bbb858f0e1a5e37ffa9b212fc9e61abebf59c72\", \"remoting_unittests\": \"74a98580826f1ce24a5cc58ec8fb62c7cc32d600\", \"sbox_integration_tests\": \"d2fa8db8b4740eec10235bc7985ec56d3179986c\", \"sbox_unittests\": \"69a82b0364eb1dcb6957ef4e40f6d71b823ae847\", \"sbox_validation_tests\": \"9231cd0b5cc442d07029ac72db9f5a466ba4febf\", \"service_manager_unittests\": \"6d0a84d77e18f1d77a29e94c1d0a52a99b8b6a11\", \"services_unittests\": \"3f4673b81c4661f86df1491a32e06f990ea4d3e0\", \"setup_unittests\": \"c9d277dcd32dec7ec7f587a974d2e2130b7f06c2\", \"shell_dialogs_unittests\": \"5b77b8854d74c20286293508503ff1fda714150d\", \"skia_unittests\": \"ede3368563d6b7002ef74a717593f4e4523e87ea\", \"snapshot_unittests\": \"4a9abef41a1189c8decb9e46d6f3f4f7aeae44b8\", \"sql_unittests\": \"96e9c602bb664c98bec84967bb23e9c28806b91b\", \"storage_unittests\": \"b9e438a6cf1d0ba298892beca3ce17b54a4e28de\", \"sync_integration_tests\": \"135f192c66a4a0f268d8cee37eb6b4117c1af296\", \"traffic_annotation_auditor_unittests\": \"aa152cecf2a15987662c6627d857dea0fa112d0e\", \"ui_base_unittests\": \"d8a04c2cde7cb89a1b1aca7e2696fa6c6d191218\", \"ui_touch_selection_unittests\": \"66e48975d67769b8a75256be7eef4a16921a8c08\", \"unit_tests\": \"f66aa0e1d84425843da3d5fe3827f2ebae132b75\", \"updater_tests\": \"8a27768d87e66493d04b667a3ef61e2a9a89ffaa\", \"url_unittests\": \"79bfaf70d0f060f3f9d1db725434db9ba5fdde75\", \"views_unittests\": \"3c284dddd92278c6074883066c5490d639c5a2da\", \"viz_unittests\": \"58a6cc9cb65609b291cb360b9988dac527e23917\", \"vr_common_unittests\": \"2860897cd2ca68ceaa25ad1246203804a2c4c9d5\", \"vr_pixeltests\": \"2ff88ffcecfcf620b06ef96e5a3075797634a519\", \"weblayer_browsertests\": \"a951530de93e338e5d1dcb582533c3020fab4245\", \"weblayer_unittests\": \"71adf444b9b505b3ba118aa90517798a3ffcbc06\", \"wm_unittests\": \"ee0b542e5ec9fa7809412cc7e535e92f81d20321\", \"wtf_unittests\": \"8b40c667938045b1009e7adb8b06b7eb2681b4fa\", \"zlib_unittests\": \"69307228fd0317f2dd0ffa2574e71f6e04b426a1\", \"zucchini_unittests\": \"2f7383a3755fc337882ae2fa8d2444c53c8a171b\"}}, \"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-7lfd\"], \"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\": [\"5640-d0eb544\"], \"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": "1607182204053068",
"status": "COMPLETED",
"status_changed_ts": "1607185856209790",
"tags": [
"build_address:luci.chromium.ci/win-asan/16044",
"builder:win-asan",
"buildset:commit/git/abf76d0d5ebfcd4be715a53b914512156d524c60",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/abf76d0d5ebfcd4be715a53b914512156d524c60",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027171096747543824",
"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/8861741034651613856/+/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:504c57b501ebee10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607185856279670",
"url": "https://ci.chromium.org/b/8861741034651613856",
"utcnow_ts": "1607185858372829"
}
}
[16:30:58.387] Invocation finished in 1h1m0.951843578s with status SUCCEEDED