[07:10:50.319] New invocation is queued and will start shortly
[07:10:51.517] Starting the invocation (attempt 1)
[07:10:51.554] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:10:51.554] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:10:51.554] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029920407583636304",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3c7580ad595f65d42f762405a3d3efca81ad0452\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029920407583636304",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/3c7580ad595f65d42f762405a3d3efca81ad0452",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c7580ad595f65d42f762405a3d3efca81ad0452",
"gitiles_ref:refs/heads/master"
]
}
[07:10:52.351] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604560251663596",
"id": "8864490345510631584",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3c7580ad595f65d42f762405a3d3efca81ad0452\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604560252089058",
"tags": [
"build_address:luci.chromium.ci/win-asan/15472",
"builder:win-asan",
"buildset:commit/git/3c7580ad595f65d42f762405a3d3efca81ad0452",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c7580ad595f65d42f762405a3d3efca81ad0452",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029920407583636304",
"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/8864490345510631584/+/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": "1604560252089217",
"url": "https://ci.chromium.org/b/8864490345510631584",
"utcnow_ts": "1604560252303175"
}
}
[07:10:52.351] Task URL: https://ci.chromium.org/b/8864490345510631584
[07:10:52.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:2:0) after 1m0s
[07:10:57.171] Received PubSub notification, asking Buildbucket for the build status
[07:10:57.253] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:52.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:2:0)
[07:11:52.361] Timer tick, asking Buildbucket for the build status
[07:11:52.655] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:52.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:4:0) after 1m0s
[07:12:52.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:4:0)
[07:12:52.719] Timer tick, asking Buildbucket for the build status
[07:12:52.836] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:52.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:5:0) after 1m0s
[07:13:54.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:5:0)
[07:13:54.077] Timer tick, asking Buildbucket for the build status
[07:13:54.196] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:54.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:6:0) after 1m0s
[07:14:54.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:6:0)
[07:14:54.317] Timer tick, asking Buildbucket for the build status
[07:14:54.449] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:54.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:7:0) after 1m0s
[07:15:54.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:7:0)
[07:15:54.481] Timer tick, asking Buildbucket for the build status
[07:15:54.776] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:54.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:8:0) after 1m0s
[07:16:54.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:8:0)
[07:16:54.816] Timer tick, asking Buildbucket for the build status
[07:16:54.890] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:54.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:9:0) after 1m0s
[07:17:54.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:9:0)
[07:17:54.910] Timer tick, asking Buildbucket for the build status
[07:17:54.973] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:54.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:10:0) after 1m0s
[07:18:55.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:10:0)
[07:18:55.010] Timer tick, asking Buildbucket for the build status
[07:18:57.472] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:57.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:11:0) after 1m0s
[07:19:57.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:11:0)
[07:19:57.766] Timer tick, asking Buildbucket for the build status
[07:19:58.140] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:58.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:12:0) after 1m0s
[07:20:58.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:12:0)
[07:20:58.214] Timer tick, asking Buildbucket for the build status
[07:20:58.325] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:58.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:13:0) after 1m0s
[07:21:58.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:13:0)
[07:21:58.413] Timer tick, asking Buildbucket for the build status
[07:21:58.536] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:58.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:14:0) after 1m0s
[07:22:58.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:14:0)
[07:22:58.565] Timer tick, asking Buildbucket for the build status
[07:22:59.162] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:59.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:15:0) after 1m0s
[07:23:59.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:15:0)
[07:23:59.254] Timer tick, asking Buildbucket for the build status
[07:23:59.370] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:59.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:16:0) after 1m0s
[07:24:59.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:16:0)
[07:24:59.471] Timer tick, asking Buildbucket for the build status
[07:24:59.852] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:59.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:17:0) after 1m0s
[07:26:00.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:17:0)
[07:26:00.034] Timer tick, asking Buildbucket for the build status
[07:26:00.469] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:00.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:18:0) after 1m0s
[07:27:01.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:18:0)
[07:27:01.248] Timer tick, asking Buildbucket for the build status
[07:27:01.490] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:01.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:19:0) after 1m0s
[07:28:01.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:19:0)
[07:28:01.963] Timer tick, asking Buildbucket for the build status
[07:28:02.117] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:02.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:20:0) after 1m0s
[07:29:02.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:20:0)
[07:29:02.149] Timer tick, asking Buildbucket for the build status
[07:29:02.327] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:02.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:21:0) after 1m0s
[07:30:02.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:21:0)
[07:30:02.366] Timer tick, asking Buildbucket for the build status
[07:30:03.117] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:03.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:22:0) after 1m0s
[07:31:03.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:22:0)
[07:31:03.188] Timer tick, asking Buildbucket for the build status
[07:31:04.343] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:04.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:23:0) after 1m0s
[07:32:04.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:23:0)
[07:32:04.428] Timer tick, asking Buildbucket for the build status
[07:32:04.751] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:04.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:24:0) after 1m0s
[07:33:04.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:24:0)
[07:33:04.766] Timer tick, asking Buildbucket for the build status
[07:33:04.923] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:04.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:25:0) after 1m0s
[07:34:04.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:25:0)
[07:34:04.918] Timer tick, asking Buildbucket for the build status
[07:34:05.232] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:05.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:26:0) after 1m0s
[07:35:05.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:26:0)
[07:35:05.302] Timer tick, asking Buildbucket for the build status
[07:35:05.399] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:05.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:27:0) after 1m0s
[07:36:05.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:27:0)
[07:36:05.623] Timer tick, asking Buildbucket for the build status
[07:36:06.266] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:06.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:28:0) after 1m0s
[07:37:06.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:28:0)
[07:37:06.371] Timer tick, asking Buildbucket for the build status
[07:37:06.533] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:06.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:29:0) after 1m0s
[07:38:06.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:29:0)
[07:38:06.620] Timer tick, asking Buildbucket for the build status
[07:38:06.854] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:06.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:30:0) after 1m0s
[07:39:07.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:30:0)
[07:39:07.068] Timer tick, asking Buildbucket for the build status
[07:39:07.166] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:07.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:31:0) after 1m0s
[07:40:07.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:31:0)
[07:40:07.413] Timer tick, asking Buildbucket for the build status
[07:40:07.497] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:07.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:32:0) after 1m0s
[07:41:07.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:32:0)
[07:41:07.797] Timer tick, asking Buildbucket for the build status
[07:41:07.930] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:07.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:33:0) after 1m0s
[07:42:08.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:33:0)
[07:42:08.292] Timer tick, asking Buildbucket for the build status
[07:42:08.434] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:08.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:34:0) after 1m0s
[07:43:08.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:34:0)
[07:43:08.572] Timer tick, asking Buildbucket for the build status
[07:43:08.665] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:08.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:35:0) after 1m0s
[07:44:08.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:35:0)
[07:44:08.700] Timer tick, asking Buildbucket for the build status
[07:44:08.822] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:08.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:36:0) after 1m0s
[07:45:08.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:36:0)
[07:45:08.866] Timer tick, asking Buildbucket for the build status
[07:45:09.245] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:09.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:37:0) after 1m0s
[07:46:09.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:37:0)
[07:46:09.317] Timer tick, asking Buildbucket for the build status
[07:46:09.452] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:09.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:38:0) after 1m0s
[07:47:09.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:38:0)
[07:47:09.496] Timer tick, asking Buildbucket for the build status
[07:47:10.879] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:10.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:39:0) after 1m0s
[07:48:11.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:39:0)
[07:48:11.139] Timer tick, asking Buildbucket for the build status
[07:48:11.336] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:11.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:40:0) after 1m0s
[07:49:11.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:40:0)
[07:49:11.435] Timer tick, asking Buildbucket for the build status
[07:49:11.543] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:11.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:41:0) after 1m0s
[07:50:11.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:41:0)
[07:50:11.607] Timer tick, asking Buildbucket for the build status
[07:50:12.159] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:12.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:42:0) after 1m0s
[07:51:12.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:42:0)
[07:51:12.326] Timer tick, asking Buildbucket for the build status
[07:51:12.466] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:12.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:43:0) after 1m0s
[07:52:12.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:43:0)
[07:52:12.489] Timer tick, asking Buildbucket for the build status
[07:52:12.621] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:12.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:44:0) after 1m0s
[07:53:12.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:44:0)
[07:53:12.769] Timer tick, asking Buildbucket for the build status
[07:53:12.843] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:12.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:45:0) after 1m0s
[07:54:13.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:45:0)
[07:54:13.036] Timer tick, asking Buildbucket for the build status
[07:54:13.144] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:13.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:46:0) after 1m0s
[07:55:13.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:46:0)
[07:55:13.426] Timer tick, asking Buildbucket for the build status
[07:55:13.563] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:13.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:47:0) after 1m0s
[07:56:13.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:47:0)
[07:56:13.961] Timer tick, asking Buildbucket for the build status
[07:56:14.491] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:14.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:48:0) after 1m0s
[07:57:14.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:48:0)
[07:57:14.569] Timer tick, asking Buildbucket for the build status
[07:57:14.666] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:14.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:49:0) after 1m0s
[07:58:14.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:49:0)
[07:58:14.769] Timer tick, asking Buildbucket for the build status
[07:58:14.904] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:14.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:50:0) after 1m0s
[07:59:15.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:50:0)
[07:59:15.098] Timer tick, asking Buildbucket for the build status
[07:59:20.830] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:20.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:51:0) after 1m0s
[08:00:21.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:51:0)
[08:00:21.411] Timer tick, asking Buildbucket for the build status
[08:00:21.564] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:21.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:52:0) after 1m0s
[08:01:21.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:52:0)
[08:01:21.672] Timer tick, asking Buildbucket for the build status
[08:01:21.960] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:21.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:53:0) after 1m0s
[08:02:22.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:53:0)
[08:02:22.079] Timer tick, asking Buildbucket for the build status
[08:02:22.264] Build 8864490345510631584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:22.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029920407583636304:54:0) after 1m0s
[08:03:18.202] Received PubSub notification, asking Buildbucket for the build status
[08:03:18.437] Build 8864490345510631584: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:03:18.437] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604563396905124",
"created_by": "project:chromium",
"created_ts": "1604560251663596",
"id": "8864490345510631584",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3c7580ad595f65d42f762405a3d3efca81ad0452\"}}",
"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\": \"3c7580ad595f65d42f762405a3d3efca81ad0452\", \"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-5-vrjs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15472, \"clang_revision\": \"llvmorg-12-init-10666-gc9f69ee7-1\", \"got_angle_revision\": \"1e494cae8d48e3e82600dd538280938a5baa13ea\", \"got_dawn_revision\": \"6564890116fd5629ad76948f12ad158d6f111cc3\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"3c7580ad595f65d42f762405a3d3efca81ad0452\", \"got_revision_cp\": \"refs/heads/master@{#824318}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"ad54c71632cc0f18584a7009f1e6fc44f5453bc7\", \"got_v8_revision\": \"7ae0bc9f7258d206e0de93911c5987c3e04979b8\", \"got_v8_revision_cp\": \"refs/heads/8.8.228@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3c7580ad595f65d42f762405a3d3efca81ad0452\", \"swarm_hashes_refs/heads/master(at){#824318}_without_patch\": {\"absl_hardening_tests\": \"c579cf9eccf1410e2f757b1fcde431fa927e87db\", \"accessibility_unittests\": \"acda22c4ad18553cdfdc1ccd1bcab883f18d286e\", \"angle_unittests\": \"464c1b9fa1754a8c4f452404d8113ea294c1d8a8\", \"app_shell_unittests\": \"723ccef6f3cf9f7ce39790ec169e7043965af08d\", \"aura_unittests\": \"0f9af7e74f08d4332213032cbab9d85eb21ecdba\", \"base_unittests\": \"20349b072f72db69909161ff87e164f05dd07000\", \"base_util_unittests\": \"0c0c819cdf4d3759ae64a2ca4b35bb81dd3ccac8\", \"blink_common_unittests\": \"866bda2fc899d7fe491c6ad9312c5009da39b791\", \"blink_fuzzer_unittests\": \"55dd9bb436f50ce9d0aeafd30081923ccc0783c7\", \"blink_heap_unittests\": \"edc3305cc1216f4fa8a412d347d2f1c2c6c69f54\", \"blink_platform_unittests\": \"615f62aefec336869833cf8b5e72835c6e1c675f\", \"blink_unittests\": \"bccae68d2c7c26c32ed9a5d1d3cfcdfbd5f8c634\", \"boringssl_crypto_tests\": \"ac50efc9f2d8aa35c6c9394bb7416364463b3590\", \"boringssl_ssl_tests\": \"da5a1707c0c714032818baf1d34ef53fd54e3297\", \"browser_switcher_bho_unittests\": \"74c023527e602357538c652a70c8d14c3e1030b1\", \"browser_tests\": \"c0e27ddb92dec4f26d31fc6b089a5473f3e60848\", \"capture_unittests\": \"e13c4d60e027ce39259562a246525691d6b0324d\", \"cast_unittests\": \"0b9a46dd1691a883fd36c7fd96183b9fc863a327\", \"cc_unittests\": \"0c1a389ae92f61beb773a2a0e574f93bebb72975\", \"chrome_app_unittests\": \"2aebc181c43d418442878dca9747b6d96c11dd06\", \"chrome_cleaner_unittests\": \"f6072709ca0553dd0750c465426fc36f5e8882c9\", \"chrome_elf_unittests\": \"c2397dfb7228fa4a6a62021c840708dcd4ee3a3c\", \"chromedriver_unittests\": \"b42d44ff431fb918d9d8605b5c9a9aba8508457e\", \"components_browsertests\": \"d81a2fb19ee0365e4f1eae8957a617138142331f\", \"components_unittests\": \"82e559f382f4116ad2bd58aebd29e2f5b1181957\", \"compositor_unittests\": \"55b9802678012b43f5972aaea1e0188d377f40b8\", \"content_browsertests\": \"3f1a70be328594b4d9f0b825b7623c98024bb552\", \"content_unittests\": \"759aeeeb9e3dcc836bd84c48be82c6ec58e3531e\", \"courgette_unittests\": \"d7a82196ce78446f738f0cb51b36f09dc023ad30\", \"crashpad_tests\": \"5bac3dc10a8a7e617f8f7b2519a3e356e5df4542\", \"cronet_tests\": \"27d6cf3bbbcb637b4dd74a2fd41e4d7c45870884\", \"cronet_unittests\": \"1f6898a56b90d125b728b50bce94e2edaf6dbd7d\", \"crypto_unittests\": \"61bffc5c07292bc4b68893f407b06409fc3682f7\", \"delayloads_unittests\": \"0899fb62ae601ba9ae9f82bbb00e9fe76fe657e8\", \"device_unittests\": \"80e3886a432f5087ee63d78b9ccd366eb05c6151\", \"display_unittests\": \"2524b9eb034b86d65fa008941bafbc37772aff79\", \"elevation_service_unittests\": \"c6413712e16f9315fa5249051f7a674935004c0c\", \"events_unittests\": \"d64dbefb256ad55b3f9031db665bfdb68f176085\", \"extensions_browsertests\": \"bd3221d0d2f3f9a970d3843b14422d2529725b9f\", \"extensions_unittests\": \"6e8ccc64800e321f186738113cf895bfb5bc82ae\", \"filesystem_service_unittests\": \"c41be6307a09f20b520896515ceb2af33da70fc4\", \"gcm_unit_tests\": \"3f13d3313557ab26a145a9a6d78b4564df81adb0\", \"gcp_unittests\": \"40a9a7450a6a4faa47467ffd09dd748e5805efd2\", \"gfx_unittests\": \"de1290e23a6003229e60ceededc16afa018299d6\", \"gin_unittests\": \"12d586d0258b4e3866fe3608eacb12deeed82858\", \"google_apis_unittests\": \"a709a63765f92cd9ec64b3d1ee7340c7252e9594\", \"gpu_unittests\": \"7ac4acb0bfb41ddbab820064acb47b3299797fb6\", \"gwp_asan_unittests\": \"bcab3c2fe02f43c50ef17bc364e2d3247b26c396\", \"headless_browsertests\": \"f6472a64947696ad296faedcd017810933fb1e40\", \"headless_unittests\": \"2ad7b31d807bd9c1000faee1394ae11431451b0d\", \"install_static_unittests\": \"c1daf2250d2cf31fd278b2bf4cbabda5b3742d69\", \"installer_util_unittests\": \"bd7e8d77fb4fd7ea7acdfc83ea59ec76085e15e9\", \"interactive_ui_tests\": \"b1695747e3ade62dbc4ac312c307a8b8ea19a4d8\", \"ipc_tests\": \"b2e18b0dc7be31f125c8b4155421e86f58e49986\", \"jingle_unittests\": \"966b50074cae04d0a767aa4e3309387b3be80bd2\", \"latency_unittests\": \"601a8ea0b588d2116db42ce262a0c1d6c04eee10\", \"libjingle_xmpp_unittests\": \"e0bcb6c76f4d74e7c721929d8374739f297dae64\", \"liburlpattern_unittests\": \"f13d33fe5f86166691a060157499d416c819c89f\", \"media_blink_unittests\": \"f08cbe258210a8a4589a1d820d5a421e5142a022\", \"media_unittests\": \"787f2eeb95415acb7a669dbe0044b1e5e8925160\", \"message_center_unittests\": \"b550910c01f27e9106f06cd1f3371f48b585d6f1\", \"midi_unittests\": \"b6434c392debd7fc1df43d5748fa8398a3ae9620\", \"mojo_core_unittests\": \"b17bf33030a7807c34d467485e1384c275f7926e\", \"mojo_unittests\": \"780f8f42ec5fbe42402a950e8417c216d84aecc4\", \"nacl_loader_unittests\": \"4c493cc3e748c5aec7731e7636085c8bba91976a\", \"native_theme_unittests\": \"0bd0d19181466e097751319a78b128910a1fa8a0\", \"net_unittests\": \"0449485f8c7a7006097e13f06432f0155ecd6805\", \"notification_helper_unittests\": \"20bc02210576f29d0021f4ecbad9c0a4d11e79c1\", \"pdf_unittests\": \"c3d4e604b5d95752d6f9e400c16e0addd6adfccf\", \"perfetto_unittests\": \"cf381c31f2e864fb87f92012de2acdfece7ca4c9\", \"ppapi_unittests\": \"20fcfd11ba47bef83373dc7ed310aa09b8d7db52\", \"printing_unittests\": \"b722d9b4c022f45f9dff5774729bb7c9daa386ea\", \"remoting_unittests\": \"b9e008c73d9ba3cafb503f1dcec8516d7e4c5152\", \"sbox_integration_tests\": \"510fba7d91280a37e9162b6cff7283723bce9c12\", \"sbox_unittests\": \"5e0505dd87bedb5371338e5c3b0648d6851ad3cd\", \"sbox_validation_tests\": \"58d896eff96535fe20999193167aedb2b7decd64\", \"service_manager_unittests\": \"9cbefceeca30573a3ef57da20c29701aef95a72a\", \"services_unittests\": \"e9b13f5ed02cd8790c0954e629e016ce0010c73d\", \"setup_unittests\": \"afe41ed2c1e429374dac8fa02a1aa9e1669ac71d\", \"shell_dialogs_unittests\": \"9c7b870cb66e77292956bcf382c3045deeb4097e\", \"skia_unittests\": \"ec60be309ce46198e98e02ad923975207af8e7ad\", \"snapshot_unittests\": \"9bb1c59f3bb8a43cca52e188d3251240b5146762\", \"sql_unittests\": \"81b8f72e2bfb60a9f9e971ba5d591c9f754f984b\", \"storage_unittests\": \"2b56c632b5507bc224fa3afb52e793468c4797df\", \"sync_integration_tests\": \"4c31c6cf018db6a5633fd2177a2a1eec04170141\", \"traffic_annotation_auditor_unittests\": \"24b0c8a10f17647befbdd58e68efcd0cbde545d1\", \"ui_base_unittests\": \"c26092c9d4735f6e7dad2c0a21a9ca2d9ec59e2f\", \"ui_touch_selection_unittests\": \"3195e71ea319e7cc6cc08eb8cfc0f3b7afb9dccc\", \"unit_tests\": \"f835c8b9d6a0e69ca7a83e062d617fda222aa7fc\", \"updater_tests\": \"3f76d08f218ac8cec7a64362bc4c2331f53b3caa\", \"url_unittests\": \"d1b18bb6c8ae3dfdcc8834c4bcede87f63367ca0\", \"views_unittests\": \"9d7ba9cfc3f5e99906cfc19a03b61f9289868453\", \"viz_unittests\": \"f016dfd7362fc30a78df32db99d2be5e9787b4a3\", \"vr_common_unittests\": \"9675c589fed30a8882da5a6d73812d121aaf99a6\", \"vr_pixeltests\": \"aef96d0b1d5ab74d94aefc80e1f2ab2b729b67d6\", \"weblayer_browsertests\": \"4b426272897a0dbde4ed60095a440a029cd522d2\", \"weblayer_unittests\": \"450b3e818c5409a047973ce73bc9bde85b3486ea\", \"wm_unittests\": \"873a22803eaee99f20073b5e77b0cdc3599971c8\", \"wtf_unittests\": \"81d17266539f40c4e9bdf822718ca200458d0a1a\", \"zlib_unittests\": \"53becd8bc685c18d75288e98730510d1c3af56c2\", \"zucchini_unittests\": \"0b6186f20859a4d2af745d6798dfc9c3ca6dca9e\"}}, \"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-5-vrjs\"], \"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\": [\"5614-d862eaa\"], \"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": "1604560256189560",
"status": "COMPLETED",
"status_changed_ts": "1604563397453358",
"tags": [
"build_address:luci.chromium.ci/win-asan/15472",
"builder:win-asan",
"buildset:commit/git/3c7580ad595f65d42f762405a3d3efca81ad0452",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c7580ad595f65d42f762405a3d3efca81ad0452",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029920407583636304",
"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/8864490345510631584/+/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:4fb00ff7fb365910",
"user_agent:luci-scheduler"
],
"updated_ts": "1604563397459351",
"url": "https://ci.chromium.org/b/8864490345510631584",
"utcnow_ts": "1604563398396743"
}
}
[08:03:18.437] Invocation finished in 52m28.135034976s with status SUCCEEDED