[06:54:22.842] New invocation is queued and will start shortly
[06:54:23.968] Starting the invocation (attempt 1)
[06:54:24.013] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:54:24.013] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:54:24.013] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023579655379105184",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"39b3e3292be886c96205bed77ae45a8015ba8083\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023579655379105184",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/39b3e3292be886c96205bed77ae45a8015ba8083",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39b3e3292be886c96205bed77ae45a8015ba8083",
"gitiles_ref:refs/heads/master"
]
}
[06:54:24.800] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610607264099297",
"id": "8858149593398472688",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"39b3e3292be886c96205bed77ae45a8015ba8083\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610607264418436",
"tags": [
"build_address:luci.chromium.ci/win-asan/16810",
"builder:win-asan",
"buildset:commit/git/39b3e3292be886c96205bed77ae45a8015ba8083",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39b3e3292be886c96205bed77ae45a8015ba8083",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023579655379105184",
"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/8858149593398472688/+/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": "1610607264418552",
"url": "https://ci.chromium.org/b/8858149593398472688",
"utcnow_ts": "1610607264718494"
}
}
[06:54:24.800] Task URL: https://ci.chromium.org/b/8858149593398472688
[06:54:24.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:2:0) after 1m0s
[06:54:26.943] Received PubSub notification, asking Buildbucket for the build status
[06:54:27.016] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:24.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:2:0)
[06:55:24.875] Timer tick, asking Buildbucket for the build status
[06:55:25.269] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:25.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:4:0) after 1m0s
[06:56:25.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:4:0)
[06:56:25.329] Timer tick, asking Buildbucket for the build status
[06:56:25.488] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:25.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:5:0) after 1m0s
[06:57:25.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:5:0)
[06:57:25.510] Timer tick, asking Buildbucket for the build status
[06:57:25.656] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:25.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:6:0) after 1m0s
[06:58:25.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:6:0)
[06:58:25.706] Timer tick, asking Buildbucket for the build status
[06:58:25.932] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:25.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:7:0) after 1m0s
[06:59:25.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:7:0)
[06:59:25.958] Timer tick, asking Buildbucket for the build status
[06:59:26.230] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:26.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:8:0) after 1m0s
[07:00:26.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:8:0)
[07:00:26.285] Timer tick, asking Buildbucket for the build status
[07:00:26.361] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:26.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:9:0) after 1m0s
[07:01:26.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:9:0)
[07:01:26.465] Timer tick, asking Buildbucket for the build status
[07:01:26.529] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:26.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:10:0) after 1m0s
[07:02:26.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:10:0)
[07:02:26.542] Timer tick, asking Buildbucket for the build status
[07:02:26.599] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:26.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:11:0) after 1m0s
[07:03:26.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:11:0)
[07:03:26.672] Timer tick, asking Buildbucket for the build status
[07:03:26.849] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:26.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:12:0) after 1m0s
[07:04:26.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:12:0)
[07:04:26.959] Timer tick, asking Buildbucket for the build status
[07:04:27.048] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:27.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:13:0) after 1m0s
[07:05:27.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:13:0)
[07:05:27.134] Timer tick, asking Buildbucket for the build status
[07:05:27.260] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:27.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:14:0) after 1m0s
[07:06:27.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:14:0)
[07:06:27.323] Timer tick, asking Buildbucket for the build status
[07:06:27.408] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:27.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:15:0) after 1m0s
[07:07:27.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:15:0)
[07:07:27.607] Timer tick, asking Buildbucket for the build status
[07:07:27.993] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:27.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:16:0) after 1m0s
[07:08:28.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:16:0)
[07:08:28.087] Timer tick, asking Buildbucket for the build status
[07:08:28.223] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:28.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:17:0) after 1m0s
[07:09:28.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:17:0)
[07:09:28.227] Timer tick, asking Buildbucket for the build status
[07:09:28.354] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:28.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:18:0) after 1m0s
[07:10:28.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:18:0)
[07:10:28.371] Timer tick, asking Buildbucket for the build status
[07:10:28.601] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:28.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:19:0) after 1m0s
[07:11:28.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:19:0)
[07:11:28.710] Timer tick, asking Buildbucket for the build status
[07:11:29.102] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:29.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:20:0) after 1m0s
[07:12:29.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:20:0)
[07:12:29.154] Timer tick, asking Buildbucket for the build status
[07:12:29.293] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:29.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:21:0) after 1m0s
[07:13:29.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:21:0)
[07:13:29.310] Timer tick, asking Buildbucket for the build status
[07:13:31.011] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:31.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:22:0) after 1m0s
[07:14:31.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:22:0)
[07:14:31.114] Timer tick, asking Buildbucket for the build status
[07:14:31.432] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:31.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:23:0) after 1m0s
[07:15:31.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:23:0)
[07:15:31.447] Timer tick, asking Buildbucket for the build status
[07:15:31.681] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:31.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:24:0) after 1m0s
[07:16:31.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:24:0)
[07:16:31.781] Timer tick, asking Buildbucket for the build status
[07:16:32.270] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:32.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:25:0) after 1m0s
[07:17:32.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:25:0)
[07:17:32.387] Timer tick, asking Buildbucket for the build status
[07:17:32.868] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:32.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:26:0) after 1m0s
[07:18:32.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:26:0)
[07:18:32.897] Timer tick, asking Buildbucket for the build status
[07:18:33.323] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:33.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:27:0) after 1m0s
[07:19:33.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:27:0)
[07:19:33.403] Timer tick, asking Buildbucket for the build status
[07:19:33.513] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:33.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:28:0) after 1m0s
[07:20:33.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:28:0)
[07:20:33.551] Timer tick, asking Buildbucket for the build status
[07:20:33.649] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:33.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:29:0) after 1m0s
[07:21:33.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:29:0)
[07:21:33.740] Timer tick, asking Buildbucket for the build status
[07:21:34.289] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:34.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:30:0) after 1m0s
[07:22:34.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:30:0)
[07:22:34.351] Timer tick, asking Buildbucket for the build status
[07:22:34.537] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:34.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:31:0) after 1m0s
[07:23:34.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:31:0)
[07:23:34.555] Timer tick, asking Buildbucket for the build status
[07:23:34.702] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:34.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:32:0) after 1m0s
[07:24:34.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:32:0)
[07:24:34.717] Timer tick, asking Buildbucket for the build status
[07:24:35.380] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:35.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:33:0) after 1m0s
[07:25:35.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:33:0)
[07:25:35.589] Timer tick, asking Buildbucket for the build status
[07:25:35.666] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:35.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:34:0) after 1m0s
[07:26:35.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:34:0)
[07:26:35.781] Timer tick, asking Buildbucket for the build status
[07:26:36.153] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:36.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:35:0) after 1m0s
[07:27:36.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:35:0)
[07:27:36.261] Timer tick, asking Buildbucket for the build status
[07:27:36.719] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:36.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:36:0) after 1m0s
[07:28:36.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:36:0)
[07:28:36.849] Timer tick, asking Buildbucket for the build status
[07:28:37.041] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:37.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:37:0) after 1m0s
[07:29:37.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:37:0)
[07:29:37.108] Timer tick, asking Buildbucket for the build status
[07:29:37.219] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:37.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:38:0) after 1m0s
[07:30:37.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:38:0)
[07:30:37.335] Timer tick, asking Buildbucket for the build status
[07:30:37.614] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:37.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:39:0) after 1m0s
[07:31:37.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:39:0)
[07:31:37.657] Timer tick, asking Buildbucket for the build status
[07:31:37.760] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:37.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:40:0) after 1m0s
[07:32:37.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:40:0)
[07:32:37.847] Timer tick, asking Buildbucket for the build status
[07:32:38.203] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:38.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:41:0) after 1m0s
[07:33:38.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:41:0)
[07:33:38.176] Timer tick, asking Buildbucket for the build status
[07:33:38.239] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:38.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:42:0) after 1m0s
[07:34:38.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:42:0)
[07:34:38.223] Timer tick, asking Buildbucket for the build status
[07:34:38.333] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:38.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:43:0) after 1m0s
[07:35:38.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:43:0)
[07:35:38.389] Timer tick, asking Buildbucket for the build status
[07:35:38.474] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:38.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:44:0) after 1m0s
[07:36:38.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:44:0)
[07:36:38.602] Timer tick, asking Buildbucket for the build status
[07:36:38.686] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:38.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:45:0) after 1m0s
[07:37:38.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:45:0)
[07:37:38.788] Timer tick, asking Buildbucket for the build status
[07:37:38.910] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:38.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:46:0) after 1m0s
[07:38:38.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:46:0)
[07:38:38.932] Timer tick, asking Buildbucket for the build status
[07:38:39.630] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:39.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:47:0) after 1m0s
[07:39:39.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:47:0)
[07:39:39.697] Timer tick, asking Buildbucket for the build status
[07:39:39.805] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:39.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:48:0) after 1m0s
[07:40:39.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:48:0)
[07:40:39.870] Timer tick, asking Buildbucket for the build status
[07:40:40.022] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:40.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:49:0) after 1m0s
[07:41:40.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:49:0)
[07:41:40.141] Timer tick, asking Buildbucket for the build status
[07:41:40.400] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:40.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:50:0) after 1m0s
[07:42:40.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:50:0)
[07:42:40.512] Timer tick, asking Buildbucket for the build status
[07:42:40.614] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:40.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:51:0) after 1m0s
[07:43:40.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:51:0)
[07:43:40.645] Timer tick, asking Buildbucket for the build status
[07:43:41.509] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:41.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:52:0) after 1m0s
[07:44:41.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:52:0)
[07:44:41.593] Timer tick, asking Buildbucket for the build status
[07:44:41.778] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:41.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:53:0) after 1m0s
[07:45:41.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:53:0)
[07:45:41.832] Timer tick, asking Buildbucket for the build status
[07:45:41.987] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:41.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:54:0) after 1m0s
[07:46:42.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:54:0)
[07:46:42.076] Timer tick, asking Buildbucket for the build status
[07:46:42.237] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:42.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:55:0) after 1m0s
[07:47:42.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:55:0)
[07:47:42.265] Timer tick, asking Buildbucket for the build status
[07:47:42.424] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:42.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:56:0) after 1m0s
[07:48:42.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:56:0)
[07:48:42.539] Timer tick, asking Buildbucket for the build status
[07:48:42.602] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:42.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:57:0) after 1m0s
[07:49:42.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:57:0)
[07:49:42.670] Timer tick, asking Buildbucket for the build status
[07:49:42.808] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:42.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:58:0) after 1m0s
[07:50:42.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:58:0)
[07:50:42.865] Timer tick, asking Buildbucket for the build status
[07:50:42.988] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:42.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:59:0) after 1m0s
[07:51:43.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:59:0)
[07:51:43.054] Timer tick, asking Buildbucket for the build status
[07:51:43.139] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:43.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:60:0) after 1m0s
[07:52:43.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:60:0)
[07:52:43.217] Timer tick, asking Buildbucket for the build status
[07:52:43.526] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:43.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:61:0) after 1m0s
[07:53:43.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:61:0)
[07:53:43.570] Timer tick, asking Buildbucket for the build status
[07:53:43.676] Build 8858149593398472688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:43.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023579655379105184:62:0) after 1m0s
[07:54:26.471] Received PubSub notification, asking Buildbucket for the build status
[07:54:26.544] Build 8858149593398472688: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:54:26.544] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610610864735542",
"created_by": "project:chromium",
"created_ts": "1610607264099297",
"id": "8858149593398472688",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"39b3e3292be886c96205bed77ae45a8015ba8083\"}}",
"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\": \"39b3e3292be886c96205bed77ae45a8015ba8083\", \"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-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16810, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"198dc345383e1f17252945bdb4a6dd47f4345ad4\", \"got_dawn_revision\": \"6f833b7f78c92595d3ec74ee622dab1868fdf40b\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"39b3e3292be886c96205bed77ae45a8015ba8083\", \"got_revision_cp\": \"refs/heads/master@{#843417}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"2cc3490759bb615084aab934a559ac66a9818880\", \"got_v8_revision\": \"712b239be61d29c242052c85f927da5291648e41\", \"got_v8_revision_cp\": \"refs/heads/8.9.272@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"39b3e3292be886c96205bed77ae45a8015ba8083\", \"swarm_hashes_refs/heads/master(at){#843417}_without_patch\": {\"absl_hardening_tests\": \"e5d200530756ee63183975eb05cece6b271f0d95\", \"accessibility_unittests\": \"bfa6f7be1b3309064d7bf3885e0e3f56afba9e53\", \"angle_unittests\": \"dd3bee314122ee6d324b179bfa33bf78e3b7f0b1\", \"app_shell_unittests\": \"931a73b00194310f723dfd8aeb2fcf338b7cd5d2\", \"aura_unittests\": \"e466c5d47014221b55592d577d27c66f1111f00d\", \"base_unittests\": \"8674b4152feee17838e350d0eecd0d6794db368f\", \"base_util_unittests\": \"262151d1d7b6f11e1333516b147d64315a589055\", \"blink_common_unittests\": \"591beb3b6683b1aaae3229c1530758db6174139d\", \"blink_fuzzer_unittests\": \"094817678a6b094313f8f2643c980dda8893fbef\", \"blink_heap_unittests\": \"ad3d3c79d2036ef980c2354d49428ceb51889e82\", \"blink_platform_unittests\": \"b87f0874933e64ea5ace742eb65775f81c25b6e5\", \"blink_unittests\": \"695e447cf156525b7a716cd2cedea1a4871ff62f\", \"boringssl_crypto_tests\": \"4d55d3433385cd277560b7b757b8856f144c9052\", \"boringssl_ssl_tests\": \"f4a74d0719257cd8a75f0fc3203113baa17c1708\", \"browser_switcher_bho_unittests\": \"2140e8c5d7e45c625b12f9c245eda90eb093d4e9\", \"browser_tests\": \"484536ef93fe2fa19daebf5606f8bedbf09754ea\", \"capture_unittests\": \"571c2500e8a1b83904287916e60bf9e7e1e038b3\", \"cast_unittests\": \"ff99bf6d77f69b79406185937f311b8d0ac37c01\", \"cc_unittests\": \"ca4851108bd0f506cdd07b5bb6ffd373137e720b\", \"chrome_app_unittests\": \"7520dfbb7e73271bf78727110df8a89e848cecc1\", \"chrome_cleaner_unittests\": \"ebb65a3c205088022c8ea274168ca687dd68352a\", \"chrome_elf_unittests\": \"e22e398e7ad577201683b8b29afebd3660050289\", \"chromedriver_unittests\": \"d757f7160337fd17862f1f919f2725d64151eb31\", \"components_browsertests\": \"d12eb7ed2eef100f4faf754ab0a095cbf4c42779\", \"components_unittests\": \"dfa4545bf2aec4319f1450bd78f14fddb921bf20\", \"compositor_unittests\": \"b4a51af4c5bc801e9880bf2e1fe0db94f47d919c\", \"content_browsertests\": \"b6bb3d745c0b451ba0bf7e9a804c04705ac65f1b\", \"content_unittests\": \"f0473a86b960535e241ec718a43bb5c9ebef0ac7\", \"courgette_unittests\": \"5b36c05fbf6b6a0daaeb02ba86575ec3a7f6186e\", \"crashpad_tests\": \"274aaa96e55b178c72ba7f6ea60b2494a08f27b7\", \"cronet_tests\": \"3d72c0300c84e8036ae3b3c75f7151a2b94bd4f7\", \"cronet_unittests\": \"5a5cc0454f2cd62813f22fad29baa50e4c30f6bc\", \"crypto_unittests\": \"13e3b845582a26f4f7e63923560370dc444e7ff9\", \"delayloads_unittests\": \"ec362300013be949b0a88a8cfe4362eeefe80257\", \"device_unittests\": \"dc9c1dbb51d00050be7c92ec58d34b1c4e3e23d5\", \"display_unittests\": \"0b16569f1432aaa0fa4665ff271d1e39439dc44f\", \"elevation_service_unittests\": \"61ae8bd5f27f12e6c4cdabc80d2314817ea39cd4\", \"events_unittests\": \"28504f43b04ba91c3ee84e2ca575872a8463a1f2\", \"extensions_browsertests\": \"d1ec37754bc024ba0f343c819ef7b44cfbfa8ed2\", \"extensions_unittests\": \"d08a50f54b3abc2062b465fec8dbddc782750573\", \"filesystem_service_unittests\": \"519f37d0a276c7c759a96edb1d035087db6e484f\", \"gcm_unit_tests\": \"542708573e98fe59544681bdabaeeae4b12fb47e\", \"gcp_unittests\": \"22250e12eab9dcf4e4b6db758587663e9edee8f3\", \"gfx_unittests\": \"3344ec1118da5b600ffa5995a201d85513ca719b\", \"gin_unittests\": \"9a39bf043ba3d175d34647ce69f9a77b4ff65647\", \"google_apis_unittests\": \"e7d02536f42b85b87b0c7a40be20abdb175c17ab\", \"gpu_unittests\": \"627ab251872b20c01718312106cc866ff30a4dac\", \"gwp_asan_unittests\": \"0edb5e62ab3bf0fd55d1879597645b04f8f920c5\", \"headless_browsertests\": \"b2b64cc1af99aa443dbeae35620e7782221d9b5f\", \"headless_unittests\": \"6de13f6045b5b6c9f999b4d1c8c5f52c4aad0e73\", \"install_static_unittests\": \"b34655fa879dd19a7c852488e9794b5945dff31c\", \"installer_util_unittests\": \"13dfca2ce3f3418642cd8c712584f71a1e9dfbae\", \"interactive_ui_tests\": \"b04fd598f1979a8c6f08f0ee0540686b241ce762\", \"ipc_tests\": \"b60cd4e6dbfbc7ff6d3f338130a4696741c18712\", \"jingle_unittests\": \"5d15934670ec8d146d05a79f40944b56dba1cc06\", \"latency_unittests\": \"1b765adf3581700311a0913d4c0ebdd9489f1a9c\", \"libjingle_xmpp_unittests\": \"28d9f75b640c56f0722f94da46fdeda8bac30f37\", \"liburlpattern_unittests\": \"6d7aa038f5e23f9350f241bfca4beaaea063e019\", \"media_blink_unittests\": \"64014523fcf7356e657c93350e0a6dbe61a406c1\", \"media_unittests\": \"a82ef3badbbd1aebeef8d3f58716b20f05ec88d1\", \"message_center_unittests\": \"7528c50d6eb736f7a746add541e6b56239fd659d\", \"midi_unittests\": \"92cb49e04ad7f2c8f57717ca582fe552b8040360\", \"mojo_core_unittests\": \"cc2d1f8753fe339755fda5bd8ca0c0aa3b658051\", \"mojo_unittests\": \"02b8ea00c06261de70e52e4c4d958de6fe621a49\", \"nacl_loader_unittests\": \"89ce0316842811d416eef07afc293e52e3e41c4f\", \"native_theme_unittests\": \"592a7736a8bd0345c62a28545506ddaac1ae1aa0\", \"net_unittests\": \"e090bf6e981640cd7c47d3d4bf75fd4df0035a50\", \"notification_helper_unittests\": \"12546d4e5cf9f25d80703114f832cea1bdf4ed76\", \"pdf_unittests\": \"f7a62baece76416539b5888cecf1885da6d719a8\", \"perfetto_unittests\": \"d232aaa00814b97103f49b55419728a7ea8b169f\", \"ppapi_unittests\": \"a97a8c9fd5c7c1977afb6e1fcf94a959ded58de4\", \"printing_unittests\": \"00644c3c1d2733db1c19ae75fc6c5a4314d8cc31\", \"remoting_unittests\": \"3367bac6f35e743e87813525da5fcfac78dbcab1\", \"sbox_integration_tests\": \"918c44c0dba0a7476ac0403e32c1ab20a0c3b4fa\", \"sbox_unittests\": \"3e90ce37a98cfc8214fa7bf36f691328e5fe1340\", \"sbox_validation_tests\": \"8e927bdb66d094882b16bd9dd2885175f1d53b0e\", \"service_manager_unittests\": \"a9e7eddc801fe9c0b2981b51bc9ed45ddf879bad\", \"services_unittests\": \"ad22fef61ebf3bab1b590a39393e4ccea24d1d81\", \"setup_unittests\": \"1f875fcbc3919b3df856cbc0c8f76a65302f4d20\", \"shell_dialogs_unittests\": \"99a16bebf4f6233986c80155a7fba6017a798d27\", \"skia_unittests\": \"9db21c2730a41032242ca7ac5468ab8993e5862e\", \"snapshot_unittests\": \"cf7b5e7d7c55d0d438d766b261dcc0bbaead653d\", \"sql_unittests\": \"47c132da9e8b679a7f214ffce49b52ec01323bda\", \"storage_unittests\": \"eb6f3e6b4115ee6e799d7ae3910359bb7ce9bf33\", \"sync_integration_tests\": \"741e1ca4fef8fc0de1d612864fbd0bf7264364e6\", \"traffic_annotation_auditor_unittests\": \"2f4a7eed60d8f9bc58273b19706aca6df832f51d\", \"ui_base_unittests\": \"1119d01d4091b9a8b0aff0236e75262153d2baf6\", \"ui_touch_selection_unittests\": \"d31e5f1e5e37806d145d2f0c71fd6f6fb81c062d\", \"unit_tests\": \"9950921c293615a12bd1130b536854c55517114a\", \"updater_tests\": \"e96f6e72fa896deec0a2645bc21808f17f6900a2\", \"url_unittests\": \"2cd8594fcc2595a70a3651cad0e65e288c3ad289\", \"views_unittests\": \"7a25e717ce0cd3328e50a11fa14cba395c30af21\", \"viz_unittests\": \"cbb32048532f3d98fa3e46528f0e42ca853ebd38\", \"vr_common_unittests\": \"7aea52bc1d2eb947ccc6a3da0b4efcac46853ecb\", \"vr_pixeltests\": \"215652de43ed78d1e46154d7316c6fd1c3166367\", \"weblayer_browsertests\": \"262a1f4444ce40e5e5c423d12599a78403056635\", \"weblayer_unittests\": \"1f9d40cc93031806cfe37a663ca1f54c031d13cf\", \"wm_unittests\": \"177643c6d7916317fdeca5744bb7c6d44a08c005\", \"wtf_unittests\": \"2049655c48c66639434cd88d19300837e9392b2d\", \"zlib_unittests\": \"a210b2dc73ef9bd9735550fd6e75ca91eed09365\", \"zucchini_unittests\": \"b306dc77361d37da49650fb7266fa492967c7b40\"}}, \"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-nb2x\"], \"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\": [\"5661-c6f6250\"], \"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": "1610607266443234",
"status": "COMPLETED",
"status_changed_ts": "1610610865204025",
"tags": [
"build_address:luci.chromium.ci/win-asan/16810",
"builder:win-asan",
"buildset:commit/git/39b3e3292be886c96205bed77ae45a8015ba8083",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39b3e3292be886c96205bed77ae45a8015ba8083",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023579655379105184",
"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/8858149593398472688/+/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:51187e0bcbc00e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610610865254178",
"url": "https://ci.chromium.org/b/8858149593398472688",
"utcnow_ts": "1610610866534163"
}
}
[07:54:26.544] Invocation finished in 1h0m3.718110343s with status SUCCEEDED