[05:10:30.537] New invocation is queued and will start shortly
[05:10:31.691] Starting the invocation (attempt 1)
[05:10:31.766] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:10:31.766] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:10:31.767] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025126338856064064",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4e3517627d816761917a05e8f020bfcb0f3914c7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025126338856064064",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/4e3517627d816761917a05e8f020bfcb0f3914c7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4e3517627d816761917a05e8f020bfcb0f3914c7",
"gitiles_ref:refs/heads/master"
]
}
[05:10:32.333] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609132231841034",
"id": "8859696276823297808",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4e3517627d816761917a05e8f020bfcb0f3914c7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609132232216308",
"tags": [
"build_address:luci.chromium.ci/win-asan/16498",
"builder:win-asan",
"buildset:commit/git/4e3517627d816761917a05e8f020bfcb0f3914c7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4e3517627d816761917a05e8f020bfcb0f3914c7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025126338856064064",
"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/8859696276823297808/+/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": "1609132232216409",
"url": "https://ci.chromium.org/b/8859696276823297808",
"utcnow_ts": "1609132232319838"
}
}
[05:10:32.333] Task URL: https://ci.chromium.org/b/8859696276823297808
[05:10:32.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:2:0) after 1m0s
[05:10:37.576] Received PubSub notification, asking Buildbucket for the build status
[05:10:37.635] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:32.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:2:0)
[05:11:32.341] Timer tick, asking Buildbucket for the build status
[05:11:32.475] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:32.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:4:0) after 1m0s
[05:12:32.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:4:0)
[05:12:32.578] Timer tick, asking Buildbucket for the build status
[05:12:32.664] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:32.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:5:0) after 1m0s
[05:13:32.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:5:0)
[05:13:32.721] Timer tick, asking Buildbucket for the build status
[05:13:32.838] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:32.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:6:0) after 1m0s
[05:14:32.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:6:0)
[05:14:32.984] Timer tick, asking Buildbucket for the build status
[05:14:33.192] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:33.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:7:0) after 1m0s
[05:15:33.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:7:0)
[05:15:33.237] Timer tick, asking Buildbucket for the build status
[05:15:33.917] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:33.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:8:0) after 1m0s
[05:16:34.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:8:0)
[05:16:34.106] Timer tick, asking Buildbucket for the build status
[05:16:34.366] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:34.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:9:0) after 1m0s
[05:17:34.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:9:0)
[05:17:34.384] Timer tick, asking Buildbucket for the build status
[05:17:34.440] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:34.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:10:0) after 1m0s
[05:18:34.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:10:0)
[05:18:34.505] Timer tick, asking Buildbucket for the build status
[05:18:34.562] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:34.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:11:0) after 1m0s
[05:19:34.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:11:0)
[05:19:34.781] Timer tick, asking Buildbucket for the build status
[05:19:34.856] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:34.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:12:0) after 1m0s
[05:20:34.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:12:0)
[05:20:34.866] Timer tick, asking Buildbucket for the build status
[05:20:34.951] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:34.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:13:0) after 1m0s
[05:21:34.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:13:0)
[05:21:34.991] Timer tick, asking Buildbucket for the build status
[05:21:35.172] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:35.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:14:0) after 1m0s
[05:22:35.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:14:0)
[05:22:35.272] Timer tick, asking Buildbucket for the build status
[05:22:35.689] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:35.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:15:0) after 1m0s
[05:23:35.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:15:0)
[05:23:35.755] Timer tick, asking Buildbucket for the build status
[05:23:35.907] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:35.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:16:0) after 1m0s
[05:24:36.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:16:0)
[05:24:36.007] Timer tick, asking Buildbucket for the build status
[05:24:36.169] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:36.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:17:0) after 1m0s
[05:25:36.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:17:0)
[05:25:36.181] Timer tick, asking Buildbucket for the build status
[05:25:36.277] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:36.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:18:0) after 1m0s
[05:26:36.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:18:0)
[05:26:36.295] Timer tick, asking Buildbucket for the build status
[05:26:36.412] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:36.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:19:0) after 1m0s
[05:27:36.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:19:0)
[05:27:36.496] Timer tick, asking Buildbucket for the build status
[05:27:36.617] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:36.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:20:0) after 1m0s
[05:28:36.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:20:0)
[05:28:36.651] Timer tick, asking Buildbucket for the build status
[05:28:36.764] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:36.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:21:0) after 1m0s
[05:29:36.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:21:0)
[05:29:36.879] Timer tick, asking Buildbucket for the build status
[05:29:36.965] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:36.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:22:0) after 1m0s
[05:30:37.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:22:0)
[05:30:37.086] Timer tick, asking Buildbucket for the build status
[05:30:37.192] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:37.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:23:0) after 1m0s
[05:31:37.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:23:0)
[05:31:37.213] Timer tick, asking Buildbucket for the build status
[05:31:37.362] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:37.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:24:0) after 1m0s
[05:32:39.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:24:0)
[05:32:39.106] Timer tick, asking Buildbucket for the build status
[05:32:39.397] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:39.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:25:0) after 1m0s
[05:33:39.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:25:0)
[05:33:39.568] Timer tick, asking Buildbucket for the build status
[05:33:39.677] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:39.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:26:0) after 1m0s
[05:34:40.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:26:0)
[05:34:40.262] Timer tick, asking Buildbucket for the build status
[05:34:40.364] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:40.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:27:0) after 1m0s
[05:35:40.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:27:0)
[05:35:40.721] Timer tick, asking Buildbucket for the build status
[05:35:40.830] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:40.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:28:0) after 1m0s
[05:36:40.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:28:0)
[05:36:40.955] Timer tick, asking Buildbucket for the build status
[05:36:41.052] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:41.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:29:0) after 1m0s
[05:37:41.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:29:0)
[05:37:41.128] Timer tick, asking Buildbucket for the build status
[05:37:41.297] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:41.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:30:0) after 1m0s
[05:38:41.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:30:0)
[05:38:41.516] Timer tick, asking Buildbucket for the build status
[05:38:41.899] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:41.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:31:0) after 1m0s
[05:39:41.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:31:0)
[05:39:41.941] Timer tick, asking Buildbucket for the build status
[05:39:42.025] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:42.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:32:0) after 1m0s
[05:40:42.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:32:0)
[05:40:42.067] Timer tick, asking Buildbucket for the build status
[05:40:42.176] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:42.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:33:0) after 1m0s
[05:41:42.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:33:0)
[05:41:42.297] Timer tick, asking Buildbucket for the build status
[05:41:42.366] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:42.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:34:0) after 1m0s
[05:42:42.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:34:0)
[05:42:42.416] Timer tick, asking Buildbucket for the build status
[05:42:42.483] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:42.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:35:0) after 1m0s
[05:43:42.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:35:0)
[05:43:42.584] Timer tick, asking Buildbucket for the build status
[05:43:42.713] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:42.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:36:0) after 1m0s
[05:44:42.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:36:0)
[05:44:42.737] Timer tick, asking Buildbucket for the build status
[05:44:42.844] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:42.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:37:0) after 1m0s
[05:45:42.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:37:0)
[05:45:42.875] Timer tick, asking Buildbucket for the build status
[05:45:43.099] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:43.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:38:0) after 1m0s
[05:46:43.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:38:0)
[05:46:43.123] Timer tick, asking Buildbucket for the build status
[05:46:43.186] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:43.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:39:0) after 1m0s
[05:47:43.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:39:0)
[05:47:43.208] Timer tick, asking Buildbucket for the build status
[05:47:43.281] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:43.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:40:0) after 1m0s
[05:48:43.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:40:0)
[05:48:43.306] Timer tick, asking Buildbucket for the build status
[05:48:43.429] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:43.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:41:0) after 1m0s
[05:49:43.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:41:0)
[05:49:43.496] Timer tick, asking Buildbucket for the build status
[05:49:43.558] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:43.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:42:0) after 1m0s
[05:50:43.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:42:0)
[05:50:43.678] Timer tick, asking Buildbucket for the build status
[05:50:43.784] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:43.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:43:0) after 1m0s
[05:51:43.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:43:0)
[05:51:43.855] Timer tick, asking Buildbucket for the build status
[05:51:44.166] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:44.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:44:0) after 1m0s
[05:52:44.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:44:0)
[05:52:44.182] Timer tick, asking Buildbucket for the build status
[05:52:44.617] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:44.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:45:0) after 1m0s
[05:53:44.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:45:0)
[05:53:44.636] Timer tick, asking Buildbucket for the build status
[05:53:44.782] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:44.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:46:0) after 1m0s
[05:54:44.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:46:0)
[05:54:44.804] Timer tick, asking Buildbucket for the build status
[05:54:45.299] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:45.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:47:0) after 1m0s
[05:55:45.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:47:0)
[05:55:45.354] Timer tick, asking Buildbucket for the build status
[05:55:45.466] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:45.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:48:0) after 1m0s
[05:56:45.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:48:0)
[05:56:45.529] Timer tick, asking Buildbucket for the build status
[05:56:45.621] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:45.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:49:0) after 1m0s
[05:57:45.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:49:0)
[05:57:45.639] Timer tick, asking Buildbucket for the build status
[05:57:45.784] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:45.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:50:0) after 1m0s
[05:58:45.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:50:0)
[05:58:45.811] Timer tick, asking Buildbucket for the build status
[05:58:45.882] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:45.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:51:0) after 1m0s
[05:59:45.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:51:0)
[05:59:45.953] Timer tick, asking Buildbucket for the build status
[05:59:46.062] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:46.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:52:0) after 1m0s
[06:00:46.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:52:0)
[06:00:46.173] Timer tick, asking Buildbucket for the build status
[06:00:47.204] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:47.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:53:0) after 1m0s
[06:01:54.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:53:0)
[06:01:54.019] Timer tick, asking Buildbucket for the build status
[06:01:54.216] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:54.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:54:0) after 1m0s
[06:02:54.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:54:0)
[06:02:54.362] Timer tick, asking Buildbucket for the build status
[06:02:54.486] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:54.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:55:0) after 1m0s
[06:03:54.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:55:0)
[06:03:54.563] Timer tick, asking Buildbucket for the build status
[06:03:54.935] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:54.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:56:0) after 1m0s
[06:04:55.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:56:0)
[06:04:55.048] Timer tick, asking Buildbucket for the build status
[06:04:55.183] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:55.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:57:0) after 1m0s
[06:05:55.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:57:0)
[06:05:55.295] Timer tick, asking Buildbucket for the build status
[06:05:55.911] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:55.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:58:0) after 1m0s
[06:06:55.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:58:0)
[06:06:55.996] Timer tick, asking Buildbucket for the build status
[06:06:56.093] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:56.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:59:0) after 1m0s
[06:07:56.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:59:0)
[06:07:56.115] Timer tick, asking Buildbucket for the build status
[06:07:56.184] Build 8859696276823297808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:56.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025126338856064064:60:0) after 1m0s
[06:08:15.076] Received PubSub notification, asking Buildbucket for the build status
[06:08:15.156] Build 8859696276823297808: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[06:08:15.156] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609135694545859",
"created_by": "project:chromium",
"created_ts": "1609132231841034",
"failure_reason": "BUILD_FAILURE",
"id": "8859696276823297808",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4e3517627d816761917a05e8f020bfcb0f3914c7\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithNoFilesShouldDeleteTempFile\"}, \"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\": \"4e3517627d816761917a05e8f020bfcb0f3914c7\", \"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-us-central1-b-32-1-2sgf\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16498, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"5e606e5bfce2b23c139c5289cbf1299844fa4b43\", \"got_dawn_revision\": \"c8d5277e86ee8c51b1dd7892b41e83eb26ac2ede\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"4e3517627d816761917a05e8f020bfcb0f3914c7\", \"got_revision_cp\": \"refs/heads/master@{#839413}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"4d7ae9713defe93b6f3de031454727a30c70a734\", \"got_v8_revision_cp\": \"refs/heads/8.9.192@{#1}\", \"got_webrtc_revision\": \"b90546580ec3e8236225f39f9e5e5e7b7128c24b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32881}\", \"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\": \"4e3517627d816761917a05e8f020bfcb0f3914c7\", \"swarm_hashes_refs/heads/master(at){#839413}_without_patch\": {\"absl_hardening_tests\": \"ce1f0f9b9b25e3cdf6a07a0e0b76af9716afb48f\", \"accessibility_unittests\": \"eba92e220e164de334aa7deaed1291afc322706f\", \"angle_unittests\": \"f2002a5259159c8967b9ae3a38ed1e949011cf61\", \"app_shell_unittests\": \"a8728a2b3b8f4dda75a99e0c7c76a5f11f712928\", \"aura_unittests\": \"d06d5d1b16bd2b0d2605858be9b5f169dad06c26\", \"base_unittests\": \"6abcc1a70511c41a7571a5b61c6cf6fe88e1521a\", \"base_util_unittests\": \"5c845bf46e78057b517371484642806e1e521fd4\", \"blink_common_unittests\": \"83f2d837fccdff539d379722211641a45e04f763\", \"blink_fuzzer_unittests\": \"2bbb9cc93df43fa541821a6347efe13c03d7abe4\", \"blink_heap_unittests\": \"5b71887bc8eb71b322b259459fdfea77afb4c83e\", \"blink_platform_unittests\": \"b581507865c6d691db8867189e26287be4e6b075\", \"blink_unittests\": \"65c830344bf0b28c46abd7ce42bda6d41e4b69f1\", \"boringssl_crypto_tests\": \"2504f21ea2ffdfb788e76dcef30b9541551ef265\", \"boringssl_ssl_tests\": \"cedf376879c94d77766e67635c228f52690a5ae8\", \"browser_switcher_bho_unittests\": \"a778b9e8cd53b81c550f894474346318a7bfba9d\", \"browser_tests\": \"5eb6f9c5dc1ef81cb092a358935a4205d1c471f7\", \"capture_unittests\": \"70b6a0635fc8dbae1b270989777948020c36f5c8\", \"cast_unittests\": \"263b1e5d1dcf5243609a998790aaf800ecde3beb\", \"cc_unittests\": \"4c6c7bf33f2700962b5fbe6cf42287bbd90a5dd7\", \"chrome_app_unittests\": \"9b5df0d16987c04f98fb441156bdda2580b3581e\", \"chrome_cleaner_unittests\": \"8e2ee9f997894807d56cd7b188af010b3dab2193\", \"chrome_elf_unittests\": \"a70c4b657336b6e3a61fcdbe3112099beafcd2c0\", \"chromedriver_unittests\": \"f6eba27c0e77aab7a58a6a0a7981e3b262388217\", \"components_browsertests\": \"788cbd0bda18a394b370db34da07d799fb0c6005\", \"components_unittests\": \"d7feead5aba58f539a77e233b7a91d8df2d348f3\", \"compositor_unittests\": \"2625fa060046817cc7cc23a9c09a8b472be7c1c9\", \"content_browsertests\": \"a89faaa168c3781c5e85ea00ce528d6a0b41a7a8\", \"content_unittests\": \"da6944b0cb0d3315ef55681f4f4309a230825c06\", \"courgette_unittests\": \"9255ed270c746a4277c076afb8e4ecc0765a665e\", \"crashpad_tests\": \"505482293273a34ff74cf1fe369e89dbddb15977\", \"cronet_tests\": \"ca87f280b297cd5a530899673fcd380ebfefaa24\", \"cronet_unittests\": \"5d7e82703fb839ba12a482a295b00c62ea461818\", \"crypto_unittests\": \"c48cfbbd3fe8af209aefad43c9d29265df725895\", \"delayloads_unittests\": \"63bbe9461472e43d01e895a8bc107ec689763bfe\", \"device_unittests\": \"b9854f8f856dce7d3d719bc2ae94cfd21229ebd3\", \"display_unittests\": \"847abf2d578d62c982c13dc893414bca1400271b\", \"elevation_service_unittests\": \"19d2db9e5ba53e536315860fe1ea5c804f4cb04c\", \"events_unittests\": \"0ed13b61480372dc777f7611428f85d7e7ecf234\", \"extensions_browsertests\": \"e7cf1d2951bcc031db256d8aa1e432da57e21899\", \"extensions_unittests\": \"bda54baf3f9c13ba4b7d0ff1220e5503efb6fc2c\", \"filesystem_service_unittests\": \"89dd1542c992bda1747d781aea08665a99d11d4d\", \"gcm_unit_tests\": \"222bda486a541decc789a49cb6aac07ce4305836\", \"gcp_unittests\": \"6f36089167a105a601bd96f88b0951fa6c3be061\", \"gfx_unittests\": \"6020061ddacfad2940a8f5d073915f8f707eeb2f\", \"gin_unittests\": \"445beb1635911fed6b446a8e1c773fa2f2af4596\", \"google_apis_unittests\": \"d97fe5e51b9c470841190d373ea3a877da925df7\", \"gpu_unittests\": \"b71fac5e17fb4a87cbcee982a9d4935285a6cd89\", \"gwp_asan_unittests\": \"4ee736f8e9eb3049d0288af289c5b9b8a75d030f\", \"headless_browsertests\": \"bec5fcd0c35f322411db1422d6cfa77bca65a50b\", \"headless_unittests\": \"0bbdd237efa6c2bfab8d9b091a808b9f014a440b\", \"install_static_unittests\": \"9a99598a4a04a34d442ea8164d217d7bd4aee5cd\", \"installer_util_unittests\": \"d6d610424f99e42ef1dab7d3c58c887d6c8d20c3\", \"interactive_ui_tests\": \"74b0a2ccb73bf19a5499609f57f9651e52a3576b\", \"ipc_tests\": \"9cb8449cf604eb6a17f6e5236bb6a22701226c6f\", \"jingle_unittests\": \"10de20512f3762c15e7de5da646102436b3bd5cb\", \"latency_unittests\": \"c05fc0b4b89fe5d2de8a56d38b6c2999b5c44181\", \"libjingle_xmpp_unittests\": \"2ca994cb2da85c1c6fd970340d7618f54a90e9c3\", \"liburlpattern_unittests\": \"e83204cc574022d43bc66ff187ae4be4bfd1d3a0\", \"media_blink_unittests\": \"9deca8c81c890eea7393a0457befc4a2add0eac1\", \"media_unittests\": \"3f500c631300356fd00aa5dea16c4d907082a306\", \"message_center_unittests\": \"4045e7b31aca38d46666ec20c8493445cf5d2a18\", \"midi_unittests\": \"673fb4e5938afb16f791e83f81427496714d118d\", \"mojo_core_unittests\": \"19501930761909f9a2dc66e5f038640dca795c62\", \"mojo_unittests\": \"e88a25dccce3770bb56ec8a19b07f340a6d248f5\", \"nacl_loader_unittests\": \"e23489d37c1d63b032f59d9ea80b36d00b6835e7\", \"native_theme_unittests\": \"2b321ac6850ba437837b661cd643d4542c59092b\", \"net_unittests\": \"174af46ccf001969f8170a643cf29fcf07e28d7a\", \"notification_helper_unittests\": \"34b3c1116a680db249052831f82c677c8a2fb9c3\", \"pdf_unittests\": \"96446b45c6de313a32dd0a9b791bc04fe50218bc\", \"perfetto_unittests\": \"579be7f61edfec382e75f1d9d552160b7eec9df3\", \"ppapi_unittests\": \"bc7c01e9a44e83201277cc7877dd8af6194595b1\", \"printing_unittests\": \"14104a11eeb6c6bb795dd72925489f8da7ae95c6\", \"remoting_unittests\": \"3748c5a04767e1be53ac280f17148930a238ec51\", \"sbox_integration_tests\": \"be56f3e66968a0273b92354c28e94f781ea5c76f\", \"sbox_unittests\": \"ebbdcfed84b280a25f8a8f05ce6b0571b4498c4e\", \"sbox_validation_tests\": \"5b1f6785a0e202c160a932a0d96b671710dc02e9\", \"service_manager_unittests\": \"3003ecbb55258aaaa9506543f91488189c0942ee\", \"services_unittests\": \"daf848cbad593dc988d3db6d06ddd0b53364d331\", \"setup_unittests\": \"e0051700ccea65efaf0afd1e7509842e1ccbbec3\", \"shell_dialogs_unittests\": \"c99631ba1974c19101d9191c5498a09509be71f8\", \"skia_unittests\": \"c73b7ada21d28ac2d3d094c77a688d96b1b37fd6\", \"snapshot_unittests\": \"8190a530963396a39f14b0f3fc259c3644fc4403\", \"sql_unittests\": \"b45de565cabf3f41cf03e4f4dcf579a0121ba3a4\", \"storage_unittests\": \"ba6156f00c63b50203834b4d199ae5a5fe6e8560\", \"sync_integration_tests\": \"77601e62c1be1ed690e6893af885fe99ca0b3ec3\", \"traffic_annotation_auditor_unittests\": \"5430e29b8db5ddf0ce6c8267dda8d94f0de85044\", \"ui_base_unittests\": \"9ab297ac3a4ef9dbe5b845d1e6885eb17df8395f\", \"ui_touch_selection_unittests\": \"d76df13d92b89e90955a342d81ce1f383e56c8ca\", \"unit_tests\": \"a22de562b0de6a18c8bd94a5b53cf53579229c14\", \"updater_tests\": \"e78479edaf2e52c55600474d2088bab558afcd98\", \"url_unittests\": \"7577df4a5fedba73d303bbfbaf2d9e85e56a4c9f\", \"views_unittests\": \"9fb5ce40580b2f159dbb4dd2a1c973713da6adfc\", \"viz_unittests\": \"4abeb61c7203526ea8ec0fc243cb9b7216a3776c\", \"vr_common_unittests\": \"db148051bb73dfcbf3d0e92bd11bbd6f33e250f1\", \"vr_pixeltests\": \"4410e9d73c83d54635e7a16a240e81e3a5b4103f\", \"weblayer_browsertests\": \"0ee99d031f7101eff8ba99cdbe70f0f88da25faf\", \"weblayer_unittests\": \"23a7f563942b1fa3c4f549c8e48afddac75d65b5\", \"wm_unittests\": \"473045d6a749dbacad6cc704227a8fc4e0787b7f\", \"wtf_unittests\": \"38f9ac97e93ed54d3723b3c75073c81bb72d3910\", \"zlib_unittests\": \"e628db89766469d0cdb585c401b421df55196a2b\", \"zucchini_unittests\": \"cdb5b6a1297b26bd6c43ad609c532815f0456100\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-2sgf\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithNoFilesShouldDeleteTempFile\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609132235395420",
"status": "COMPLETED",
"status_changed_ts": "1609132235655607",
"tags": [
"build_address:luci.chromium.ci/win-asan/16498",
"builder:win-asan",
"buildset:commit/git/4e3517627d816761917a05e8f020bfcb0f3914c7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4e3517627d816761917a05e8f020bfcb0f3914c7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025126338856064064",
"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/8859696276823297808/+/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:50c092d6e9820110",
"user_agent:luci-scheduler"
],
"updated_ts": "1609135694816293",
"url": "https://ci.chromium.org/b/8859696276823297808",
"utcnow_ts": "1609135695145272"
}
}
[06:08:15.156] Invocation finished in 57m44.637021626s with status FAILED