[20:50:57.502] New invocation is queued and will start shortly
[20:50:59.071] Starting the invocation (attempt 1)
[20:50:59.105] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:50:59.106] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:50:59.106] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020537322572264544",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"acc794db2fbd1da098046004e4a8f2fcf498d2c2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020537322572264544",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/acc794db2fbd1da098046004e4a8f2fcf498d2c2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/acc794db2fbd1da098046004e4a8f2fcf498d2c2",
"gitiles_ref:refs/heads/master"
]
}
[20:51:00.290] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613508659191780",
"id": "8855107260138739952",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"acc794db2fbd1da098046004e4a8f2fcf498d2c2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613508659785704",
"tags": [
"build_address:luci.chromium.ci/win-asan/17381",
"builder:win-asan",
"buildset:commit/git/acc794db2fbd1da098046004e4a8f2fcf498d2c2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/acc794db2fbd1da098046004e4a8f2fcf498d2c2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020537322572264544",
"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/8855107260138739952/+/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": "1613508659785810",
"url": "https://ci.chromium.org/b/8855107260138739952",
"utcnow_ts": "1613508660277336"
}
}
[20:51:00.290] Task URL: https://ci.chromium.org/b/8855107260138739952
[20:51:00.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:2:0) after 1m0s
[20:51:09.562] Received PubSub notification, asking Buildbucket for the build status
[20:51:09.887] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:01.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:2:0)
[20:52:01.062] Timer tick, asking Buildbucket for the build status
[20:52:01.136] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:01.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:4:0) after 1m0s
[20:53:02.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:4:0)
[20:53:02.147] Timer tick, asking Buildbucket for the build status
[20:53:02.245] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:02.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:5:0) after 1m0s
[20:54:02.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:5:0)
[20:54:02.750] Timer tick, asking Buildbucket for the build status
[20:54:02.921] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:02.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:6:0) after 1m0s
[20:55:02.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:6:0)
[20:55:02.994] Timer tick, asking Buildbucket for the build status
[20:55:03.093] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:03.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:7:0) after 1m0s
[20:56:03.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:7:0)
[20:56:03.138] Timer tick, asking Buildbucket for the build status
[20:56:03.267] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:03.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:8:0) after 1m0s
[20:57:03.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:8:0)
[20:57:03.332] Timer tick, asking Buildbucket for the build status
[20:57:03.409] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:03.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:9:0) after 1m0s
[20:58:03.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:9:0)
[20:58:03.458] Timer tick, asking Buildbucket for the build status
[20:58:03.561] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:03.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:10:0) after 1m0s
[20:59:03.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:10:0)
[20:59:03.594] Timer tick, asking Buildbucket for the build status
[20:59:03.735] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:03.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:11:0) after 1m0s
[21:00:03.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:11:0)
[21:00:03.793] Timer tick, asking Buildbucket for the build status
[21:00:04.001] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:04.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:12:0) after 1m0s
[21:01:04.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:12:0)
[21:01:04.045] Timer tick, asking Buildbucket for the build status
[21:01:04.179] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:04.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:13:0) after 1m0s
[21:02:04.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:13:0)
[21:02:04.188] Timer tick, asking Buildbucket for the build status
[21:02:04.300] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:04.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:14:0) after 1m0s
[21:03:04.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:14:0)
[21:03:04.339] Timer tick, asking Buildbucket for the build status
[21:03:04.500] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:04.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:15:0) after 1m0s
[21:04:04.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:15:0)
[21:04:04.521] Timer tick, asking Buildbucket for the build status
[21:04:04.599] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:04.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:16:0) after 1m0s
[21:05:04.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:16:0)
[21:05:04.687] Timer tick, asking Buildbucket for the build status
[21:05:04.892] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:04.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:17:0) after 1m0s
[21:06:04.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:17:0)
[21:06:04.960] Timer tick, asking Buildbucket for the build status
[21:06:05.110] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:05.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:18:0) after 1m0s
[21:07:05.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:18:0)
[21:07:05.135] Timer tick, asking Buildbucket for the build status
[21:07:05.307] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:05.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:19:0) after 1m0s
[21:08:05.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:19:0)
[21:08:05.326] Timer tick, asking Buildbucket for the build status
[21:08:05.488] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:05.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:20:0) after 1m0s
[21:09:05.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:20:0)
[21:09:05.513] Timer tick, asking Buildbucket for the build status
[21:09:05.626] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:05.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:21:0) after 1m0s
[21:10:05.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:21:0)
[21:10:05.663] Timer tick, asking Buildbucket for the build status
[21:10:05.758] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:05.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:22:0) after 1m0s
[21:11:05.776] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:22:0)
[21:11:05.776] Timer tick, asking Buildbucket for the build status
[21:11:06.026] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:06.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:23:0) after 1m0s
[21:12:06.060] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:23:0)
[21:12:06.060] Timer tick, asking Buildbucket for the build status
[21:12:06.249] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:06.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:24:0) after 1m0s
[21:13:06.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:24:0)
[21:13:06.285] Timer tick, asking Buildbucket for the build status
[21:13:06.373] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:06.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:25:0) after 1m0s
[21:14:06.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:25:0)
[21:14:06.420] Timer tick, asking Buildbucket for the build status
[21:14:06.650] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:06.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:26:0) after 1m0s
[21:15:06.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:26:0)
[21:15:06.673] Timer tick, asking Buildbucket for the build status
[21:15:06.909] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:06.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:27:0) after 1m0s
[21:16:06.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:27:0)
[21:16:06.905] Timer tick, asking Buildbucket for the build status
[21:16:07.085] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:07.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:28:0) after 1m0s
[21:17:07.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:28:0)
[21:17:07.107] Timer tick, asking Buildbucket for the build status
[21:17:07.291] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:07.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:29:0) after 1m0s
[21:18:07.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:29:0)
[21:18:07.343] Timer tick, asking Buildbucket for the build status
[21:18:07.457] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:07.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:30:0) after 1m0s
[21:19:07.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:30:0)
[21:19:07.479] Timer tick, asking Buildbucket for the build status
[21:19:07.568] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:07.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:31:0) after 1m0s
[21:20:07.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:31:0)
[21:20:07.594] Timer tick, asking Buildbucket for the build status
[21:20:08.052] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:08.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:32:0) after 1m0s
[21:21:08.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:32:0)
[21:21:08.074] Timer tick, asking Buildbucket for the build status
[21:21:08.174] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:08.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:33:0) after 1m0s
[21:22:08.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:33:0)
[21:22:08.197] Timer tick, asking Buildbucket for the build status
[21:22:08.554] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:08.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:34:0) after 1m0s
[21:23:08.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:34:0)
[21:23:08.589] Timer tick, asking Buildbucket for the build status
[21:23:08.684] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:08.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:35:0) after 1m0s
[21:24:08.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:35:0)
[21:24:08.710] Timer tick, asking Buildbucket for the build status
[21:24:08.800] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:08.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:36:0) after 1m0s
[21:25:08.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:36:0)
[21:25:08.820] Timer tick, asking Buildbucket for the build status
[21:25:08.938] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:08.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:37:0) after 1m0s
[21:26:08.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:37:0)
[21:26:08.956] Timer tick, asking Buildbucket for the build status
[21:26:09.072] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:09.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:38:0) after 1m0s
[21:27:09.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:38:0)
[21:27:09.115] Timer tick, asking Buildbucket for the build status
[21:27:09.432] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:09.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:39:0) after 1m0s
[21:28:09.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:39:0)
[21:28:09.471] Timer tick, asking Buildbucket for the build status
[21:28:09.565] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:09.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:40:0) after 1m0s
[21:29:09.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:40:0)
[21:29:09.581] Timer tick, asking Buildbucket for the build status
[21:29:09.681] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:09.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:41:0) after 1m0s
[21:30:09.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:41:0)
[21:30:09.705] Timer tick, asking Buildbucket for the build status
[21:30:09.810] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:09.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:42:0) after 1m0s
[21:31:09.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:42:0)
[21:31:09.881] Timer tick, asking Buildbucket for the build status
[21:31:10.146] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:10.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:43:0) after 1m0s
[21:32:10.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:43:0)
[21:32:10.150] Timer tick, asking Buildbucket for the build status
[21:32:10.242] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:10.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:44:0) after 1m0s
[21:33:10.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:44:0)
[21:33:10.362] Timer tick, asking Buildbucket for the build status
[21:33:10.758] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:10.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:45:0) after 1m0s
[21:34:10.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:45:0)
[21:34:10.926] Timer tick, asking Buildbucket for the build status
[21:34:11.042] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:11.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:46:0) after 1m0s
[21:35:11.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:46:0)
[21:35:11.116] Timer tick, asking Buildbucket for the build status
[21:35:11.232] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:11.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:47:0) after 1m0s
[21:36:11.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:47:0)
[21:36:11.578] Timer tick, asking Buildbucket for the build status
[21:36:11.673] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:11.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:48:0) after 1m0s
[21:37:11.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:48:0)
[21:37:11.728] Timer tick, asking Buildbucket for the build status
[21:37:11.915] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:11.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:49:0) after 1m0s
[21:38:11.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:49:0)
[21:38:11.944] Timer tick, asking Buildbucket for the build status
[21:38:12.114] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:12.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:50:0) after 1m0s
[21:39:12.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:50:0)
[21:39:12.135] Timer tick, asking Buildbucket for the build status
[21:39:12.234] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:12.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:51:0) after 1m0s
[21:40:12.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:51:0)
[21:40:12.252] Timer tick, asking Buildbucket for the build status
[21:40:12.358] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:12.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:52:0) after 1m0s
[21:41:12.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:52:0)
[21:41:12.379] Timer tick, asking Buildbucket for the build status
[21:41:12.644] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:12.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:53:0) after 1m0s
[21:42:12.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:53:0)
[21:42:12.747] Timer tick, asking Buildbucket for the build status
[21:42:12.852] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:12.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:54:0) after 1m0s
[21:43:12.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:54:0)
[21:43:12.978] Timer tick, asking Buildbucket for the build status
[21:43:13.065] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:13.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:55:0) after 1m0s
[21:44:13.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:55:0)
[21:44:13.102] Timer tick, asking Buildbucket for the build status
[21:44:13.317] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:13.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:56:0) after 1m0s
[21:45:13.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:56:0)
[21:45:13.603] Timer tick, asking Buildbucket for the build status
[21:45:13.714] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:13.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:57:0) after 1m0s
[21:46:13.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:57:0)
[21:46:13.894] Timer tick, asking Buildbucket for the build status
[21:46:14.003] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:14.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:58:0) after 1m0s
[21:47:14.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:58:0)
[21:47:14.216] Timer tick, asking Buildbucket for the build status
[21:47:14.660] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:14.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:59:0) after 1m0s
[21:48:14.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:59:0)
[21:48:14.757] Timer tick, asking Buildbucket for the build status
[21:48:15.319] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:15.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:60:0) after 1m0s
[21:49:15.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:60:0)
[21:49:15.422] Timer tick, asking Buildbucket for the build status
[21:49:15.619] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:15.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:61:0) after 1m0s
[21:50:15.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:61:0)
[21:50:15.658] Timer tick, asking Buildbucket for the build status
[21:50:15.798] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:15.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:62:0) after 1m0s
[21:51:15.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:62:0)
[21:51:15.865] Timer tick, asking Buildbucket for the build status
[21:51:16.060] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:16.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:63:0) after 1m0s
[21:52:16.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:63:0)
[21:52:16.140] Timer tick, asking Buildbucket for the build status
[21:52:16.241] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:16.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:64:0) after 1m0s
[21:53:16.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:64:0)
[21:53:16.318] Timer tick, asking Buildbucket for the build status
[21:53:16.404] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:16.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:65:0) after 1m0s
[21:54:16.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:65:0)
[21:54:16.445] Timer tick, asking Buildbucket for the build status
[21:54:16.627] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:16.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:66:0) after 1m0s
[21:55:16.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:66:0)
[21:55:16.603] Timer tick, asking Buildbucket for the build status
[21:55:16.836] Build 8855107260138739952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:16.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020537322572264544:67:0) after 1m0s
[21:55:28.798] Received PubSub notification, asking Buildbucket for the build status
[21:55:29.447] Build 8855107260138739952: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:55:29.447] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613512526852215",
"created_by": "project:chromium",
"created_ts": "1613508659191780",
"id": "8855107260138739952",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"acc794db2fbd1da098046004e4a8f2fcf498d2c2\"}}",
"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\": \"acc794db2fbd1da098046004e4a8f2fcf498d2c2\", \"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-0-6l07\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17381, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-1\", \"got_angle_revision\": \"91b6c3b1a2b12f6c834238ed680d82c3de741cd0\", \"got_dawn_revision\": \"71b8c9f4ad6f75edea66e91295015d00237703f6\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"acc794db2fbd1da098046004e4a8f2fcf498d2c2\", \"got_revision_cp\": \"refs/heads/master@{#854434}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a421556a7cb5554b6f60eb9a18345bb1b7a23ad2\", \"got_v8_revision\": \"51a9b91e1e5176bb007e749e7f506eee7b5407ba\", \"got_v8_revision_cp\": \"refs/heads/9.0.187@{#1}\", \"got_webrtc_revision\": \"8408c9938cb543a7cd5e7127927f3b4d0834ad59\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33272}\", \"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\": \"acc794db2fbd1da098046004e4a8f2fcf498d2c2\", \"swarm_hashes_refs/heads/master(at){#854434}_without_patch\": {\"absl_hardening_tests\": \"78c5376c6cab568dfd02731b5f1f7814fc71e88a\", \"accessibility_unittests\": \"5f9675fdf9960cae5a2a1bfb4dbade52d0261551\", \"angle_unittests\": \"930bcb7c9742d164421d05f1778b48a294cb524b\", \"app_shell_unittests\": \"ffcd0ae43ec4462b3d17779c2643cfc61d815af6\", \"aura_unittests\": \"755d904cc6e45b20065ae953d1c4325188f5e8c3\", \"base_unittests\": \"2136460fca4d553faa2854e7d9b3498ff7b383aa\", \"base_util_unittests\": \"8ce15ba8104f97aec401ef323e00c3b893493342\", \"blink_common_unittests\": \"298ca4484c9631431a1e4b52a19afe6dab5d8b50\", \"blink_fuzzer_unittests\": \"a8f22c0530035c15dad815af1bcd08c7cdd34867\", \"blink_heap_unittests\": \"7c84a863c1b37d96d0817491f120e9c64d30f3d6\", \"blink_platform_unittests\": \"58808a0b4c460c8f706af9a6018dcc04300d618c\", \"blink_unittests\": \"4887ed1ae769cd44495bdb2c92f4241b3aa633ce\", \"boringssl_crypto_tests\": \"9108fb64242108a6e6f42b04030194cc6a54cde4\", \"boringssl_ssl_tests\": \"dba48211b66fc8971f1ecf5fa8a3ae3b10282661\", \"browser_switcher_bho_unittests\": \"919796450b3a64d6e5b1a7f36d734f072210cacd\", \"browser_tests\": \"c4a279a98d9edac1a9f409b8b2ad5647733f83c8\", \"capture_unittests\": \"6d7e3d160eaad50a532bab134b6725116de1ff4b\", \"cast_unittests\": \"39908f7438ef6c8b926676657b687b987b479ac7\", \"cc_unittests\": \"91df46fe90e8f23b6ab7a43a729196eb232bceb5\", \"chrome_app_unittests\": \"6e9e2bb39380379e64cdd1ddc3fd127b618cbca9\", \"chrome_cleaner_unittests\": \"9d8f333da342a392e99ce3c8c7494f779d6c5423\", \"chrome_elf_unittests\": \"b6f2b3ab0afccb34d09b7551bd157df749003e90\", \"chromedriver_unittests\": \"2dc9b9c6687c922b773d9c23016c36449697064b\", \"components_browsertests\": \"63239249291eb25f3fa9203b206fc8f612011a94\", \"components_unittests\": \"66f2ba4c08a510e1115973987b90c4c4c9fdf677\", \"compositor_unittests\": \"cc967bf0e46a5d1286ac6f64c60bc0dc3bcb1d53\", \"content_browsertests\": \"703973e6ecc251488f3ae08c779ab7ee013444fe\", \"content_unittests\": \"9efc49a1a5615a399a292dc1e0b7ef18311f01fe\", \"courgette_unittests\": \"147122c40731cdb5ece6d525b333422fdda18ce4\", \"crashpad_tests\": \"b08a44b077ffc76ae95b7fd2e191e10d8387be50\", \"cronet_tests\": \"6bbe9493682c40f25d00a81a7d761ec6dc0dd397\", \"cronet_unittests\": \"630915e95165643cc063fdf189633ced3e400a5c\", \"crypto_unittests\": \"316469c8ddf0af054a5d919a504bb8c5b00bcfea\", \"delayloads_unittests\": \"c4be70040105e9071ab03ee245fb8b09fa974d1c\", \"device_unittests\": \"348e25e693ad5ae05a409e507ca5164d4a360b81\", \"display_unittests\": \"b1e49aa9d39032fb0113684bdd3bc403306193e7\", \"elevation_service_unittests\": \"453620f61c104ae8f601447593d86c8e2502fba9\", \"events_unittests\": \"34a39ade151af5c4f945e1f25eb97a7fe0c62edd\", \"extensions_browsertests\": \"1cc4e53ab23c2a725da659762cfb8158212a7e0f\", \"extensions_unittests\": \"7ec1645504c6283bdf9e1a683b72683de2e7cb98\", \"filesystem_service_unittests\": \"273c400bfb39ba268f658183edfa4311b4c6e107\", \"gcm_unit_tests\": \"0e3fe99ff0e0fe1b4db58be3ebb5e03f3aa7fcd4\", \"gcp_unittests\": \"27a0e6aca1c8b64c8c7ccdf0aac755222cc25746\", \"gfx_unittests\": \"1b1524c9a48360dbb9f3b9b625a3821a07042115\", \"gin_unittests\": \"54ae00077cc60bb990ef86e67d92c502425b007d\", \"google_apis_unittests\": \"6826df46286751070ded90ee861240f1a08e7ad1\", \"gpu_unittests\": \"0867bf8af62c112dd2f389860a325741834db244\", \"gwp_asan_unittests\": \"6aeffaaf12f78435928891242f768d466747d188\", \"headless_browsertests\": \"4e61072ef63246299514b12196b25c5ad90ca2c1\", \"headless_unittests\": \"35bf43ffd265f8b9509a288187c01851f7f771db\", \"install_static_unittests\": \"b7498949eaa70121b374b699df120ef72de666a9\", \"installer_util_unittests\": \"f8400a194838ef134067b5975492c555a706249d\", \"interactive_ui_tests\": \"46343a8b0f7ff553bb04c45dd2ed43ceb5ade286\", \"ipc_tests\": \"122d1470ac2a40be1cbcb0dbb209bcc3ef5f72a9\", \"jingle_unittests\": \"bdce8188dff5e749d1301d3fc8df31a151c8e462\", \"latency_unittests\": \"0bb06dac91bcf16f7b018c591a9310abfa202a34\", \"libjingle_xmpp_unittests\": \"4726cce8f64b76dbcb978a949a4b1e0be99c8710\", \"liburlpattern_unittests\": \"c5aecb4fa3b340e4e0cf0842d5328d66f50d4a07\", \"media_blink_unittests\": \"05eb33e910198c93a7e8229722f1999b47eab3c1\", \"media_unittests\": \"e61ea876f30f5bd5b48717ec45c47e2caa144140\", \"message_center_unittests\": \"8a8222b07b80ab19aa83c9b7087b5c697180b7b6\", \"midi_unittests\": \"34cb8c9c227008e39b223ce11d1442910ac4e4b8\", \"mojo_core_unittests\": \"70a03ae267a64441ba1fd52f674acf4d3aeab9c4\", \"mojo_unittests\": \"e9b57e265b288cbfd427475b8b27c2e5a6d3e454\", \"nacl_loader_unittests\": \"3f67bff8814265d4bdc3c0e93086b4dedd21b7fc\", \"native_theme_unittests\": \"33162886d98c9f22292bed788c2f20b0b03b6ab5\", \"net_unittests\": \"7542198cefd35d205b0d3d67fd093a1fc409e72e\", \"notification_helper_unittests\": \"f42f7039d6bcf22ccd1deb5c70e0b64171c6b354\", \"pdf_unittests\": \"a3059dea588f16603822ab8b810c6d6844eb587b\", \"perfetto_unittests\": \"ffdd0f8951c78891e1fd2c9b0410e720672ec056\", \"ppapi_unittests\": \"6dce14f170c0426ce1b582b06c1e309ef6570a3b\", \"printing_unittests\": \"790639fac5817e9bf85f06d9bef1ccbc89b1d177\", \"remoting_unittests\": \"020f7bef24970cfe694c13ee2b16b3f0b3f4696f\", \"sbox_integration_tests\": \"c7d92a3d14a44aa4082dab5402af5aa3a5d775d8\", \"sbox_unittests\": \"690e1665de4ef7d42d1ddba3bdbfabcff5ba4b53\", \"sbox_validation_tests\": \"ffe12ef36ef1354b20b214d2e1465e98c1a10851\", \"service_manager_unittests\": \"15650be773e979ff81b79c9904df82c5935e09d0\", \"services_unittests\": \"964499351761e986ff6172a9cb55f9a2cd114306\", \"setup_unittests\": \"3f5a0683eb48bb5657f932940afe7ba47a0bf03a\", \"shell_dialogs_unittests\": \"142e1325b44eab134a080fcfaf4d3c9cd1fdb249\", \"skia_unittests\": \"8679200a1b5ea4c585a3b913d6c7669e70ff4ed1\", \"snapshot_unittests\": \"95567082c87cfcdc5607d1f8a6bec7ba7161302d\", \"sql_unittests\": \"3af3f451664c9048b8751aac78f7c1c358424a43\", \"storage_unittests\": \"e920c8b31db143b471de9a6f736f9a3b1c1a6611\", \"sync_integration_tests\": \"c41410530588688d276ccb44e90264e5e8d5d954\", \"traffic_annotation_auditor_unittests\": \"34f6b1737aea97fb10b732e130f7901be90f7e2d\", \"ui_base_unittests\": \"0069ca0a5dabefcb37ac1de15bb30b49011630e5\", \"ui_touch_selection_unittests\": \"c44919b0421f950b6b1841ee8dc8e0df3d6dd5ee\", \"unit_tests\": \"240d7c512da11ca402aebce21d53fd3d340b3eb7\", \"updater_tests\": \"30c156a0b64672e2199c9de597e73cdd7d424cde\", \"url_unittests\": \"52cdd0e36d2708f13a6931db8797e8547b16a858\", \"views_unittests\": \"9d3516132939a071d284f4f5eb71f95091f988a1\", \"viz_unittests\": \"9406a13ac0361142ebe96020ac198971b4fb3404\", \"vr_common_unittests\": \"1cacf867f83ea0a78fdb6c0b9d56a5369e4c4e65\", \"vr_pixeltests\": \"4ae633f438d49ce28aef550316b0a8e8281458a1\", \"weblayer_browsertests\": \"d9b620193e4d84219d3a9eb88324ef45dbba8bc8\", \"weblayer_unittests\": \"633818e6c7c5d6317b47a3e2eb635be96de0ede2\", \"wm_unittests\": \"c771fee5df3e176fd9777188f03b110937baf35b\", \"wtf_unittests\": \"2e1c3e5b82f259037b926a35e7b040481189445a\", \"zlib_unittests\": \"b1847eac2e652f4afa12f8d28cb9b9e2c76d0131\", \"zucchini_unittests\": \"7a694ff83ca09524b4a2298fdc62e8cb95b5ecd8\"}}, \"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-0-6l07\"], \"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\": [\"5700-b36f18b\"], \"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": "1613508664921408",
"status": "COMPLETED",
"status_changed_ts": "1613512527281279",
"tags": [
"build_address:luci.chromium.ci/win-asan/17381",
"builder:win-asan",
"buildset:commit/git/acc794db2fbd1da098046004e4a8f2fcf498d2c2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/acc794db2fbd1da098046004e4a8f2fcf498d2c2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020537322572264544",
"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/8855107260138739952/+/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:51c56dd6440ca610",
"user_agent:luci-scheduler"
],
"updated_ts": "1613512527359021",
"url": "https://ci.chromium.org/b/8855107260138739952",
"utcnow_ts": "1613512529418045"
}
}
[21:55:29.447] Invocation finished in 1h4m31.960654573s with status SUCCEEDED