[04:12:21.300] New invocation is queued and will start shortly
[04:12:22.419] Starting the invocation (attempt 1)
[04:12:22.468] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:12:22.468] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:12:22.468] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029841039840224784",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2ca8b447af5482fc3f323ecaa40197436a4d244a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029841039840224784",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/2ca8b447af5482fc3f323ecaa40197436a4d244a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2ca8b447af5482fc3f323ecaa40197436a4d244a",
"gitiles_ref:refs/heads/master"
]
}
[04:12:24.488] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1604635942633747",
"id": "8864410977775451776",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2ca8b447af5482fc3f323ecaa40197436a4d244a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604635944318953",
"tags": [
"build_address:luci.chromium.ci/win-asan/15489",
"builder:win-asan",
"buildset:commit/git/2ca8b447af5482fc3f323ecaa40197436a4d244a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2ca8b447af5482fc3f323ecaa40197436a4d244a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029841039840224784",
"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/8864410977775451776/+/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": "1604635944319083",
"url": "https://ci.chromium.org/b/8864410977775451776",
"utcnow_ts": "1604635944479526"
}
}
[04:12:24.488] Task URL: https://ci.chromium.org/b/8864410977775451776
[04:12:24.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:2:0) after 1m0s
[04:12:28.228] Received PubSub notification, asking Buildbucket for the build status
[04:12:28.398] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:24.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:2:0)
[04:13:24.476] Timer tick, asking Buildbucket for the build status
[04:13:24.566] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:24.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:4:0) after 1m0s
[04:14:24.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:4:0)
[04:14:24.601] Timer tick, asking Buildbucket for the build status
[04:14:24.700] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:24.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:5:0) after 1m0s
[04:15:24.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:5:0)
[04:15:24.734] Timer tick, asking Buildbucket for the build status
[04:15:24.852] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:24.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:6:0) after 1m0s
[04:16:24.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:6:0)
[04:16:24.891] Timer tick, asking Buildbucket for the build status
[04:16:25.331] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:25.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:7:0) after 1m0s
[04:17:25.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:7:0)
[04:17:25.379] Timer tick, asking Buildbucket for the build status
[04:17:25.465] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:25.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:8:0) after 1m0s
[04:18:25.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:8:0)
[04:18:25.507] Timer tick, asking Buildbucket for the build status
[04:18:25.647] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:25.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:9:0) after 1m0s
[04:19:25.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:9:0)
[04:19:25.701] Timer tick, asking Buildbucket for the build status
[04:19:25.931] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:25.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:10:0) after 1m0s
[04:20:25.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:10:0)
[04:20:25.994] Timer tick, asking Buildbucket for the build status
[04:20:26.217] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:26.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:11:0) after 1m0s
[04:21:26.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:11:0)
[04:21:26.299] Timer tick, asking Buildbucket for the build status
[04:21:26.838] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:26.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:12:0) after 1m0s
[04:22:26.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:12:0)
[04:22:26.810] Timer tick, asking Buildbucket for the build status
[04:22:28.038] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:28.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:13:0) after 1m0s
[04:23:28.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:13:0)
[04:23:28.135] Timer tick, asking Buildbucket for the build status
[04:23:28.217] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:28.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:14:0) after 1m0s
[04:24:28.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:14:0)
[04:24:28.362] Timer tick, asking Buildbucket for the build status
[04:24:28.431] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:28.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:15:0) after 1m0s
[04:25:28.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:15:0)
[04:25:28.573] Timer tick, asking Buildbucket for the build status
[04:25:28.640] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:28.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:16:0) after 1m0s
[04:26:28.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:16:0)
[04:26:28.716] Timer tick, asking Buildbucket for the build status
[04:26:28.962] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:28.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:17:0) after 1m0s
[04:27:28.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:17:0)
[04:27:28.994] Timer tick, asking Buildbucket for the build status
[04:27:29.136] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:29.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:18:0) after 1m0s
[04:28:29.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:18:0)
[04:28:29.144] Timer tick, asking Buildbucket for the build status
[04:28:29.282] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:29.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:19:0) after 1m0s
[04:29:29.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:19:0)
[04:29:29.308] Timer tick, asking Buildbucket for the build status
[04:29:29.426] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:29.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:20:0) after 1m0s
[04:30:29.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:20:0)
[04:30:29.504] Timer tick, asking Buildbucket for the build status
[04:30:29.628] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:29.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:21:0) after 1m0s
[04:31:29.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:21:0)
[04:31:29.626] Timer tick, asking Buildbucket for the build status
[04:31:29.746] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:29.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:22:0) after 1m0s
[04:32:29.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:22:0)
[04:32:29.806] Timer tick, asking Buildbucket for the build status
[04:32:30.483] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:30.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:23:0) after 1m0s
[04:33:30.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:23:0)
[04:33:30.516] Timer tick, asking Buildbucket for the build status
[04:33:30.633] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:30.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:24:0) after 1m0s
[04:34:30.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:24:0)
[04:34:30.785] Timer tick, asking Buildbucket for the build status
[04:34:37.292] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:37.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:25:0) after 1m0s
[04:35:37.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:25:0)
[04:35:37.296] Timer tick, asking Buildbucket for the build status
[04:35:37.410] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:37.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:26:0) after 1m0s
[04:36:37.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:26:0)
[04:36:37.469] Timer tick, asking Buildbucket for the build status
[04:36:37.686] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:37.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:27:0) after 1m0s
[04:37:37.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:27:0)
[04:37:37.749] Timer tick, asking Buildbucket for the build status
[04:37:37.890] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:37.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:28:0) after 1m0s
[04:38:37.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:28:0)
[04:38:37.957] Timer tick, asking Buildbucket for the build status
[04:38:38.022] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:38.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:29:0) after 1m0s
[04:39:38.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:29:0)
[04:39:38.065] Timer tick, asking Buildbucket for the build status
[04:39:38.146] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:38.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:30:0) after 1m0s
[04:40:38.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:30:0)
[04:40:38.488] Timer tick, asking Buildbucket for the build status
[04:40:38.585] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:38.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:31:0) after 1m0s
[04:41:38.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:31:0)
[04:41:38.785] Timer tick, asking Buildbucket for the build status
[04:41:38.928] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:38.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:32:0) after 1m0s
[04:42:39.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:32:0)
[04:42:39.028] Timer tick, asking Buildbucket for the build status
[04:42:39.675] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:39.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:33:0) after 1m0s
[04:43:39.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:33:0)
[04:43:39.905] Timer tick, asking Buildbucket for the build status
[04:43:40.292] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:40.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:34:0) after 1m0s
[04:44:40.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:34:0)
[04:44:40.381] Timer tick, asking Buildbucket for the build status
[04:44:40.583] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:40.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:35:0) after 1m0s
[04:45:40.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:35:0)
[04:45:40.630] Timer tick, asking Buildbucket for the build status
[04:45:41.027] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:41.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:36:0) after 1m0s
[04:46:41.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:36:0)
[04:46:41.098] Timer tick, asking Buildbucket for the build status
[04:46:41.407] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:41.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:37:0) after 1m0s
[04:47:41.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:37:0)
[04:47:41.469] Timer tick, asking Buildbucket for the build status
[04:47:41.619] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:41.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:38:0) after 1m0s
[04:48:41.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:38:0)
[04:48:41.737] Timer tick, asking Buildbucket for the build status
[04:48:41.854] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:41.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:39:0) after 1m0s
[04:49:41.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:39:0)
[04:49:41.941] Timer tick, asking Buildbucket for the build status
[04:49:42.009] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:42.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:40:0) after 1m0s
[04:50:42.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:40:0)
[04:50:42.123] Timer tick, asking Buildbucket for the build status
[04:50:42.198] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:42.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:41:0) after 1m0s
[04:51:42.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:41:0)
[04:51:42.271] Timer tick, asking Buildbucket for the build status
[04:51:42.679] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:42.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:42:0) after 1m0s
[04:52:42.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:42:0)
[04:52:42.727] Timer tick, asking Buildbucket for the build status
[04:52:42.878] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:42.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:43:0) after 1m0s
[04:53:43.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:43:0)
[04:53:43.001] Timer tick, asking Buildbucket for the build status
[04:53:43.122] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:43.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:44:0) after 1m0s
[04:54:43.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:44:0)
[04:54:43.216] Timer tick, asking Buildbucket for the build status
[04:54:43.359] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:43.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:45:0) after 1m0s
[04:55:43.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:45:0)
[04:55:43.425] Timer tick, asking Buildbucket for the build status
[04:55:43.541] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:43.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:46:0) after 1m0s
[04:56:43.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:46:0)
[04:56:43.569] Timer tick, asking Buildbucket for the build status
[04:56:43.822] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:43.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:47:0) after 1m0s
[04:57:43.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:47:0)
[04:57:43.899] Timer tick, asking Buildbucket for the build status
[04:57:44.908] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:44.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:48:0) after 1m0s
[04:58:44.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:48:0)
[04:58:44.938] Timer tick, asking Buildbucket for the build status
[04:58:45.017] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:45.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:49:0) after 1m0s
[04:59:45.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:49:0)
[04:59:45.119] Timer tick, asking Buildbucket for the build status
[04:59:45.584] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:45.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:50:0) after 1m0s
[05:00:45.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:50:0)
[05:00:45.792] Timer tick, asking Buildbucket for the build status
[05:00:46.149] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:46.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:51:0) after 1m0s
[05:01:46.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:51:0)
[05:01:46.187] Timer tick, asking Buildbucket for the build status
[05:01:46.256] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:46.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:52:0) after 1m0s
[05:02:46.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:52:0)
[05:02:46.281] Timer tick, asking Buildbucket for the build status
[05:02:46.976] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:46.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:53:0) after 1m0s
[05:03:47.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:53:0)
[05:03:47.049] Timer tick, asking Buildbucket for the build status
[05:03:47.139] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:47.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:54:0) after 1m0s
[05:04:47.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:54:0)
[05:04:47.265] Timer tick, asking Buildbucket for the build status
[05:04:47.356] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:47.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:55:0) after 1m0s
[05:05:47.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:55:0)
[05:05:47.586] Timer tick, asking Buildbucket for the build status
[05:05:47.901] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:47.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:56:0) after 1m0s
[05:06:47.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:56:0)
[05:06:47.973] Timer tick, asking Buildbucket for the build status
[05:06:48.211] Build 8864410977775451776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:48.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029841039840224784:57:0) after 1m0s
[05:07:13.064] Received PubSub notification, asking Buildbucket for the build status
[05:07:13.555] Build 8864410977775451776: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:07:13.555] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1604639232012398",
"created_by": "project:chromium",
"created_ts": "1604635942633747",
"id": "8864410977775451776",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2ca8b447af5482fc3f323ecaa40197436a4d244a\"}}",
"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\": \"2ca8b447af5482fc3f323ecaa40197436a4d244a\", \"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-8-b9eu\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15489, \"clang_revision\": \"llvmorg-12-init-10666-gc9f69ee7-1\", \"got_angle_revision\": \"d30f0114470888d3d85a92547f3d3b9c387ebb44\", \"got_dawn_revision\": \"2b6b0f45ff0597a847d0da25485dce9e2278ee80\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"2ca8b447af5482fc3f323ecaa40197436a4d244a\", \"got_revision_cp\": \"refs/heads/master@{#824712}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"612ded0654a027d64922448f136d125a9dc17956\", \"got_v8_revision\": \"7c6f3c7091ee7b531bd7609f7021e4a29ced462a\", \"got_v8_revision_cp\": \"refs/heads/8.8.241@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"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\": \"2ca8b447af5482fc3f323ecaa40197436a4d244a\", \"swarm_hashes_refs/heads/master(at){#824712}_without_patch\": {\"absl_hardening_tests\": \"021eb9f2050bd0e26fbeab5c71f548cfcdc30966\", \"accessibility_unittests\": \"f859670db8cc82b51579166bf6eb8505e9133dfb\", \"angle_unittests\": \"c9e08811d38365cb495bebbe838270b353a644dc\", \"app_shell_unittests\": \"6ca9fbe3cb67009a16ec8723532b18f11967cef1\", \"aura_unittests\": \"f696d8df52a8c3693b09eab17fd904906ed5b492\", \"base_unittests\": \"3db3354d083417d850944779e733738c2626957f\", \"base_util_unittests\": \"afa061f5e6b028e08827b6f427b25a4c2c46c752\", \"blink_common_unittests\": \"5e8439e6ce011f49f1148f26fb1dbb43a101a778\", \"blink_fuzzer_unittests\": \"69a6caffc33895d03249a80c0e023006a5a855f3\", \"blink_heap_unittests\": \"1883579da8127fe23ae8164a31f16659c3bcc6c7\", \"blink_platform_unittests\": \"0b6c64dfabc096f9dbc2d1ace7c9300727043635\", \"blink_unittests\": \"b36f99ef61ff9f25a109555f7c6ba4c99a5e57e8\", \"boringssl_crypto_tests\": \"5056205557a2380160de094ec920ef7714c91ff7\", \"boringssl_ssl_tests\": \"e90058c71db486e655aa9cea1b9cabb4d6b8b9f4\", \"browser_switcher_bho_unittests\": \"7fc9c32f3e9334fe952633c59c8f165db2b3e1c6\", \"browser_tests\": \"23b05e107c2df3fe583cb162a0001502d58b024d\", \"capture_unittests\": \"8873a276eec175d4dc0a151b49d0f35d57334b31\", \"cast_unittests\": \"3bd6b7c7addcf911d2734909495f613afe29e39f\", \"cc_unittests\": \"75ab7cf15b63f647afdd5fad1fbc5f7b4437c6b5\", \"chrome_app_unittests\": \"3f5000a4756fc5eca7d07ee35ba9a26ded4cb7d7\", \"chrome_cleaner_unittests\": \"481199c42c831bdb63ae393a312f3d414b17f1a3\", \"chrome_elf_unittests\": \"5f3494201ed835895ca8caff9101e4286fc09622\", \"chromedriver_unittests\": \"cdc22189008f1c3ee87696834666c149947a2dba\", \"components_browsertests\": \"22e96a31751fac054885a005d2f2e91c46c7d0ec\", \"components_unittests\": \"0b0ae3b011d02d16ee78ba55f049baa96ddfdad0\", \"compositor_unittests\": \"d22c49974fe4623ad9f1806b393cd4a51c53d1ae\", \"content_browsertests\": \"279c6d527f2abdb69756a945e0ced39ff94e3305\", \"content_unittests\": \"3ed553886700e50e63057c761d1ef7887f355d16\", \"courgette_unittests\": \"5addf8b2259453e0d76235a7262ac4da7fe56d0d\", \"crashpad_tests\": \"464e5d180c0077589fd179be33407c3f219e7bfc\", \"cronet_tests\": \"f603cf8baa7f5c5a71a436079aa01cf23770b6ac\", \"cronet_unittests\": \"bed7060292328b2315a087c7a3701f501281aa34\", \"crypto_unittests\": \"cf08f9b215137e7358cfedca640f088751a89b1c\", \"delayloads_unittests\": \"218c5d8c24bbb963b7257ef2b28c0f38d6904ea1\", \"device_unittests\": \"676c4c31632f69ad4c947db7f8b6fdac2c58daa0\", \"display_unittests\": \"8890c5ab21d97b5dc22de93a8a8f2d4c65e1a394\", \"elevation_service_unittests\": \"11f43c848dc4e647538ca25afae912a9d6301d69\", \"events_unittests\": \"2d9cd43625505414b1e531653e105909d6a778d1\", \"extensions_browsertests\": \"bb915e4501fa85bb372806d70e62f1f4283b602d\", \"extensions_unittests\": \"e34f9ab27fd81ade0474aecf28a1055a9df475b8\", \"filesystem_service_unittests\": \"e1b9f99c7e7a2aea6c4c57d6343f5edd357665e0\", \"gcm_unit_tests\": \"4d1123221691df8d6b8063b00dc517076021752f\", \"gcp_unittests\": \"65f8a8df7b42fcfcb854b499eb09c485ced9656e\", \"gfx_unittests\": \"89799f6ccbfd873994067180f82c9c1ed0a27c04\", \"gin_unittests\": \"de150a832a6d61fe0520dc90a4a58d47535321cc\", \"google_apis_unittests\": \"eeefb58288c494a41e65390d13cff4ebc7544d3f\", \"gpu_unittests\": \"47f25a1ee84e97ec45e0c9a1c1f29a8a2ff7a7f7\", \"gwp_asan_unittests\": \"ddddc663f0defbcf5a7516890f47b42dd7e370ed\", \"headless_browsertests\": \"4f2cb1f0b4959361bfef1bccb85fa75a8408ac71\", \"headless_unittests\": \"578b05c70c217360c1c921a1d35ff99ed495b8fd\", \"install_static_unittests\": \"90078e9c6ce81087916dda0b00eefa444638f667\", \"installer_util_unittests\": \"2415b0ff7ebb07a17f84e835d34bc5c531f4ef4f\", \"interactive_ui_tests\": \"82a6d1530cf3c8481ec850787343cee212ef2b7e\", \"ipc_tests\": \"f6cce61119f6a66c36a10f478f3d3c3edbe01c36\", \"jingle_unittests\": \"8baec5cbc041fb689f2880d083e92a06adbf0ef1\", \"latency_unittests\": \"3def60e1d53e2534b48617363b0cc745da9e8b8d\", \"libjingle_xmpp_unittests\": \"c5ee23768e8428dadfcb16b82ac0d963779ce634\", \"liburlpattern_unittests\": \"262de4a4b73a2b0fe45d2143bb3decc3347d3d38\", \"media_blink_unittests\": \"1fd3f7ca275a1245ea6cfd7d5af3bf6c60636efe\", \"media_unittests\": \"f7938fc399f6e74e477a7a5d16cbe4ad12176eee\", \"message_center_unittests\": \"a89d3df7729bc74ab4fcc75c1e95b35b0135c0fc\", \"midi_unittests\": \"2475d9830dbe9ee8013aebf7462b253666703abb\", \"mojo_core_unittests\": \"55f7376627bad252e08b74d974c65f60f5962f75\", \"mojo_unittests\": \"23fa935af97a3f87d2d5923706051d9d30353892\", \"nacl_loader_unittests\": \"75b003ba9275ac3ae601ea2f214d78fc77d916cc\", \"native_theme_unittests\": \"8f847b80db44c243ce2ef596da05d2c8c493e881\", \"net_unittests\": \"5638a81d4466fdc7f1aa741bd795f7968547179e\", \"notification_helper_unittests\": \"88b09e36d0f27ad5e566e3dd4fcd433c9779c673\", \"pdf_unittests\": \"3d3c25abb9b5d96980af3ef633d4c5feb57648e2\", \"perfetto_unittests\": \"13e1ec8034fe37092e9aecbb7a8e67ea92c21806\", \"ppapi_unittests\": \"f3c1904bcc2ed13cbeebf916ecc1dfc3f2ebeca0\", \"printing_unittests\": \"97e03341fd814376c19eb33c7511a67a9b0467c1\", \"remoting_unittests\": \"cb81f623e41a083fba4df620c54566a8c9ec14a7\", \"sbox_integration_tests\": \"5f733291a5b6d0df8968c6e3ca6021f820277a45\", \"sbox_unittests\": \"cefdd0dc989ac5347e37cbddb407cf6c31584ebc\", \"sbox_validation_tests\": \"b74a233d52ca7a75ea17080e9d5c8c1abeee52af\", \"service_manager_unittests\": \"3e58fec5e0d18e3eebc884c4cfe9163bec1262ae\", \"services_unittests\": \"7a2fa6a6346b447ee7372a518eff2a4ee938eb27\", \"setup_unittests\": \"6e30c47d117369f912a8984ad8da8ff5f5cfc48b\", \"shell_dialogs_unittests\": \"f04b9a9b0c68ad6865b400e9b9c14b940dd32c22\", \"skia_unittests\": \"0108145b8a552a258aa31534cd836c303c19ac89\", \"snapshot_unittests\": \"f1dc4581ff6f61625d7cf73e51587914b4d4df0a\", \"sql_unittests\": \"3a7d3ca2f2569e94c883bd0043ee21a798ad6834\", \"storage_unittests\": \"b62f98274b4b55726ab1385ec37ec7ff8b3ac0fd\", \"sync_integration_tests\": \"372733989b8b50f8f8137f83b9eb86e20f301fc0\", \"traffic_annotation_auditor_unittests\": \"e6ccfd3b344f8ee42363d670ea4111585e286b03\", \"ui_base_unittests\": \"58fb6b7cc9119c2de5f36c5f6b0b64b8f579751e\", \"ui_touch_selection_unittests\": \"18b27cbbb0e0cb862aa8deff2cd2b09f40bfcdbd\", \"unit_tests\": \"b9ea5b71bf31a1fddcc8b32c95dedb673c87ef8e\", \"updater_tests\": \"5655463c42c5b996e80333ebe3ef9723185b05b2\", \"url_unittests\": \"4e075d6ef6100a1a82026b8ffd24ba06170250d3\", \"views_unittests\": \"e9573d432691ade2131ac902794e06f116066704\", \"viz_unittests\": \"429105718430afcdb85d74612747905ce42b42a0\", \"vr_common_unittests\": \"78c2747a39d0a611f1340507a76143b5571ce23b\", \"vr_pixeltests\": \"aec56890958bafa1ee4814c44b7ecb4f2a1d779e\", \"weblayer_browsertests\": \"6f65c68c1b7f5d30ac33fb75aa79563a97a9e0d5\", \"weblayer_unittests\": \"e93065128dad4c9823b262fd85b166fb5d4e2e56\", \"wm_unittests\": \"1130fb7218add4f471880241ee2979fd6c182ac4\", \"wtf_unittests\": \"c727e56342acca35e53fa10027b73ffaf03f919d\", \"zlib_unittests\": \"7d55904f60c2b5f678a8df1021c4f036a2f4e245\", \"zucchini_unittests\": \"3b108dbdbc5ba51129b4a90f88d7659f9646fb87\"}}, \"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-8-b9eu\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604635946901359",
"status": "COMPLETED",
"status_changed_ts": "1604639232530456",
"tags": [
"build_address:luci.chromium.ci/win-asan/15489",
"builder:win-asan",
"buildset:commit/git/2ca8b447af5482fc3f323ecaa40197436a4d244a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2ca8b447af5482fc3f323ecaa40197436a4d244a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029841039840224784",
"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/8864410977775451776/+/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:4fb492eeb24c3210",
"user_agent:luci-scheduler"
],
"updated_ts": "1604639232540445",
"url": "https://ci.chromium.org/b/8864410977775451776",
"utcnow_ts": "1604639233507290"
}
}
[05:07:13.555] Invocation finished in 54m52.274132287s with status SUCCEEDED