[04:51:02.754] New invocation is queued and will start shortly
[04:51:03.851] Starting the invocation (attempt 1)
[04:51:03.889] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:51:03.889] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:51:03.889] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018332790881695136",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a21946a5efba0a0d52771cdc11b733363b4b30fe\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018332790881695136",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a21946a5efba0a0d52771cdc11b733363b4b30fe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a21946a5efba0a0d52771cdc11b733363b4b30fe",
"gitiles_ref:refs/heads/master"
]
}
[04:51:04.651] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615611063973532",
"id": "8852902728942058064",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a21946a5efba0a0d52771cdc11b733363b4b30fe\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615611064419126",
"tags": [
"build_address:luci.chromium.ci/win-asan/17783",
"builder:win-asan",
"buildset:commit/git/a21946a5efba0a0d52771cdc11b733363b4b30fe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a21946a5efba0a0d52771cdc11b733363b4b30fe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018332790881695136",
"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/8852902728942058064/+/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": "1615611064419231",
"url": "https://ci.chromium.org/b/8852902728942058064",
"utcnow_ts": "1615611064645024"
}
}
[04:51:04.651] Task URL: https://ci.chromium.org/b/8852902728942058064
[04:51:04.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:2:0) after 1m0s
[04:51:11.521] Received PubSub notification, asking Buildbucket for the build status
[04:51:11.596] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:04.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:2:0)
[04:52:04.675] Timer tick, asking Buildbucket for the build status
[04:52:04.737] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:04.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:4:0) after 1m0s
[04:53:04.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:4:0)
[04:53:04.880] Timer tick, asking Buildbucket for the build status
[04:53:05.306] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:05.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:5:0) after 1m0s
[04:54:05.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:5:0)
[04:54:05.475] Timer tick, asking Buildbucket for the build status
[04:54:05.562] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:05.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:6:0) after 1m0s
[04:55:05.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:6:0)
[04:55:05.689] Timer tick, asking Buildbucket for the build status
[04:55:06.007] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:06.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:7:0) after 1m0s
[04:56:06.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:7:0)
[04:56:06.085] Timer tick, asking Buildbucket for the build status
[04:56:06.185] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:06.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:8:0) after 1m0s
[04:57:06.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:8:0)
[04:57:06.373] Timer tick, asking Buildbucket for the build status
[04:57:06.633] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:06.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:9:0) after 1m0s
[04:58:06.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:9:0)
[04:58:06.696] Timer tick, asking Buildbucket for the build status
[04:58:06.772] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:06.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:10:0) after 1m0s
[04:59:06.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:10:0)
[04:59:06.795] Timer tick, asking Buildbucket for the build status
[04:59:07.010] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:07.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:11:0) after 1m0s
[05:00:07.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:11:0)
[05:00:07.010] Timer tick, asking Buildbucket for the build status
[05:00:07.250] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:07.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:12:0) after 1m0s
[05:01:09.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:12:0)
[05:01:09.798] Timer tick, asking Buildbucket for the build status
[05:01:09.883] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:09.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:13:0) after 1m0s
[05:02:09.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:13:0)
[05:02:09.911] Timer tick, asking Buildbucket for the build status
[05:02:09.995] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:09.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:14:0) after 1m0s
[05:03:10.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:14:0)
[05:03:10.055] Timer tick, asking Buildbucket for the build status
[05:03:10.355] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:10.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:15:0) after 1m0s
[05:04:10.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:15:0)
[05:04:10.375] Timer tick, asking Buildbucket for the build status
[05:04:10.513] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:10.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:16:0) after 1m0s
[05:05:10.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:16:0)
[05:05:10.523] Timer tick, asking Buildbucket for the build status
[05:05:10.615] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:10.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:17:0) after 1m0s
[05:06:10.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:17:0)
[05:06:10.658] Timer tick, asking Buildbucket for the build status
[05:06:10.775] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:10.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:18:0) after 1m0s
[05:07:10.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:18:0)
[05:07:10.876] Timer tick, asking Buildbucket for the build status
[05:07:11.050] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:11.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:19:0) after 1m0s
[05:08:11.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:19:0)
[05:08:11.066] Timer tick, asking Buildbucket for the build status
[05:08:11.142] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:11.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:20:0) after 1m0s
[05:09:11.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:20:0)
[05:09:11.200] Timer tick, asking Buildbucket for the build status
[05:09:11.334] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:11.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:21:0) after 1m0s
[05:10:11.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:21:0)
[05:10:11.406] Timer tick, asking Buildbucket for the build status
[05:10:11.541] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:11.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:22:0) after 1m0s
[05:11:11.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:22:0)
[05:11:11.620] Timer tick, asking Buildbucket for the build status
[05:11:11.922] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:11.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:23:0) after 1m0s
[05:12:11.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:23:0)
[05:12:11.960] Timer tick, asking Buildbucket for the build status
[05:12:12.056] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:12.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:24:0) after 1m0s
[05:13:12.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:24:0)
[05:13:12.072] Timer tick, asking Buildbucket for the build status
[05:13:15.256] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:15.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:25:0) after 1m0s
[05:14:15.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:25:0)
[05:14:15.340] Timer tick, asking Buildbucket for the build status
[05:14:15.480] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:15.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:26:0) after 1m0s
[05:15:15.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:26:0)
[05:15:15.534] Timer tick, asking Buildbucket for the build status
[05:15:15.615] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:15.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:27:0) after 1m0s
[05:16:15.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:27:0)
[05:16:15.751] Timer tick, asking Buildbucket for the build status
[05:16:15.846] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:15.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:28:0) after 1m0s
[05:17:15.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:28:0)
[05:17:15.877] Timer tick, asking Buildbucket for the build status
[05:17:16.152] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:16.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:29:0) after 1m0s
[05:18:16.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:29:0)
[05:18:16.239] Timer tick, asking Buildbucket for the build status
[05:18:16.317] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:16.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:30:0) after 1m0s
[05:19:16.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:30:0)
[05:19:16.357] Timer tick, asking Buildbucket for the build status
[05:19:16.441] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:16.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:31:0) after 1m0s
[05:20:16.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:31:0)
[05:20:16.479] Timer tick, asking Buildbucket for the build status
[05:20:16.571] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:16.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:32:0) after 1m0s
[05:21:16.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:32:0)
[05:21:16.580] Timer tick, asking Buildbucket for the build status
[05:21:16.689] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:16.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:33:0) after 1m0s
[05:22:16.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:33:0)
[05:22:16.707] Timer tick, asking Buildbucket for the build status
[05:22:16.820] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:16.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:34:0) after 1m0s
[05:23:16.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:34:0)
[05:23:16.860] Timer tick, asking Buildbucket for the build status
[05:23:17.075] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:17.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:35:0) after 1m0s
[05:24:17.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:35:0)
[05:24:17.191] Timer tick, asking Buildbucket for the build status
[05:24:17.307] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:17.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:36:0) after 1m0s
[05:25:17.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:36:0)
[05:25:17.303] Timer tick, asking Buildbucket for the build status
[05:25:17.378] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:17.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:37:0) after 1m0s
[05:26:17.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:37:0)
[05:26:17.571] Timer tick, asking Buildbucket for the build status
[05:26:17.693] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:17.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:38:0) after 1m0s
[05:27:17.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:38:0)
[05:27:17.768] Timer tick, asking Buildbucket for the build status
[05:27:17.846] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:17.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:39:0) after 1m0s
[05:28:17.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:39:0)
[05:28:17.970] Timer tick, asking Buildbucket for the build status
[05:28:18.110] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:18.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:40:0) after 1m0s
[05:29:18.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:40:0)
[05:29:18.212] Timer tick, asking Buildbucket for the build status
[05:29:18.419] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:18.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:41:0) after 1m0s
[05:30:18.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:41:0)
[05:30:18.539] Timer tick, asking Buildbucket for the build status
[05:30:18.624] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:18.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:42:0) after 1m0s
[05:31:18.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:42:0)
[05:31:18.646] Timer tick, asking Buildbucket for the build status
[05:31:19.081] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:19.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:43:0) after 1m0s
[05:32:19.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:43:0)
[05:32:19.207] Timer tick, asking Buildbucket for the build status
[05:32:19.443] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:19.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:44:0) after 1m0s
[05:33:19.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:44:0)
[05:33:19.523] Timer tick, asking Buildbucket for the build status
[05:33:20.110] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:20.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:45:0) after 1m0s
[05:34:20.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:45:0)
[05:34:20.365] Timer tick, asking Buildbucket for the build status
[05:34:20.440] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:20.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:46:0) after 1m0s
[05:35:20.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:46:0)
[05:35:20.476] Timer tick, asking Buildbucket for the build status
[05:35:20.983] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:20.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:47:0) after 1m0s
[05:36:21.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:47:0)
[05:36:21.211] Timer tick, asking Buildbucket for the build status
[05:36:21.605] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:21.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:48:0) after 1m0s
[05:37:21.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:48:0)
[05:37:21.678] Timer tick, asking Buildbucket for the build status
[05:37:22.054] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:22.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:49:0) after 1m0s
[05:38:22.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:49:0)
[05:38:22.120] Timer tick, asking Buildbucket for the build status
[05:38:22.324] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:22.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:50:0) after 1m0s
[05:39:22.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:50:0)
[05:39:22.382] Timer tick, asking Buildbucket for the build status
[05:39:22.456] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:22.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:51:0) after 1m0s
[05:40:22.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:51:0)
[05:40:22.526] Timer tick, asking Buildbucket for the build status
[05:40:22.685] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:22.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:52:0) after 1m0s
[05:41:22.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:52:0)
[05:41:22.710] Timer tick, asking Buildbucket for the build status
[05:41:22.895] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:22.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:53:0) after 1m0s
[05:42:22.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:53:0)
[05:42:22.912] Timer tick, asking Buildbucket for the build status
[05:42:23.319] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:23.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:54:0) after 1m0s
[05:43:23.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:54:0)
[05:43:23.364] Timer tick, asking Buildbucket for the build status
[05:43:23.509] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:23.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:55:0) after 1m0s
[05:44:23.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:55:0)
[05:44:23.619] Timer tick, asking Buildbucket for the build status
[05:44:23.833] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:23.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:56:0) after 1m0s
[05:45:23.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:56:0)
[05:45:23.873] Timer tick, asking Buildbucket for the build status
[05:45:23.982] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:23.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:57:0) after 1m0s
[05:46:24.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:57:0)
[05:46:24.005] Timer tick, asking Buildbucket for the build status
[05:46:24.350] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:24.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:58:0) after 1m0s
[05:47:24.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:58:0)
[05:47:24.427] Timer tick, asking Buildbucket for the build status
[05:47:24.513] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:24.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:59:0) after 1m0s
[05:48:24.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:59:0)
[05:48:24.644] Timer tick, asking Buildbucket for the build status
[05:48:24.731] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:24.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:60:0) after 1m0s
[05:49:24.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:60:0)
[05:49:24.797] Timer tick, asking Buildbucket for the build status
[05:49:24.893] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:24.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:61:0) after 1m0s
[05:50:24.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:61:0)
[05:50:24.965] Timer tick, asking Buildbucket for the build status
[05:50:25.040] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:25.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:62:0) after 1m0s
[05:51:25.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:62:0)
[05:51:25.076] Timer tick, asking Buildbucket for the build status
[05:51:25.172] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:25.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:63:0) after 1m0s
[05:52:25.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:63:0)
[05:52:25.190] Timer tick, asking Buildbucket for the build status
[05:52:25.403] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:25.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:64:0) after 1m0s
[05:53:25.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:64:0)
[05:53:25.496] Timer tick, asking Buildbucket for the build status
[05:53:25.621] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:25.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:65:0) after 1m0s
[05:54:25.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:65:0)
[05:54:25.743] Timer tick, asking Buildbucket for the build status
[05:54:25.821] Build 8852902728942058064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:25.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018332790881695136:66:0) after 1m0s
[05:55:17.879] Received PubSub notification, asking Buildbucket for the build status
[05:55:17.960] Build 8852902728942058064: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:55:17.960] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615614917145614",
"created_by": "project:chromium",
"created_ts": "1615611063973532",
"id": "8852902728942058064",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a21946a5efba0a0d52771cdc11b733363b4b30fe\"}}",
"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\": \"a21946a5efba0a0d52771cdc11b733363b4b30fe\", \"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-2-zecp\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17783, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"ca5dbef04014dede0beb79aa5a9c71a5b8c6286c\", \"got_dawn_revision\": \"bdc134044932f54ce1a27d92e4c85763d764421e\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"a21946a5efba0a0d52771cdc11b733363b4b30fe\", \"got_revision_cp\": \"refs/heads/master@{#862653}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"314aa755ed3af01dc42d77587752a32a883972ed\", \"got_v8_revision\": \"9d12b666c3e113f5cd61e2b2eb384aae242ac840\", \"got_v8_revision_cp\": \"refs/heads/9.1.105@{#1}\", \"got_webrtc_revision\": \"86473404362e38131203537b8a8c8ec7c1abc31a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33444}\", \"is_cached\": true, \"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\": \"a21946a5efba0a0d52771cdc11b733363b4b30fe\", \"swarm_hashes_refs/heads/master(at){#862653}_without_patch\": {\"absl_hardening_tests\": \"936d0dd7c64c0f1b38033254e100c2b023fec043\", \"accessibility_unittests\": \"4138eecdcef986fac07ba8cf2582a9dd3fb1fd8e\", \"angle_unittests\": \"8764be3c46c95649d86b25e19c010d16a7589bd0\", \"app_shell_unittests\": \"7f63d605691bb67efabdcda50b755aa96a2ec945\", \"aura_unittests\": \"5389ab6344275fe466d2e07f999473174b06cfdc\", \"base_unittests\": \"96c85e3daa0c69ac42dc594ddeceb09ea43a6b27\", \"base_util_unittests\": \"ed3f460df789604e33e7aa33ba0250e65781b775\", \"blink_common_unittests\": \"b5ae0075d62fba85c671408a24aaa9cd9fa07761\", \"blink_fuzzer_unittests\": \"4b6da5308b47bf85819c8587ad03f13851a42c79\", \"blink_heap_unittests\": \"140084029e455b4c463e086358887390522f8485\", \"blink_platform_unittests\": \"a54c5debb1ccb34cbfaac951ab31f7bc59daadd1\", \"blink_unittests\": \"6b1f3eaa7891daa17692b6bac941b3b630263aff\", \"boringssl_crypto_tests\": \"48116f73155803b3adbfbff3cb583975da7f25c8\", \"boringssl_ssl_tests\": \"b03c50f14055d33e112a72355586775436c4a7ff\", \"browser_switcher_bho_unittests\": \"5ef088f8f246dad48142fe7fa207f649a5718e7b\", \"browser_tests\": \"a9457a8da581c0cea28cc7146637a08c16a3806f\", \"capture_unittests\": \"715e9d3596da77ee3629f631c4216c50ea24e5f0\", \"cast_unittests\": \"d3212fb1fadbd497c269ceaed8e1a7d1fc461b82\", \"cc_unittests\": \"034fa0fb44991be805904f777add30b870e92e50\", \"chrome_app_unittests\": \"4c276c0d5242215a7c697e342586a535b55d56a9\", \"chrome_cleaner_unittests\": \"5103463888a4405c8e49a9f75df3a2254d2a7464\", \"chrome_elf_unittests\": \"84eb871e64d8dc5121c973bdaa0882d70f392fd1\", \"chromedriver_unittests\": \"a38fb11476e88834f588b1e0d650affcb70d9324\", \"color_unittests\": \"805729671c916fffb1b6905e7d957d06bf8be1c9\", \"components_browsertests\": \"08d0b18a83fdcfe47783579616e2aba3ee1a882e\", \"components_unittests\": \"a26d8d8f4c0e803acc155366e60eb4f6f463c945\", \"compositor_unittests\": \"3a6c0998fba6506c165a34d7eb51b35448ef0dbf\", \"content_browsertests\": \"6ed2c950888584ba2b2c0eeef075aa1e9bcb1bf3\", \"content_unittests\": \"b26cb2c93759b599c8ae71b3319e9c7a737bcdb5\", \"courgette_unittests\": \"49d75bdf43f6b751ea90f397254ec6783366377f\", \"crashpad_tests\": \"6336ac76ecdb5e5d4cd05c23b132afd718cfcb70\", \"cronet_tests\": \"b24f103e6a70251ad8e0417b1dfd5e572ae28523\", \"cronet_unittests\": \"fb6b27e897eaed8f04b09115b7ff2231e16e7853\", \"crypto_unittests\": \"bf2e08c4e92bc611b47d57881f522a6f2c5b6d7d\", \"delayloads_unittests\": \"6f047f95a931bfb67a724c3d9f38f44c5b540082\", \"device_unittests\": \"05b6596c8479b3dd9111f3c8e5a43cd3655d54b8\", \"display_unittests\": \"4b05095fdc81d8bc4d0aaf8e2a291fea3042fe09\", \"elevation_service_unittests\": \"044830c57a16c58d60086a96a0c34ea812963370\", \"events_unittests\": \"026b84a85c2672641fe3970f30399b726805f657\", \"extensions_browsertests\": \"ec0480470aa1c74a17c221c5afe0751f1d389e42\", \"extensions_unittests\": \"4cf39398bbd66c1b5648e553751b1698ecb0ff49\", \"filesystem_service_unittests\": \"bfc4cedda9b01fd84962ca7edb8f50b7e8c7e968\", \"gcm_unit_tests\": \"6dd440a1f55e8f65255106c92f55e00b162b7b5b\", \"gcp_unittests\": \"82d7911bed515e5c8cb7e74ddc91c527285cbf07\", \"gfx_unittests\": \"5a9c38d48f4491e32cdfa361f281ed3e6c3f5d80\", \"gin_unittests\": \"440c36d64641528ccd8360a8655759ac332db5db\", \"google_apis_unittests\": \"8a96d66b056e64da387d8169ac68af8e8dbe54f9\", \"gpu_unittests\": \"812a35e66f02307d01a70442bb92d70aea0603b8\", \"gwp_asan_unittests\": \"4a234ce89939697e2b86eb806c2dd073f71d4ed0\", \"headless_browsertests\": \"de13f63df1078ef6f9baf32951ca6e30a7bb8c46\", \"headless_unittests\": \"399df708517c69131355bef3397e6ee5289e1800\", \"install_static_unittests\": \"d3566c577dad0a05b5828d2d147a69056b119d37\", \"installer_util_unittests\": \"bb3797c09bdf750edbbcea437ff368f0365f0bdd\", \"interactive_ui_tests\": \"c93a91ca24c5861835507ca97d650d2b35dfa4c3\", \"ipc_tests\": \"52776360bdaea2637ad440b21ff0a70518870595\", \"jingle_unittests\": \"02605fac6517602c6c3b2baf364b6698254a1fa2\", \"latency_unittests\": \"d31cbcdec56200fa4d67a3c5e4c02af2579ba6cb\", \"libjingle_xmpp_unittests\": \"da5bfe15b48afb2d81b0cf5789c4b5370316902b\", \"liburlpattern_unittests\": \"6433462f30a880c19bd0e8c45e9d1dccc2884be4\", \"media_blink_unittests\": \"106e2e25963b3d938e19bc1d5bc0b7bc4b90c38b\", \"media_unittests\": \"3e0fa03e782f65ff3d88d729aabf060a92784018\", \"message_center_unittests\": \"02b71895e3e7bd7fed0aa30ff8eb96e5bc8deb1e\", \"midi_unittests\": \"10208db65237ce546de576288f05f7cadafbcaa3\", \"mojo_core_unittests\": \"de06a3440909647503e9d40caef1ec81e7bc1e26\", \"mojo_unittests\": \"7c465f4282b3f4cd22dd5568ace8cf54d1dec619\", \"nacl_loader_unittests\": \"f253e8ce651d5f6fffbfd21f9215cf8e7f29801f\", \"native_theme_unittests\": \"5e8df0760743803aa07cc90e04db8a9cbf80f49d\", \"net_unittests\": \"6b263664feb0ef84ae62440589e58191fabe8326\", \"notification_helper_unittests\": \"f6a866a826f3bf38c3a8c54ef6baa3a23467e3c2\", \"pdf_unittests\": \"f40c2e0cb79d8ada020caad35242f880229e32c2\", \"perfetto_unittests\": \"c71a85c5f9c575bd063d6d79c285d1e95947cbdb\", \"ppapi_unittests\": \"96032d18c5c029b01a17a8ae4de856c648f0370a\", \"printing_unittests\": \"2a66a33d9720bf3f29e5ca7e8d7273a2fd9dd920\", \"remoting_unittests\": \"c38a862f10b0ce7cb3319c8fbf8efb4e6836c316\", \"sbox_integration_tests\": \"48f038212544dcd93c2535d3937c73358a5430e0\", \"sbox_unittests\": \"58c41596f7257d3377087ecb2586f36894edfe73\", \"sbox_validation_tests\": \"fde24e102bd7947044e113ef1f6515bc5700ac37\", \"service_manager_unittests\": \"43b217995cc8161baa82dd9454e1f481a3e14a98\", \"services_unittests\": \"b6a112bf04c05814ce1bb2f211ff34fa796f5890\", \"setup_unittests\": \"53fe8e03548edfb5ecdfc3c001bcddbd31492d15\", \"shell_dialogs_unittests\": \"2e6f866df5128fcc99b4efc5f996e0adf0e2650c\", \"skia_unittests\": \"0d37c7fc3478f26e64a04bfaadb931c0405ff922\", \"snapshot_unittests\": \"07f4af13168a39b6b2552a30594197528ee251ed\", \"sql_unittests\": \"551259d0d96367b3b08401703f2a07c22a3fcff9\", \"storage_unittests\": \"c91095f30b706d720fe3eb2cb6663a6977c6d507\", \"sync_integration_tests\": \"687aa2342ca9220689264ac9b28dbf9456520565\", \"traffic_annotation_auditor_unittests\": \"c5f097a5f3f47eeb7f5205b580eb2d1240e56884\", \"ui_base_unittests\": \"f7a5b1fddd729d14452992d59db9634f65c87a30\", \"ui_touch_selection_unittests\": \"0e98979015b66041d5acfe54f47784954bf7b0c3\", \"unit_tests\": \"5c4ccd90003b963d8e7f5e2474c3cbb6a4e3dd5a\", \"updater_tests\": \"8beb83aa0ab5915118ecde136f118102ba98b515\", \"url_unittests\": \"035b7e30bea267c27e4321e6e5efa29f50a6c3d8\", \"views_unittests\": \"827dfdeeb14f7b68a6c02174ee9c2f8b66a4f58a\", \"viz_unittests\": \"1fee6d59d2411a163d5e9c0c75ac82a81db286b7\", \"vr_common_unittests\": \"51966bbb4a5e23ae3aa362bc44b0725259ebd916\", \"vr_pixeltests\": \"1c48367db2c8083c51809f32ab5fcba51757f147\", \"weblayer_browsertests\": \"27f183fae52e074a74ad4ead8288161398e162a2\", \"weblayer_unittests\": \"e6ea096470277a9585e2ae34ae9af113dffa075d\", \"wm_unittests\": \"6406372283135c84b048201ea6e9f39c0b02f357\", \"wtf_unittests\": \"6944f8468d8dbf3f4428d88d769e0b460331e4e4\", \"zlib_unittests\": \"c340da81e5fb0a6f815974026c10b56fa5c153d6\", \"zucchini_unittests\": \"25004f2a1b8e10536ea7709d8efd7d2007db9cac\"}}, \"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-2-zecp\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5754-8c4f760\"], \"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": "1615611068198741",
"status": "COMPLETED",
"status_changed_ts": "1615614917531366",
"tags": [
"build_address:luci.chromium.ci/win-asan/17783",
"builder:win-asan",
"buildset:commit/git/a21946a5efba0a0d52771cdc11b733363b4b30fe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a21946a5efba0a0d52771cdc11b733363b4b30fe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018332790881695136",
"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/8852902728942058064/+/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:5242bdf9c7a9fb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615614917535800",
"url": "https://ci.chromium.org/b/8852902728942058064",
"utcnow_ts": "1615614917951542"
}
}
[05:55:17.960] Invocation finished in 1h4m15.220503607s with status SUCCEEDED