[04:12:43.383] New invocation is queued and will start shortly
[04:12:44.521] Starting the invocation (attempt 1)
[04:12:44.577] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:12:44.577] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:12:44.578] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026760719827784048",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"09d2698e17a3e718a77e5ad8fff969f53f9ed37b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026760719827784048",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/09d2698e17a3e718a77e5ad8fff969f53f9ed37b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09d2698e17a3e718a77e5ad8fff969f53f9ed37b",
"gitiles_ref:refs/heads/master"
]
}
[04:12:45.536] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607573564692342",
"id": "8861330657787981040",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"09d2698e17a3e718a77e5ad8fff969f53f9ed37b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607573565225020",
"tags": [
"build_address:luci.chromium.ci/win-asan/16131",
"builder:win-asan",
"buildset:commit/git/09d2698e17a3e718a77e5ad8fff969f53f9ed37b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09d2698e17a3e718a77e5ad8fff969f53f9ed37b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026760719827784048",
"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/8861330657787981040/+/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": "1607573565225183",
"url": "https://ci.chromium.org/b/8861330657787981040",
"utcnow_ts": "1607573565515881"
}
}
[04:12:45.536] Task URL: https://ci.chromium.org/b/8861330657787981040
[04:12:45.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:2:0) after 1m0s
[04:12:49.097] Received PubSub notification, asking Buildbucket for the build status
[04:12:49.301] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:45.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:2:0)
[04:13:45.591] Timer tick, asking Buildbucket for the build status
[04:13:45.768] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:45.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:4:0) after 1m0s
[04:14:45.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:4:0)
[04:14:45.798] Timer tick, asking Buildbucket for the build status
[04:14:46.085] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:46.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:5:0) after 1m0s
[04:15:46.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:5:0)
[04:15:46.108] Timer tick, asking Buildbucket for the build status
[04:15:46.213] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:46.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:6:0) after 1m0s
[04:16:46.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:6:0)
[04:16:46.235] Timer tick, asking Buildbucket for the build status
[04:16:46.357] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:46.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:7:0) after 1m0s
[04:17:46.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:7:0)
[04:17:46.427] Timer tick, asking Buildbucket for the build status
[04:17:46.553] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:46.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:8:0) after 1m0s
[04:18:46.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:8:0)
[04:18:46.564] Timer tick, asking Buildbucket for the build status
[04:18:46.653] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:46.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:9:0) after 1m0s
[04:19:46.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:9:0)
[04:19:46.680] Timer tick, asking Buildbucket for the build status
[04:19:46.907] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:46.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:10:0) after 1m0s
[04:20:46.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:10:0)
[04:20:46.946] Timer tick, asking Buildbucket for the build status
[04:20:47.013] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:47.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:11:0) after 1m0s
[04:21:46.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:11:0)
[04:21:46.997] Timer tick, asking Buildbucket for the build status
[04:21:47.173] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:47.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:12:0) after 1m0s
[04:22:47.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:12:0)
[04:22:47.203] Timer tick, asking Buildbucket for the build status
[04:22:47.305] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:47.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:13:0) after 1m0s
[04:23:47.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:13:0)
[04:23:47.357] Timer tick, asking Buildbucket for the build status
[04:23:47.415] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:47.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:14:0) after 1m0s
[04:24:47.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:14:0)
[04:24:47.640] Timer tick, asking Buildbucket for the build status
[04:24:47.702] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:47.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:15:0) after 1m0s
[04:25:47.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:15:0)
[04:25:47.711] Timer tick, asking Buildbucket for the build status
[04:25:47.811] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:47.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:16:0) after 1m0s
[04:26:47.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:16:0)
[04:26:47.823] Timer tick, asking Buildbucket for the build status
[04:26:48.002] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:48.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:17:0) after 1m0s
[04:27:48.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:17:0)
[04:27:48.078] Timer tick, asking Buildbucket for the build status
[04:27:48.564] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:48.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:18:0) after 1m0s
[04:28:48.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:18:0)
[04:28:48.650] Timer tick, asking Buildbucket for the build status
[04:28:48.925] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:48.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:19:0) after 1m0s
[04:29:48.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:19:0)
[04:29:48.952] Timer tick, asking Buildbucket for the build status
[04:29:49.024] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:49.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:20:0) after 1m0s
[04:30:49.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:20:0)
[04:30:49.123] Timer tick, asking Buildbucket for the build status
[04:30:49.246] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:49.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:21:0) after 1m0s
[04:31:49.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:21:0)
[04:31:49.336] Timer tick, asking Buildbucket for the build status
[04:31:49.441] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:49.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:22:0) after 1m0s
[04:32:49.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:22:0)
[04:32:49.473] Timer tick, asking Buildbucket for the build status
[04:32:49.632] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:49.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:23:0) after 1m0s
[04:33:49.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:23:0)
[04:33:49.662] Timer tick, asking Buildbucket for the build status
[04:33:49.799] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:49.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:24:0) after 1m0s
[04:34:49.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:24:0)
[04:34:49.974] Timer tick, asking Buildbucket for the build status
[04:34:50.141] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:50.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:25:0) after 1m0s
[04:35:50.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:25:0)
[04:35:50.162] Timer tick, asking Buildbucket for the build status
[04:35:50.269] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:50.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:26:0) after 1m0s
[04:36:50.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:26:0)
[04:36:50.293] Timer tick, asking Buildbucket for the build status
[04:36:50.834] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:50.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:27:0) after 1m0s
[04:37:50.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:27:0)
[04:37:50.902] Timer tick, asking Buildbucket for the build status
[04:37:51.282] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:51.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:28:0) after 1m0s
[04:38:51.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:28:0)
[04:38:51.321] Timer tick, asking Buildbucket for the build status
[04:38:51.445] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:51.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:29:0) after 1m0s
[04:39:51.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:29:0)
[04:39:51.507] Timer tick, asking Buildbucket for the build status
[04:39:51.679] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:51.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:30:0) after 1m0s
[04:40:51.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:30:0)
[04:40:51.737] Timer tick, asking Buildbucket for the build status
[04:40:52.012] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:52.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:31:0) after 1m0s
[04:41:52.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:31:0)
[04:41:52.160] Timer tick, asking Buildbucket for the build status
[04:41:52.276] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:52.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:32:0) after 1m0s
[04:42:52.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:32:0)
[04:42:52.381] Timer tick, asking Buildbucket for the build status
[04:42:52.488] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:52.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:33:0) after 1m0s
[04:43:52.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:33:0)
[04:43:52.545] Timer tick, asking Buildbucket for the build status
[04:43:52.742] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:52.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:34:0) after 1m0s
[04:44:52.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:34:0)
[04:44:52.830] Timer tick, asking Buildbucket for the build status
[04:44:52.947] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:52.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:35:0) after 1m0s
[04:45:53.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:35:0)
[04:45:53.087] Timer tick, asking Buildbucket for the build status
[04:45:53.163] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:53.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:36:0) after 1m0s
[04:46:53.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:36:0)
[04:46:53.170] Timer tick, asking Buildbucket for the build status
[04:46:53.238] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:53.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:37:0) after 1m0s
[04:47:53.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:37:0)
[04:47:53.328] Timer tick, asking Buildbucket for the build status
[04:47:53.625] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:53.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:38:0) after 1m0s
[04:48:53.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:38:0)
[04:48:53.721] Timer tick, asking Buildbucket for the build status
[04:48:53.797] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:53.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:39:0) after 1m0s
[04:49:53.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:39:0)
[04:49:53.822] Timer tick, asking Buildbucket for the build status
[04:49:54.105] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:54.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:40:0) after 1m0s
[04:50:54.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:40:0)
[04:50:54.158] Timer tick, asking Buildbucket for the build status
[04:50:54.281] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:54.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:41:0) after 1m0s
[04:51:54.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:41:0)
[04:51:54.414] Timer tick, asking Buildbucket for the build status
[04:51:54.497] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:54.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:42:0) after 1m0s
[04:52:54.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:42:0)
[04:52:54.597] Timer tick, asking Buildbucket for the build status
[04:52:55.013] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:55.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:43:0) after 1m0s
[04:53:55.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:43:0)
[04:53:55.068] Timer tick, asking Buildbucket for the build status
[04:53:55.164] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:55.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:44:0) after 1m0s
[04:54:55.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:44:0)
[04:54:55.186] Timer tick, asking Buildbucket for the build status
[04:54:55.634] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:55.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:45:0) after 1m0s
[04:55:55.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:45:0)
[04:55:55.630] Timer tick, asking Buildbucket for the build status
[04:55:55.691] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:55.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:46:0) after 1m0s
[04:56:55.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:46:0)
[04:56:55.765] Timer tick, asking Buildbucket for the build status
[04:56:56.437] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:56.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:47:0) after 1m0s
[04:57:56.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:47:0)
[04:57:56.469] Timer tick, asking Buildbucket for the build status
[04:57:56.584] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:56.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:48:0) after 1m0s
[04:58:56.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:48:0)
[04:58:56.616] Timer tick, asking Buildbucket for the build status
[04:58:56.693] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:56.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:49:0) after 1m0s
[04:59:56.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:49:0)
[04:59:56.717] Timer tick, asking Buildbucket for the build status
[04:59:57.179] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:57.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:50:0) after 1m0s
[05:00:57.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:50:0)
[05:00:57.216] Timer tick, asking Buildbucket for the build status
[05:00:57.322] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:57.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:51:0) after 1m0s
[05:01:57.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:51:0)
[05:01:57.378] Timer tick, asking Buildbucket for the build status
[05:01:57.583] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:57.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:52:0) after 1m0s
[05:02:57.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:52:0)
[05:02:57.609] Timer tick, asking Buildbucket for the build status
[05:02:57.744] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:57.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:53:0) after 1m0s
[05:03:57.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:53:0)
[05:03:57.744] Timer tick, asking Buildbucket for the build status
[05:03:57.830] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:57.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:54:0) after 1m0s
[05:04:57.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:54:0)
[05:04:57.847] Timer tick, asking Buildbucket for the build status
[05:04:57.979] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:57.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:55:0) after 1m0s
[05:05:58.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:55:0)
[05:05:58.039] Timer tick, asking Buildbucket for the build status
[05:05:58.235] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:58.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:56:0) after 1m0s
[05:06:58.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:56:0)
[05:06:58.238] Timer tick, asking Buildbucket for the build status
[05:06:58.327] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:58.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:57:0) after 1m0s
[05:07:58.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:57:0)
[05:07:58.344] Timer tick, asking Buildbucket for the build status
[05:07:58.729] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:58.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:58:0) after 1m0s
[05:08:58.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:58:0)
[05:08:58.751] Timer tick, asking Buildbucket for the build status
[05:08:58.825] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:58.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:59:0) after 1m0s
[05:09:58.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:59:0)
[05:09:58.857] Timer tick, asking Buildbucket for the build status
[05:09:59.297] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:59.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:60:0) after 1m0s
[05:10:59.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:60:0)
[05:10:59.297] Timer tick, asking Buildbucket for the build status
[05:10:59.535] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:59.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:61:0) after 1m0s
[05:11:59.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:61:0)
[05:11:59.521] Timer tick, asking Buildbucket for the build status
[05:11:59.585] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:59.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:62:0) after 1m0s
[05:12:59.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:62:0)
[05:12:59.641] Timer tick, asking Buildbucket for the build status
[05:13:00.832] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:00.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:63:0) after 1m0s
[05:14:01.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:63:0)
[05:14:01.150] Timer tick, asking Buildbucket for the build status
[05:14:01.442] Build 8861330657787981040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:01.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026760719827784048:64:0) after 1m0s
[05:14:02.585] Received PubSub notification, asking Buildbucket for the build status
[05:14:03.088] Build 8861330657787981040: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:14:03.088] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607577239552997",
"created_by": "project:chromium",
"created_ts": "1607573564692342",
"id": "8861330657787981040",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"09d2698e17a3e718a77e5ad8fff969f53f9ed37b\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"09d2698e17a3e718a77e5ad8fff969f53f9ed37b\", \"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-6-3vmi\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16131, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"067a12f059511ead6e91ab9e91af1d180ad9c144\", \"got_dawn_revision\": \"29fd62989338a0d5812bc654a3afee6dc684bfc4\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"09d2698e17a3e718a77e5ad8fff969f53f9ed37b\", \"got_revision_cp\": \"refs/heads/master@{#835543}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"83498ed6d22854cd2433e2411e67298e4d2cc0cd\", \"got_v8_revision\": \"7e546be6b2f61eb429d51abb78e973f7f623f62f\", \"got_v8_revision_cp\": \"refs/heads/8.9.126@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"09d2698e17a3e718a77e5ad8fff969f53f9ed37b\", \"swarm_hashes_refs/heads/master(at){#835543}_without_patch\": {\"absl_hardening_tests\": \"7e6087d7b3ecac11d5ac3bf5563540580213e040\", \"accessibility_unittests\": \"fb8a93afb83925d4c1a52fe2a8d3eb3b37a54d62\", \"angle_unittests\": \"23ecd28ae7e39f0f150d33bba50e89f660a52163\", \"app_shell_unittests\": \"994df5c58bb0dddf7ec0e4784a3ba267a58cb956\", \"aura_unittests\": \"7c13e8012a47ef14e05e84f9091ade68b8419229\", \"base_unittests\": \"8f3f57041324c6e6b5bf170c17d91af4ce1d334b\", \"base_util_unittests\": \"5b55db84b3ac496b032fa35b6744031ed690fefb\", \"blink_common_unittests\": \"7c0644b3acaf3ce1a296059dceffd51829820c3a\", \"blink_fuzzer_unittests\": \"9666150a7bc0296935ce33dcffd0fa21bf7845c6\", \"blink_heap_unittests\": \"0374bd6c13aa94634ad6d293b5e8b3916b13b0fb\", \"blink_platform_unittests\": \"d95f005be68022991575f41da1a93a2ad9e7f04f\", \"blink_unittests\": \"83580a61d34803a29e8e81e34c6f2de65601b99b\", \"boringssl_crypto_tests\": \"c6c0d07c5b7e719ed73f8143be8c98068d225f0f\", \"boringssl_ssl_tests\": \"eb567cb0827e6bda6bc2e713b44c7fb92840259a\", \"browser_switcher_bho_unittests\": \"bff5f980f781357f6b61e16d6d96e20519844f74\", \"browser_tests\": \"74e66a102038b38a1439fb358668b2e269b048f6\", \"capture_unittests\": \"530a8bcacf224db4ce25dcadcb627c4bce62c51a\", \"cast_unittests\": \"24e545a8be2a38e18ea9750d9a57298ff9715029\", \"cc_unittests\": \"40dbaceb19f2c3da6b0b93062fd8afd96c1b8898\", \"chrome_app_unittests\": \"2c036d83c468531d00420044ce02bba57dcb4168\", \"chrome_cleaner_unittests\": \"a091dfbb68fe934363d69d39a02c2537f5ed84ee\", \"chrome_elf_unittests\": \"4b8433aa8357768fcfc07403bd685104fd1f5d6a\", \"chromedriver_unittests\": \"23416cd503bc8d8f222b77b3899fc62b4fd27868\", \"components_browsertests\": \"0fb3fe68b0725b7a4e996d5849a3400f7e03246f\", \"components_unittests\": \"fd09726ed0dc045489f09e7acc65c51b689970f8\", \"compositor_unittests\": \"a885c224946c811fde20539092a5cb000eb45e99\", \"content_browsertests\": \"2aec309a8688d01874ef6d6dfb8afc001777ae06\", \"content_unittests\": \"ff91c0ad1c6ca73a49a235c477f0584787d24878\", \"courgette_unittests\": \"3c9c06a4d448061ce4ea1e4ceafd708aec9d5167\", \"crashpad_tests\": \"b738124fca2d5e62b4b844cb3738ada5bfd6480d\", \"cronet_tests\": \"0dfdf8924d8d9df4ba4f4be19968463c7ffcb23f\", \"cronet_unittests\": \"aa5cb68b74842768d858dad6ce4a6a1b651e6fdd\", \"crypto_unittests\": \"1c19cdf8e8e961cd595896cae25a08838075efa6\", \"delayloads_unittests\": \"773b76f36c67c83f5d1497501254a92e50304856\", \"device_unittests\": \"c6d0dc8f912e1f014c27a0ab0e4ac5238d0968b4\", \"display_unittests\": \"bd01aeebf14212967b4ee0da790d04ff48c253f9\", \"elevation_service_unittests\": \"605cd3ab860a7c961d4772da35e03984921e852e\", \"events_unittests\": \"41a5d96ac8223dd787804136f963e178ba1778ff\", \"extensions_browsertests\": \"0268f760c98f43cbc3583bae75ac8447b529619c\", \"extensions_unittests\": \"6d6685b06726df3b8cbaa7a143db2a41544d725c\", \"filesystem_service_unittests\": \"4ac4aca493e229e7bed22d1b46007944f0a19db9\", \"gcm_unit_tests\": \"d49e0b135785268f8a3104912c6328e247a826f8\", \"gcp_unittests\": \"a69839f00fddce4a13a2ff8d855dd3aff228273c\", \"gfx_unittests\": \"40353c1cfc9d6ce4a8ae68e278e1c5b6bee4b2b4\", \"gin_unittests\": \"8872b46ddb3e259510e8e0e7b51c95472fcb8a96\", \"google_apis_unittests\": \"427a65dc56e5c66075fd516cc997f09314e3131d\", \"gpu_unittests\": \"8c3a6c9567da29c179fc1835bab019cdc2ff1b1a\", \"gwp_asan_unittests\": \"8adee696cb39095eb65af23cee2d79f0c53f4dd2\", \"headless_browsertests\": \"42291758f2550cf9719f34e373ce035612b2774b\", \"headless_unittests\": \"594d850cde70a1a3e7ea9e7f90a82aba29d973eb\", \"install_static_unittests\": \"88b03a21ee20bdd0ed74c1948f7fdd600a3c386d\", \"installer_util_unittests\": \"ae87ab2b09d2b9eef2696a535a02544ed8eba987\", \"interactive_ui_tests\": \"00ab25ba733d0f35a0276c89be348a00692f61c5\", \"ipc_tests\": \"6aa1165bb4d1935bc164cf0f5ae44aa9c0d1692a\", \"jingle_unittests\": \"6147aa2ed54f505c5249d61807c113a9b98e723f\", \"latency_unittests\": \"c2d788e4f19ad4bd602b4ad8e7df34e47f439202\", \"libjingle_xmpp_unittests\": \"301eea2bdad4c3b63b073ce91eb5abe59d6cae28\", \"liburlpattern_unittests\": \"5a41e4562eb71f5c3932fd940027039400ab13be\", \"media_blink_unittests\": \"6b3eea79fd618b887d37c717243489d170fb8970\", \"media_unittests\": \"72f243aa88ef28275f4788fd33b46fafddc4d73e\", \"message_center_unittests\": \"87040b47908df95171cf6b3eb091592c84511da0\", \"midi_unittests\": \"3c2f293ac251c296c65962cb0e4687cb05db5fb8\", \"mojo_core_unittests\": \"30aed90010d6bf654611cee080f102f428a593be\", \"mojo_unittests\": \"f955e4a4d687ba5f0809991e9dd7aa7b8f8c68e2\", \"nacl_loader_unittests\": \"27feca89a8d746c598e1486cfcc37417540d546f\", \"native_theme_unittests\": \"b0aa90b4728c0af4cd7eaacd39c174d16b03eb7d\", \"net_unittests\": \"793f69a3057112a3e3d7c1ab23430d39abbdac6e\", \"notification_helper_unittests\": \"8778fa4a12e127b644987f02a3c5643be9dbbc83\", \"pdf_unittests\": \"454490fd782544d048fac803c3290befe34e340b\", \"perfetto_unittests\": \"685ccee9f1f2264fea12beb5e199d1890b7b2929\", \"ppapi_unittests\": \"81bcfbe8cba4a7fadee14926467bb2ab799b9218\", \"printing_unittests\": \"5a3bcbeedb339522b37e383d1fd002b9f6f74425\", \"remoting_unittests\": \"0bba56679699d0201e69c3e11df1775bb2b43102\", \"sbox_integration_tests\": \"655cbd59c4ef65e8a91715383fc9948d161aeff0\", \"sbox_unittests\": \"f19259f1840feac7049638cac618f0b72f1f7b19\", \"sbox_validation_tests\": \"7d538345bde4a25acbb4d177faa29182ddbde2c4\", \"service_manager_unittests\": \"7d9397d49e0a7bfc1301c8edca124970a9e86477\", \"services_unittests\": \"098211a826479fcce2af1e909ae74e02a07fbe85\", \"setup_unittests\": \"9c09988cf5abebe22dc90a3eef4a8dbf9a821ccb\", \"shell_dialogs_unittests\": \"96aa875ffdf544538314a54416c12d8f81d76ebe\", \"skia_unittests\": \"cc5cd7931693a8ed081200dde4ed4dee25372850\", \"snapshot_unittests\": \"42f28c55545bb2fb35e990c6eb4ba12f07042e6e\", \"sql_unittests\": \"e10a4081b670315dab420f9b38dadb0fb198eb78\", \"storage_unittests\": \"7668de6f513a58bcddeaf91c3d8343f62fe07a40\", \"sync_integration_tests\": \"6e4e9b2728e64b0fde64ba07d9d6a3b1b86c9988\", \"traffic_annotation_auditor_unittests\": \"6c48838ce11827a4b564cfef039e3df7cfdfd699\", \"ui_base_unittests\": \"611e4e12b1a7419821b4ab323fcb98c3d41a6160\", \"ui_touch_selection_unittests\": \"65123bdb998ef12c091ac521afc55fe99a05cddc\", \"unit_tests\": \"a275881f01ac561df1f80e5cb442515cc214873a\", \"updater_tests\": \"f9c090bfbf391b1a13e69e939525b67097079648\", \"url_unittests\": \"3565ce0f0427ca01ea9fe459440a26c38c87dc52\", \"views_unittests\": \"b88cbc725f29b92745a75cdd12b23ebd6370163b\", \"viz_unittests\": \"a2f5a9ede4ea541fc290cffab6f14a956527f04b\", \"vr_common_unittests\": \"05aaecedcde16ab8c575f89bb2e7c3b913c07742\", \"vr_pixeltests\": \"b8b178143d3460bf007cda24a5fb7617b015b271\", \"weblayer_browsertests\": \"6cd66f909e356686afffcff0416216a66a92184c\", \"weblayer_unittests\": \"a12e8e324dc3a9fff657d556961604f5d21d22a3\", \"wm_unittests\": \"f173167149562ffbd928cd584a7c8200d4d89cb5\", \"wtf_unittests\": \"bba918a3a469b6bb76db61e8daf03d72862e65c8\", \"zlib_unittests\": \"75bff7d8a9f394992356f0c731912768b3c8d9ad\", \"zucchini_unittests\": \"7a1ca22065cb34dc97200f554406e812677f831b\"}}, \"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-6-3vmi\"], \"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-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607573568080123",
"status": "COMPLETED",
"status_changed_ts": "1607577239984344",
"tags": [
"build_address:luci.chromium.ci/win-asan/16131",
"builder:win-asan",
"buildset:commit/git/09d2698e17a3e718a77e5ad8fff969f53f9ed37b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09d2698e17a3e718a77e5ad8fff969f53f9ed37b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026760719827784048",
"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/8861330657787981040/+/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:5063ab79ad2e9410",
"user_agent:luci-scheduler"
],
"updated_ts": "1607577240005699",
"url": "https://ci.chromium.org/b/8861330657787981040",
"utcnow_ts": "1607577243064368"
}
}
[05:14:03.089] Invocation finished in 1h1m19.7251314s with status SUCCEEDED