[00:05:32.887] New invocation is queued and will start shortly
[00:05:34.006] Starting the invocation (attempt 1)
[00:05:34.049] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:05:34.049] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:05:34.049] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028225822193118752",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"653fd6b1795f9529ec402dced33fe8a910853ef2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028225822193118752",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/653fd6b1795f9529ec402dced33fe8a910853ef2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/653fd6b1795f9529ec402dced33fe8a910853ef2",
"gitiles_ref:refs/heads/master"
]
}
[00:05:35.015] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606176334196974",
"id": "8862795760152177856",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"653fd6b1795f9529ec402dced33fe8a910853ef2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606176334867029",
"tags": [
"build_address:luci.chromium.ci/win-asan/15811",
"builder:win-asan",
"buildset:commit/git/653fd6b1795f9529ec402dced33fe8a910853ef2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/653fd6b1795f9529ec402dced33fe8a910853ef2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028225822193118752",
"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/8862795760152177856/+/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": "1606176334867229",
"url": "https://ci.chromium.org/b/8862795760152177856",
"utcnow_ts": "1606176335004915"
}
}
[00:05:35.015] Task URL: https://ci.chromium.org/b/8862795760152177856
[00:05:35.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:2:0) after 1m0s
[00:05:36.992] Received PubSub notification, asking Buildbucket for the build status
[00:05:37.084] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:35.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:2:0)
[00:06:35.156] Timer tick, asking Buildbucket for the build status
[00:06:35.276] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:35.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:4:0) after 1m0s
[00:07:35.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:4:0)
[00:07:35.460] Timer tick, asking Buildbucket for the build status
[00:07:35.990] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:35.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:5:0) after 1m0s
[00:08:36.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:5:0)
[00:08:36.304] Timer tick, asking Buildbucket for the build status
[00:08:36.420] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:36.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:6:0) after 1m0s
[00:09:36.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:6:0)
[00:09:36.515] Timer tick, asking Buildbucket for the build status
[00:09:36.772] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:36.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:7:0) after 1m0s
[00:10:36.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:7:0)
[00:10:36.950] Timer tick, asking Buildbucket for the build status
[00:10:37.308] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:37.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:8:0) after 1m0s
[00:11:37.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:8:0)
[00:11:37.405] Timer tick, asking Buildbucket for the build status
[00:11:37.496] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:37.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:9:0) after 1m0s
[00:12:37.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:9:0)
[00:12:37.931] Timer tick, asking Buildbucket for the build status
[00:12:37.991] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:37.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:10:0) after 1m0s
[00:13:38.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:10:0)
[00:13:38.150] Timer tick, asking Buildbucket for the build status
[00:13:38.216] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:38.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:11:0) after 1m0s
[00:14:38.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:11:0)
[00:14:38.383] Timer tick, asking Buildbucket for the build status
[00:14:38.614] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:38.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:12:0) after 1m0s
[00:15:38.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:12:0)
[00:15:38.751] Timer tick, asking Buildbucket for the build status
[00:15:39.323] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:39.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:13:0) after 1m0s
[00:16:39.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:13:0)
[00:16:39.435] Timer tick, asking Buildbucket for the build status
[00:16:39.512] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:39.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:14:0) after 1m0s
[00:17:39.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:14:0)
[00:17:39.572] Timer tick, asking Buildbucket for the build status
[00:17:39.635] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:39.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:15:0) after 1m0s
[00:18:39.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:15:0)
[00:18:39.716] Timer tick, asking Buildbucket for the build status
[00:18:40.042] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:40.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:16:0) after 1m0s
[00:19:40.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:16:0)
[00:19:40.158] Timer tick, asking Buildbucket for the build status
[00:19:40.297] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:40.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:17:0) after 1m0s
[00:20:40.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:17:0)
[00:20:40.280] Timer tick, asking Buildbucket for the build status
[00:20:40.424] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:40.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:18:0) after 1m0s
[00:21:40.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:18:0)
[00:21:40.424] Timer tick, asking Buildbucket for the build status
[00:21:40.557] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:40.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:19:0) after 1m0s
[00:22:40.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:19:0)
[00:22:40.578] Timer tick, asking Buildbucket for the build status
[00:22:40.719] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:40.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:20:0) after 1m0s
[00:23:40.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:20:0)
[00:23:40.781] Timer tick, asking Buildbucket for the build status
[00:23:40.972] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:40.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:21:0) after 1m0s
[00:24:41.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:21:0)
[00:24:41.052] Timer tick, asking Buildbucket for the build status
[00:24:41.461] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:41.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:22:0) after 1m0s
[00:25:41.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:22:0)
[00:25:41.561] Timer tick, asking Buildbucket for the build status
[00:25:41.685] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:41.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:23:0) after 1m0s
[00:26:41.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:23:0)
[00:26:41.734] Timer tick, asking Buildbucket for the build status
[00:26:42.896] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:42.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:24:0) after 1m0s
[00:27:42.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:24:0)
[00:27:42.965] Timer tick, asking Buildbucket for the build status
[00:27:43.088] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:43.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:25:0) after 1m0s
[00:28:43.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:25:0)
[00:28:43.179] Timer tick, asking Buildbucket for the build status
[00:28:43.387] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:43.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:26:0) after 1m0s
[00:29:43.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:26:0)
[00:29:43.523] Timer tick, asking Buildbucket for the build status
[00:29:43.846] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:43.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:27:0) after 1m0s
[00:30:43.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:27:0)
[00:30:43.936] Timer tick, asking Buildbucket for the build status
[00:30:44.155] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:44.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:28:0) after 1m0s
[00:31:44.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:28:0)
[00:31:44.235] Timer tick, asking Buildbucket for the build status
[00:31:44.395] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:44.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:29:0) after 1m0s
[00:32:44.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:29:0)
[00:32:44.454] Timer tick, asking Buildbucket for the build status
[00:32:44.854] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:44.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:30:0) after 1m0s
[00:33:44.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:30:0)
[00:33:44.971] Timer tick, asking Buildbucket for the build status
[00:33:45.168] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:45.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:31:0) after 1m0s
[00:34:45.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:31:0)
[00:34:45.568] Timer tick, asking Buildbucket for the build status
[00:34:45.687] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:45.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:32:0) after 1m0s
[00:35:45.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:32:0)
[00:35:45.755] Timer tick, asking Buildbucket for the build status
[00:35:45.876] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:45.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:33:0) after 1m0s
[00:36:46.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:33:0)
[00:36:46.047] Timer tick, asking Buildbucket for the build status
[00:36:46.168] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:46.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:34:0) after 1m0s
[00:37:46.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:34:0)
[00:37:46.254] Timer tick, asking Buildbucket for the build status
[00:37:46.368] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:46.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:35:0) after 1m0s
[00:38:46.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:35:0)
[00:38:46.411] Timer tick, asking Buildbucket for the build status
[00:38:47.479] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:47.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:36:0) after 1m0s
[00:39:47.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:36:0)
[00:39:47.694] Timer tick, asking Buildbucket for the build status
[00:39:47.827] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:47.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:37:0) after 1m0s
[00:40:48.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:37:0)
[00:40:48.010] Timer tick, asking Buildbucket for the build status
[00:40:48.339] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:48.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:38:0) after 1m0s
[00:41:48.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:38:0)
[00:41:48.369] Timer tick, asking Buildbucket for the build status
[00:41:48.489] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:48.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:39:0) after 1m0s
[00:42:48.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:39:0)
[00:42:48.723] Timer tick, asking Buildbucket for the build status
[00:42:48.796] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:48.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:40:0) after 1m0s
[00:43:49.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:40:0)
[00:43:49.168] Timer tick, asking Buildbucket for the build status
[00:43:49.262] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:49.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:41:0) after 1m0s
[00:44:49.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:41:0)
[00:44:49.291] Timer tick, asking Buildbucket for the build status
[00:44:49.557] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:49.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:42:0) after 1m0s
[00:45:49.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:42:0)
[00:45:49.728] Timer tick, asking Buildbucket for the build status
[00:45:49.970] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:49.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:43:0) after 1m0s
[00:46:50.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:43:0)
[00:46:50.032] Timer tick, asking Buildbucket for the build status
[00:46:50.113] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:50.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:44:0) after 1m0s
[00:47:50.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:44:0)
[00:47:50.145] Timer tick, asking Buildbucket for the build status
[00:47:50.277] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:50.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:45:0) after 1m0s
[00:48:50.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:45:0)
[00:48:50.324] Timer tick, asking Buildbucket for the build status
[00:48:50.597] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:50.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:46:0) after 1m0s
[00:49:50.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:46:0)
[00:49:50.593] Timer tick, asking Buildbucket for the build status
[00:49:50.677] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:50.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:47:0) after 1m0s
[00:50:50.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:47:0)
[00:50:50.703] Timer tick, asking Buildbucket for the build status
[00:50:50.983] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:50.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:48:0) after 1m0s
[00:51:51.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:48:0)
[00:51:51.147] Timer tick, asking Buildbucket for the build status
[00:51:51.289] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:51.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:49:0) after 1m0s
[00:52:51.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:49:0)
[00:52:51.316] Timer tick, asking Buildbucket for the build status
[00:52:51.453] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:51.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:50:0) after 1m0s
[00:53:51.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:50:0)
[00:53:51.604] Timer tick, asking Buildbucket for the build status
[00:53:51.945] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:51.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:51:0) after 1m0s
[00:54:52.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:51:0)
[00:54:52.058] Timer tick, asking Buildbucket for the build status
[00:54:52.198] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:52.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:52:0) after 1m0s
[00:55:52.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:52:0)
[00:55:52.322] Timer tick, asking Buildbucket for the build status
[00:55:52.545] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:52.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:53:0) after 1m0s
[00:56:52.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:53:0)
[00:56:52.575] Timer tick, asking Buildbucket for the build status
[00:56:52.824] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:52.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:54:0) after 1m0s
[00:57:52.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:54:0)
[00:57:52.879] Timer tick, asking Buildbucket for the build status
[00:57:52.956] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:52.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:55:0) after 1m0s
[00:58:52.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:55:0)
[00:58:52.985] Timer tick, asking Buildbucket for the build status
[00:58:53.449] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:53.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:56:0) after 1m0s
[00:59:53.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:56:0)
[00:59:53.473] Timer tick, asking Buildbucket for the build status
[00:59:53.773] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:53.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:57:0) after 1m0s
[01:00:53.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:57:0)
[01:00:53.865] Timer tick, asking Buildbucket for the build status
[01:00:54.285] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:54.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:58:0) after 1m0s
[01:01:54.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:58:0)
[01:01:54.432] Timer tick, asking Buildbucket for the build status
[01:01:55.376] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:55.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:59:0) after 1m0s
[01:02:55.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:59:0)
[01:02:55.520] Timer tick, asking Buildbucket for the build status
[01:02:55.721] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:55.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:60:0) after 1m0s
[01:03:56.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:60:0)
[01:03:56.082] Timer tick, asking Buildbucket for the build status
[01:03:56.208] Build 8862795760152177856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:56.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028225822193118752:61:0) after 1m0s
[01:04:51.383] Received PubSub notification, asking Buildbucket for the build status
[01:04:51.456] Build 8862795760152177856: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:04:51.456] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606179888992904",
"created_by": "project:chromium",
"created_ts": "1606176334196974",
"id": "8862795760152177856",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"653fd6b1795f9529ec402dced33fe8a910853ef2\"}}",
"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\": \"653fd6b1795f9529ec402dced33fe8a910853ef2\", \"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-3-xoby\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15811, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"abcabb474bf1c25eea48d7dca14bcafde737eba7\", \"got_dawn_revision\": \"03badec5d73ab4138f798b94221546c496177a09\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"653fd6b1795f9529ec402dced33fe8a910853ef2\", \"got_revision_cp\": \"refs/heads/master@{#830372}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"88e698a4f3e35f509ac80d9ffed8a4e0bbe3a85a\", \"got_v8_revision\": \"db1a5e72db56e0241356c2b824f50770d62dec90\", \"got_v8_revision_cp\": \"refs/heads/8.9.38@{#1}\", \"got_webrtc_revision\": \"19526a69c763d26f4699f14dd33681db8976a1b1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32676}\", \"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\": \"653fd6b1795f9529ec402dced33fe8a910853ef2\", \"swarm_hashes_refs/heads/master(at){#830372}_without_patch\": {\"absl_hardening_tests\": \"68220dabff8853b36faac8ed4caba006c78e3f6d\", \"accessibility_unittests\": \"89ef1585a9061f991c165dee26b545b804e3a4e6\", \"angle_unittests\": \"c49ac00933e4cbbbe4f2d4a39ea7be81fad62e6d\", \"app_shell_unittests\": \"5b6d69f209f2252cc4c2ac45b72278b1291ea1f2\", \"aura_unittests\": \"fa41f8f523ada6f1fe2277bb14cc95ff341ea5da\", \"base_unittests\": \"f95609bdaf8362a7f659d4a05101e4beac9d9af2\", \"base_util_unittests\": \"ba1dad7f381d613e0ac4cf90e557cdb748431588\", \"blink_common_unittests\": \"5dbf233dbb6f62e24b003e0d6c7c83bb75eef202\", \"blink_fuzzer_unittests\": \"3efebdcc07bca5f180123873393ee18ec39e8f51\", \"blink_heap_unittests\": \"e7f67b07f4f83bc74cff1189f9612c661fb6b097\", \"blink_platform_unittests\": \"96807193f65a1abf62ddaf1b5d0d54130537f6f5\", \"blink_unittests\": \"159be5b62d536d0adb4cab4faa04be5bd3046e9d\", \"boringssl_crypto_tests\": \"260a55559a572395d908bf3327ad4027660ba07c\", \"boringssl_ssl_tests\": \"1b2f9e3dd9249eadcbbe720f2364b0be62d49227\", \"browser_switcher_bho_unittests\": \"a56360406e5c7671d66c91f3d7e35b4ea75f2e3b\", \"browser_tests\": \"f48ad205606ac26a8f0398ebc658d694a09b379d\", \"capture_unittests\": \"07a0befe799ce5b3849b661fa296d0a4611bf4cf\", \"cast_unittests\": \"91a3f9f22237b9a5e8da7889f876df70ab5dbdbf\", \"cc_unittests\": \"d4ff2636bb8ebe808c72141620c1f5dd58c56ad9\", \"chrome_app_unittests\": \"85e24689d03a70285a371022c2d8fa2a712441a6\", \"chrome_cleaner_unittests\": \"e2e89e25780cb5b6a09d12f9a4fe5c33663dc2ed\", \"chrome_elf_unittests\": \"18fadb33a1ddb12d87bd4f6ebc76095570383967\", \"chromedriver_unittests\": \"d963d875edf01acd2770c7e7a51a6b56658accc7\", \"components_browsertests\": \"eb3eef1c6648666794a6c19e8d2b07717a25e6ef\", \"components_unittests\": \"36c5c6ffa6c161784829726daae03a98b8727644\", \"compositor_unittests\": \"76cc43d4e6c8da16851b4958ab1f6adc0e82d2a7\", \"content_browsertests\": \"09c6abd4cfaea0f4c5c53e6c385728d3c150283a\", \"content_unittests\": \"c4a6b5db92d08faf86444318c49d7bbd452d0d6a\", \"courgette_unittests\": \"de2f2040f1736932588335fe678928247f24048d\", \"crashpad_tests\": \"70f7f777f8181898298ca1237adbfeedf7a30046\", \"cronet_tests\": \"595aa6e47119e335e70252bdd6dbbf8c93661a63\", \"cronet_unittests\": \"cd7684432508f8782f6e32486cd47e4c61421900\", \"crypto_unittests\": \"e31d0abc0a3987e007cc85bf152e0036a9400cb5\", \"delayloads_unittests\": \"d063556fe02f5380b1824603a7c4505409f60fac\", \"device_unittests\": \"a0b7c72db9d58356dd52117305acfd660de9e00c\", \"display_unittests\": \"3450fc4dba5662cacfc4286c22df93d6feed1c15\", \"elevation_service_unittests\": \"6f585bc7d0e5399425485fbb94939c06ff99fd12\", \"events_unittests\": \"e9316faf38f655addd8fd72992f671da00e12b89\", \"extensions_browsertests\": \"562ef6225f84aa3fb7b438c4e8cba71c6adeab30\", \"extensions_unittests\": \"bb142e20c977fda46a684ebef5017597c56ac9d7\", \"filesystem_service_unittests\": \"8f109864f9829683d53619704034f80a13f04bbc\", \"gcm_unit_tests\": \"a0f601b56984c1db776d358228dffb54f2b1f93a\", \"gcp_unittests\": \"22dbfca7cf7ff35b8a35c15bcb089f6f194ad616\", \"gfx_unittests\": \"0a1950bbaf16479a70f487ae095a43f7012a8e63\", \"gin_unittests\": \"7575143d6f1626696bcb072456f49c957cba3767\", \"google_apis_unittests\": \"8e8bfcd17db7c367bc5b7eb3f6a118e2ec05bb54\", \"gpu_unittests\": \"ef793ce5aa393aa6723d442d583e413869e1ec36\", \"gwp_asan_unittests\": \"6a78bce1cbf6b1ce68c1dee3ee39c2953f2d8007\", \"headless_browsertests\": \"eacaf7ff278a3c5230b7920694f2e4895e25feb8\", \"headless_unittests\": \"8a25c7eb63feb6209547905b11b8f25d7b8bb238\", \"install_static_unittests\": \"56ce4bd4e518714745eec4df000cd1a9de265acf\", \"installer_util_unittests\": \"c5e2ef904c5288489ae52b19be57e656b570197b\", \"interactive_ui_tests\": \"16971afb2cfbf8df8738bb718808dfd777f68510\", \"ipc_tests\": \"766d256654ca71a6a072ce2ee42d04551b082b75\", \"jingle_unittests\": \"2c7196ac372988b1eefa40a061226fa8de52b71b\", \"latency_unittests\": \"bc27f47b61425b02f541bf1fa43c702be3c825de\", \"libjingle_xmpp_unittests\": \"36b8c8230be03179430798e633367abc8dcd8629\", \"liburlpattern_unittests\": \"a272d434e16c8488d3652c7b741821a129c7771e\", \"media_blink_unittests\": \"4bdb18215f2ca19261447ef006e0f189abba9576\", \"media_unittests\": \"3bf4f3ca0bbc2774b5632461bec67ab188b73e16\", \"message_center_unittests\": \"a720395d5ac24151bb728ec2c86ce49358d61a18\", \"midi_unittests\": \"00d781503802e7b0da68b2f7f9117b14ee20a1d5\", \"mojo_core_unittests\": \"0877f4ad1889eaa3ef4455c6db33cdd3eb1c03db\", \"mojo_unittests\": \"23d9ee4088827086b2c27659c424b0705b6fe49a\", \"nacl_loader_unittests\": \"f1874951afb229542309e8978250499572ab403a\", \"native_theme_unittests\": \"c022a6d03e373f75ef45f01b0560d63e3603a684\", \"net_unittests\": \"a40cbe061fd4c5af5c78e53b82bb5a4624bb7abd\", \"notification_helper_unittests\": \"f3ebc4f897e1f7c6b75440b146a0ad9440ec6ee2\", \"pdf_unittests\": \"0bf407a6ef14fe50c13d59e552d2db9d9081b4e6\", \"perfetto_unittests\": \"4caf43e0d66feb6eadd61ae5397ecdd3cb71cc9c\", \"ppapi_unittests\": \"7a857107f8f9575f7574fa796643403bfae315a7\", \"printing_unittests\": \"a1090e9f0baca7715c8a3fa22c7615428cc61d7c\", \"remoting_unittests\": \"40327e3b5ba5858a3ebdda159381d0cf692dc791\", \"sbox_integration_tests\": \"b6589497dd13559b24858cc45822699d3e529766\", \"sbox_unittests\": \"12b44e12208fe6006a8a5b936e0fed822d0fd65f\", \"sbox_validation_tests\": \"6e33e0fa59b56a7c6e1724f5ef0309dd8041caac\", \"service_manager_unittests\": \"d94209ba61d98aab92cf93dbb3044e80fb06d4c7\", \"services_unittests\": \"dd81538ca7a1a8a3ffe7de367e06489380c81397\", \"setup_unittests\": \"731c165abdc888b506b721c95b2613f3313afd16\", \"shell_dialogs_unittests\": \"da415b3ee74359f3ac08d1592610bfe48d833430\", \"skia_unittests\": \"ed1bfe38c723b592097212562775e681d7d3f534\", \"snapshot_unittests\": \"3176d25e0b6e78e66ff98619318d2618ca5396cd\", \"sql_unittests\": \"0565fa3ca9c81d97f4a3047cf8ee10c902369f00\", \"storage_unittests\": \"e3b144be3983b5af6b178add530214829e19b587\", \"sync_integration_tests\": \"d8aacb61e0bf9aff5ff58383ff6dff107f0661d7\", \"traffic_annotation_auditor_unittests\": \"ab8fb458ff99ea68fe295a72f6ef63010e98f079\", \"ui_base_unittests\": \"2fd3da6ef64f59df5c34acd0562c107143214063\", \"ui_touch_selection_unittests\": \"bdfee7b5fee0c653785161b2935f34cf79048580\", \"unit_tests\": \"5989f9e5fdf0fab80eeef8a734f83bb66c804a3e\", \"updater_tests\": \"cdb19ec3c87e05c7621888b792f708a9af725466\", \"url_unittests\": \"a375371d267332d077b90d6a0621cde17e461a1d\", \"views_unittests\": \"f5908deff78265db5e4a063ce3705f6dfd82c129\", \"viz_unittests\": \"5bef4aaf2c5ef298235373722689430bded8dfaa\", \"vr_common_unittests\": \"d9f84a6639833f62f02c6815d9e159db54927f0f\", \"vr_pixeltests\": \"ef508d7f0f4215a35e6240b0c83cfa0493b10e02\", \"weblayer_browsertests\": \"56394d2a07033440d25e3852cf9c4639f85f5eac\", \"weblayer_unittests\": \"493e3fab7c5d81303b742b7fd5941f9e5804be6c\", \"wm_unittests\": \"bf688ecdd65f379db80f191e3647d897efea026d\", \"wtf_unittests\": \"a3d54d50ae9b0daf913ca38bf1f97c0986431cfe\", \"zlib_unittests\": \"da7b44661fadd43e95ee3bfb8484caa2b48a2224\", \"zucchini_unittests\": \"22acaf2e0ac14cc75d36693ca3691755fac99d91\"}}, \"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-3-xoby\"], \"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\": [\"5637-4d8b832\"], \"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": "1606176336365593",
"status": "COMPLETED",
"status_changed_ts": "1606179889747229",
"tags": [
"build_address:luci.chromium.ci/win-asan/15811",
"builder:win-asan",
"buildset:commit/git/653fd6b1795f9529ec402dced33fe8a910853ef2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/653fd6b1795f9529ec402dced33fe8a910853ef2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028225822193118752",
"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/8862795760152177856/+/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:5010636d0dc6ab10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606179889832656",
"url": "https://ci.chromium.org/b/8862795760152177856",
"utcnow_ts": "1606179891446770"
}
}
[01:04:51.456] Invocation finished in 59m18.58981115s with status SUCCEEDED