[05:09:48.949] New invocation is queued and will start shortly
[05:09:49.997] Starting the invocation (attempt 1)
[05:09:50.032] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:09:50.032] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:09:50.032] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020958922008356416",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1c34619967f42cbc23d07c541ca89bce56835a8d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020958922008356416",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1c34619967f42cbc23d07c541ca89bce56835a8d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c34619967f42cbc23d07c541ca89bce56835a8d",
"gitiles_ref:refs/heads/master"
]
}
[05:09:51.495] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613106590322883",
"id": "8855528859904583232",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1c34619967f42cbc23d07c541ca89bce56835a8d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613106591199047",
"tags": [
"build_address:luci.chromium.ci/win-asan/17293",
"builder:win-asan",
"buildset:commit/git/1c34619967f42cbc23d07c541ca89bce56835a8d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c34619967f42cbc23d07c541ca89bce56835a8d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020958922008356416",
"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/8855528859904583232/+/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": "1613106591199252",
"url": "https://ci.chromium.org/b/8855528859904583232",
"utcnow_ts": "1613106591477357"
}
}
[05:09:51.495] Task URL: https://ci.chromium.org/b/8855528859904583232
[05:09:51.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:2:0) after 1m0s
[05:09:54.899] Received PubSub notification, asking Buildbucket for the build status
[05:09:55.050] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:51.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:2:0)
[05:10:51.516] Timer tick, asking Buildbucket for the build status
[05:10:51.587] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:51.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:4:0) after 1m0s
[05:11:51.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:4:0)
[05:11:51.638] Timer tick, asking Buildbucket for the build status
[05:11:51.728] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:51.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:5:0) after 1m0s
[05:12:51.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:5:0)
[05:12:51.818] Timer tick, asking Buildbucket for the build status
[05:12:52.126] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:52.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:6:0) after 1m0s
[05:13:52.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:6:0)
[05:13:52.095] Timer tick, asking Buildbucket for the build status
[05:13:52.166] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:52.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:7:0) after 1m0s
[05:14:52.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:7:0)
[05:14:52.399] Timer tick, asking Buildbucket for the build status
[05:14:52.502] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:52.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:8:0) after 1m0s
[05:15:52.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:8:0)
[05:15:52.696] Timer tick, asking Buildbucket for the build status
[05:15:53.006] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:53.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:9:0) after 1m0s
[05:16:53.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:9:0)
[05:16:53.054] Timer tick, asking Buildbucket for the build status
[05:16:53.116] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:53.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:10:0) after 1m0s
[05:17:53.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:10:0)
[05:17:53.281] Timer tick, asking Buildbucket for the build status
[05:17:53.370] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:53.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:11:0) after 1m0s
[05:18:53.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:11:0)
[05:18:53.372] Timer tick, asking Buildbucket for the build status
[05:18:53.596] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:53.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:12:0) after 1m0s
[05:19:53.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:12:0)
[05:19:53.661] Timer tick, asking Buildbucket for the build status
[05:19:53.815] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:53.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:13:0) after 1m0s
[05:20:53.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:13:0)
[05:20:53.839] Timer tick, asking Buildbucket for the build status
[05:20:54.037] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:54.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:14:0) after 1m0s
[05:21:54.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:14:0)
[05:21:54.053] Timer tick, asking Buildbucket for the build status
[05:21:54.139] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:54.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:15:0) after 1m0s
[05:22:54.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:15:0)
[05:22:54.218] Timer tick, asking Buildbucket for the build status
[05:22:54.438] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:54.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:16:0) after 1m0s
[05:23:54.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:16:0)
[05:23:54.569] Timer tick, asking Buildbucket for the build status
[05:23:54.650] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:54.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:17:0) after 1m0s
[05:24:54.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:17:0)
[05:24:54.678] Timer tick, asking Buildbucket for the build status
[05:24:54.915] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:54.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:18:0) after 1m0s
[05:25:54.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:18:0)
[05:25:54.926] Timer tick, asking Buildbucket for the build status
[05:25:55.203] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:55.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:19:0) after 1m0s
[05:26:55.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:19:0)
[05:26:55.246] Timer tick, asking Buildbucket for the build status
[05:26:55.592] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:55.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:20:0) after 1m0s
[05:27:55.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:20:0)
[05:27:55.736] Timer tick, asking Buildbucket for the build status
[05:27:55.815] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:55.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:21:0) after 1m0s
[05:28:55.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:21:0)
[05:28:55.970] Timer tick, asking Buildbucket for the build status
[05:28:56.065] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:56.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:22:0) after 1m0s
[05:29:56.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:22:0)
[05:29:56.075] Timer tick, asking Buildbucket for the build status
[05:29:56.592] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:56.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:23:0) after 1m0s
[05:30:56.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:23:0)
[05:30:56.636] Timer tick, asking Buildbucket for the build status
[05:30:57.110] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:57.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:24:0) after 1m0s
[05:31:57.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:24:0)
[05:31:57.126] Timer tick, asking Buildbucket for the build status
[05:31:57.714] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:57.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:25:0) after 1m0s
[05:32:57.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:25:0)
[05:32:57.802] Timer tick, asking Buildbucket for the build status
[05:32:57.903] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:57.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:26:0) after 1m0s
[05:33:58.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:26:0)
[05:33:58.094] Timer tick, asking Buildbucket for the build status
[05:33:58.175] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:58.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:27:0) after 1m0s
[05:34:58.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:27:0)
[05:34:58.235] Timer tick, asking Buildbucket for the build status
[05:34:58.336] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:58.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:28:0) after 1m0s
[05:35:58.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:28:0)
[05:35:58.362] Timer tick, asking Buildbucket for the build status
[05:35:58.459] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:58.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:29:0) after 1m0s
[05:36:58.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:29:0)
[05:36:58.520] Timer tick, asking Buildbucket for the build status
[05:36:58.604] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:58.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:30:0) after 1m0s
[05:37:58.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:30:0)
[05:37:58.609] Timer tick, asking Buildbucket for the build status
[05:37:58.893] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:58.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:31:0) after 1m0s
[05:38:58.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:31:0)
[05:38:58.899] Timer tick, asking Buildbucket for the build status
[05:38:59.108] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:59.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:32:0) after 1m0s
[05:39:59.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:32:0)
[05:39:59.231] Timer tick, asking Buildbucket for the build status
[05:39:59.803] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:59.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:33:0) after 1m0s
[05:40:59.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:33:0)
[05:40:59.913] Timer tick, asking Buildbucket for the build status
[05:41:00.008] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:00.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:34:0) after 1m0s
[05:42:00.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:34:0)
[05:42:00.036] Timer tick, asking Buildbucket for the build status
[05:42:00.536] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:00.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:35:0) after 1m0s
[05:43:00.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:35:0)
[05:43:00.556] Timer tick, asking Buildbucket for the build status
[05:43:00.639] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:00.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:36:0) after 1m0s
[05:44:01.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:36:0)
[05:44:01.277] Timer tick, asking Buildbucket for the build status
[05:44:01.368] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:01.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:37:0) after 1m0s
[05:45:01.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:37:0)
[05:45:01.905] Timer tick, asking Buildbucket for the build status
[05:45:02.045] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:02.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:38:0) after 1m0s
[05:46:02.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:38:0)
[05:46:02.083] Timer tick, asking Buildbucket for the build status
[05:46:02.433] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:02.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:39:0) after 1m0s
[05:47:02.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:39:0)
[05:47:02.478] Timer tick, asking Buildbucket for the build status
[05:47:02.806] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:02.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:40:0) after 1m0s
[05:48:02.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:40:0)
[05:48:02.851] Timer tick, asking Buildbucket for the build status
[05:48:02.938] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:02.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:41:0) after 1m0s
[05:49:02.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:41:0)
[05:49:02.982] Timer tick, asking Buildbucket for the build status
[05:49:03.318] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:03.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:42:0) after 1m0s
[05:50:03.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:42:0)
[05:50:03.348] Timer tick, asking Buildbucket for the build status
[05:50:03.515] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:03.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:43:0) after 1m0s
[05:51:03.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:43:0)
[05:51:03.589] Timer tick, asking Buildbucket for the build status
[05:51:03.697] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:03.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:44:0) after 1m0s
[05:52:03.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:44:0)
[05:52:03.790] Timer tick, asking Buildbucket for the build status
[05:52:04.006] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:04.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:45:0) after 1m0s
[05:53:04.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:45:0)
[05:53:04.029] Timer tick, asking Buildbucket for the build status
[05:53:04.442] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:04.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:46:0) after 1m0s
[05:54:04.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:46:0)
[05:54:04.460] Timer tick, asking Buildbucket for the build status
[05:54:04.546] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:04.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:47:0) after 1m0s
[05:55:04.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:47:0)
[05:55:04.627] Timer tick, asking Buildbucket for the build status
[05:55:04.770] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:04.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:48:0) after 1m0s
[05:56:04.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:48:0)
[05:56:04.862] Timer tick, asking Buildbucket for the build status
[05:56:05.016] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:05.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:49:0) after 1m0s
[05:57:05.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:49:0)
[05:57:05.064] Timer tick, asking Buildbucket for the build status
[05:57:05.160] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:05.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:50:0) after 1m0s
[05:58:05.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:50:0)
[05:58:05.207] Timer tick, asking Buildbucket for the build status
[05:58:05.299] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:05.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:51:0) after 1m0s
[05:59:05.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:51:0)
[05:59:05.319] Timer tick, asking Buildbucket for the build status
[05:59:05.415] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:05.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:52:0) after 1m0s
[06:00:05.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:52:0)
[06:00:05.438] Timer tick, asking Buildbucket for the build status
[06:00:05.910] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:05.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:53:0) after 1m0s
[06:01:05.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:53:0)
[06:01:05.979] Timer tick, asking Buildbucket for the build status
[06:01:06.089] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:06.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:54:0) after 1m0s
[06:02:06.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:54:0)
[06:02:06.171] Timer tick, asking Buildbucket for the build status
[06:02:06.332] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:06.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:55:0) after 1m0s
[06:03:06.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:55:0)
[06:03:06.333] Timer tick, asking Buildbucket for the build status
[06:03:06.433] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:06.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:56:0) after 1m0s
[06:04:06.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:56:0)
[06:04:06.447] Timer tick, asking Buildbucket for the build status
[06:04:06.722] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:06.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:57:0) after 1m0s
[06:05:06.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:57:0)
[06:05:06.805] Timer tick, asking Buildbucket for the build status
[06:05:06.965] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:06.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:58:0) after 1m0s
[06:06:07.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:58:0)
[06:06:07.022] Timer tick, asking Buildbucket for the build status
[06:06:07.292] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:07.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:59:0) after 1m0s
[06:07:07.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:59:0)
[06:07:07.360] Timer tick, asking Buildbucket for the build status
[06:07:07.442] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:07.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:60:0) after 1m0s
[06:08:07.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:60:0)
[06:08:07.472] Timer tick, asking Buildbucket for the build status
[06:08:07.563] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:07.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:61:0) after 1m0s
[06:09:07.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:61:0)
[06:09:07.631] Timer tick, asking Buildbucket for the build status
[06:09:07.731] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:07.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:62:0) after 1m0s
[06:10:07.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:62:0)
[06:10:07.728] Timer tick, asking Buildbucket for the build status
[06:10:08.009] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:08.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:63:0) after 1m0s
[06:11:08.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:63:0)
[06:11:08.070] Timer tick, asking Buildbucket for the build status
[06:11:08.315] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:08.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:64:0) after 1m0s
[06:12:08.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:64:0)
[06:12:08.349] Timer tick, asking Buildbucket for the build status
[06:12:08.444] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:08.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:65:0) after 1m0s
[06:13:08.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:65:0)
[06:13:08.517] Timer tick, asking Buildbucket for the build status
[06:13:08.957] Build 8855528859904583232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:08.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020958922008356416:66:0) after 1m0s
[06:13:27.819] Received PubSub notification, asking Buildbucket for the build status
[06:13:27.906] Build 8855528859904583232: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:13:27.907] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613110406565622",
"created_by": "project:chromium",
"created_ts": "1613106590322883",
"id": "8855528859904583232",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1c34619967f42cbc23d07c541ca89bce56835a8d\"}}",
"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\": \"1c34619967f42cbc23d07c541ca89bce56835a8d\", \"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-1-ckf5\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17293, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"d44159c23c104746dd12b35d251922d89d45f4e8\", \"got_nacl_revision\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\", \"got_revision\": \"1c34619967f42cbc23d07c541ca89bce56835a8d\", \"got_revision_cp\": \"refs/heads/master@{#853401}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"673a7fe5c3ff6686c98951cafb24a93ca5bd1303\", \"got_v8_revision\": \"358aa1391624d156e3a358fae69b15a0dcee52b9\", \"got_v8_revision_cp\": \"refs/heads/9.0.166@{#1}\", \"got_webrtc_revision\": \"f28d7e89b7a3c985d83f59d16b9f3c3f45ef7997\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33224}\", \"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\": \"1c34619967f42cbc23d07c541ca89bce56835a8d\", \"swarm_hashes_refs/heads/master(at){#853401}_without_patch\": {\"absl_hardening_tests\": \"3a039c5b9cd9a9dfb16d1981e953185ef2f42759\", \"accessibility_unittests\": \"d466ee77c1eaf4faf69552b018e9f2b719b5e6b7\", \"angle_unittests\": \"f60d5b6ce4bb554d08f08519285c043ff4885cfb\", \"app_shell_unittests\": \"0ab4ce20df01502c0f1c48eb0be08352195f2b1a\", \"aura_unittests\": \"7da14b5aff818f759efed352dec0be344e7fc4f4\", \"base_unittests\": \"27b84f4263c564ba42bcd348066dc4ca96673baf\", \"base_util_unittests\": \"76c516e614471cd424ec8f918a44e86aef37e1a2\", \"blink_common_unittests\": \"b3ada9ff696d4189327edcdfb6619af23c78cfec\", \"blink_fuzzer_unittests\": \"996827238400c9116d906a8e0d69be437e6d234a\", \"blink_heap_unittests\": \"b7d7b9b64aca006c1a9b1bb62a09535acffd901e\", \"blink_platform_unittests\": \"ccecf9593f621a3f325536bd762731f570d4e8f5\", \"blink_unittests\": \"14e965d945179e65933d27b6a2ec6ebe5e7cd967\", \"boringssl_crypto_tests\": \"4d18ebf91aafaf0ecae7002933ae673f40136d1f\", \"boringssl_ssl_tests\": \"d602c63e4991c8745ae651a4bb07581e3ee2db34\", \"browser_switcher_bho_unittests\": \"893e8b1b49b7c442708f35a8a8ba318477db4af2\", \"browser_tests\": \"96b083d2907b7393a61c18c1948c5c7a195eeaf5\", \"capture_unittests\": \"94d776e00447127574f4c0211d489b93ae4fc6a0\", \"cast_unittests\": \"5c31402df02a916f367f2b283286402670c54409\", \"cc_unittests\": \"587bee0c0cabcb22e701324e8fe92fdd1f80a79f\", \"chrome_app_unittests\": \"b4459f822ce218d488b354142fde91424eff9c65\", \"chrome_cleaner_unittests\": \"d3e40365cf1ec542d9ec6064bd11973b91afb5ef\", \"chrome_elf_unittests\": \"01fe2413e09c0d1dcc8fcec913c7ab2bb2fb187a\", \"chromedriver_unittests\": \"72bbe793e63f626b64a06ba8f2a0717bc526bfe0\", \"components_browsertests\": \"bbfa5755593ca2355250af44d3e6cc95a45cdfc7\", \"components_unittests\": \"1976e2efdbbffc83712d94b25e01fed0ceae706f\", \"compositor_unittests\": \"509b8866602d547a0ec5e08d95e2411d97548f57\", \"content_browsertests\": \"12678ac9e33280607bb0e029ece0cdb77ae84236\", \"content_unittests\": \"f01ad9f967219dd6c0148621d0e85c07334e698d\", \"courgette_unittests\": \"07bd19bdf63060c401b7d531e7fcbcf9fa4e22f5\", \"crashpad_tests\": \"cedb260da41d345883028410588e94e673afb4fe\", \"cronet_tests\": \"bab7803df96152839cbe3280e841f6e00651da1c\", \"cronet_unittests\": \"ad26cdd062fc9f1dacbd160d8ca7c5933645710b\", \"crypto_unittests\": \"822cb3f663ea1b18f7dcc63991368ee3f56488a8\", \"delayloads_unittests\": \"8016021ea8c44ffa624926cde8d2c8e3c7c115d6\", \"device_unittests\": \"735dab67cf649e8802e58f0f035d806d92f088a8\", \"display_unittests\": \"82568530cf21eb136037f776106219da01f76d1d\", \"elevation_service_unittests\": \"f9ae918755867ed82f4fc109e936b034b92d6cb9\", \"events_unittests\": \"9dc93590411ad30d97466fe80c9fa4ee743a59d8\", \"extensions_browsertests\": \"7bf92626558669acc8a25f02bbc13250fdb50b3c\", \"extensions_unittests\": \"caaf95b695436071575983c32a58e8c71e457237\", \"filesystem_service_unittests\": \"5f7a600bc16262e5de1f372016b4e733ee9855de\", \"gcm_unit_tests\": \"8ffaede3df11d559eb5a89565fa3ea898a1b553d\", \"gcp_unittests\": \"87345152719ebe8225a34d5d9cb59cd2d1681bb6\", \"gfx_unittests\": \"e34631f61d8e4aee14b6f252633d857b6ca2da8e\", \"gin_unittests\": \"e117a3446104006792051b83a0ff03e8fcf92b3d\", \"google_apis_unittests\": \"d84c8ffd9e07bad76d8d23146bc54173649a58ab\", \"gpu_unittests\": \"a7e8ca726eb5d5f53d41ec3724ea9e4492a781d9\", \"gwp_asan_unittests\": \"e8a78c59e726b6be5b8be6a3704ff084c1252c43\", \"headless_browsertests\": \"dac7f4c8684860135baa9e32efcf4049c76adb61\", \"headless_unittests\": \"5b84c635af76a30c6726c9e0850cadc7c95c5a0d\", \"install_static_unittests\": \"8bae182a41f8efb185ae385547df3f04ca77b3d0\", \"installer_util_unittests\": \"f3b1d94238a28cfa03370e65bef301cfebf25b8f\", \"interactive_ui_tests\": \"f3ce1bad2c80de23569ca5b717406a43f9fe79fa\", \"ipc_tests\": \"31b269e20350afc5c818d57a1097bbacba5db45a\", \"jingle_unittests\": \"5d945820705c6e36b66f894ca15d7e99a166ec02\", \"latency_unittests\": \"a7a5bbe99a7004c2022f03c98c8f6b75a5722163\", \"libjingle_xmpp_unittests\": \"d80707ed03295a18d82096e595fee82fef59fdb5\", \"liburlpattern_unittests\": \"e7d8547823d08b5090cf40274d70aa674fd0d768\", \"media_blink_unittests\": \"32facbef7f9d7de19531bd98a43182c094086221\", \"media_unittests\": \"c27fb9f42ed79ca9675018fd4b538d5942e618e0\", \"message_center_unittests\": \"7265cfbc8a656bde7f3f89122e0f3e568f6f76eb\", \"midi_unittests\": \"76ada4ef09391b2b7cf65c7924834b58e25c0617\", \"mojo_core_unittests\": \"3e4104a4aad01a4d0fadb73746a0b011d8bdd13f\", \"mojo_unittests\": \"73698d0412a3b20bbacb47d5a8296af1d9d3ecb5\", \"nacl_loader_unittests\": \"0c1c625e7dc3b789634d94da46b4a87685e76bd3\", \"native_theme_unittests\": \"f50bbc3006a81923ea680ad2eae84383959ed479\", \"net_unittests\": \"2e82bec3a2198656a2be53297ba17d47a3ace662\", \"notification_helper_unittests\": \"5ad019884fbab66f4d545a7cbec3d9c23676446f\", \"pdf_unittests\": \"d65fa39762cfb88f844bc467b49daf17fe347e02\", \"perfetto_unittests\": \"924961f43f69d7b1d1e9d3398c43c3a82df1e7fe\", \"ppapi_unittests\": \"3e3b62ef501b6033118fad8709a57bb8fd7849f0\", \"printing_unittests\": \"c9915e9994715362559e206251573afb3be05289\", \"remoting_unittests\": \"d3e778bb0ab4fcb492f704e15db98b134d442059\", \"sbox_integration_tests\": \"f064568b0e6a28dec66f6dc8f1517f59103db81e\", \"sbox_unittests\": \"1e4ec602cc88f7d8c2c423bd0466ad1debbbf8a4\", \"sbox_validation_tests\": \"9b630326d220022e92f0b00ba1f780f0580f30f3\", \"service_manager_unittests\": \"7ca8c16b5bd9d43429c0a369dcfd4cd6dfcfe115\", \"services_unittests\": \"a4ca191f978b0496427c39bfee073437ebfdb381\", \"setup_unittests\": \"71e71ead8e448320252b6da1e4bc94d482cb24c5\", \"shell_dialogs_unittests\": \"5b34c38e074f61e4fc0116efddace8bc506a17d8\", \"skia_unittests\": \"9c39ea685213db8fba5363fd8ea6d91f2a2db7bc\", \"snapshot_unittests\": \"c38a310d393d191dee6e9b1f8c45dae616059ffd\", \"sql_unittests\": \"bf632344b38eabdcdc3e985ad50aebbcf8014636\", \"storage_unittests\": \"3da9f39b8d299fc284e5ce0b50213846bb997e16\", \"sync_integration_tests\": \"a327053132e24c932d7a63ec0a654d1ec4974343\", \"traffic_annotation_auditor_unittests\": \"f76b9f0189e3e2b365bcce038ed67e28c01faad4\", \"ui_base_unittests\": \"0c6235d381097ef5d972e64ca4f1103276e12d18\", \"ui_touch_selection_unittests\": \"c2a05eae2f1a40d7647e04946c64422ba5ed9688\", \"unit_tests\": \"f0a555632647aae7a86dbe8f70aa3906afa6d32c\", \"updater_tests\": \"332a245f45a58a6a17d94f15520bd68c60da5a60\", \"url_unittests\": \"8f65e0f16f3f1e64d3b12899d25b6b9583c0bd25\", \"views_unittests\": \"2513a4e0c8ecaf69352235a86d8fa54abbc3e0e1\", \"viz_unittests\": \"8ed3644aeb07f5984172dcef02ac0003f0168458\", \"vr_common_unittests\": \"056aa29f2d30a11ec8111eab57560ede4d680675\", \"vr_pixeltests\": \"f182553305920c72b47fe8429bd730de2df262b0\", \"weblayer_browsertests\": \"f7aa34ec0ccbb9356cc6ee98ebfd8ef2131382af\", \"weblayer_unittests\": \"cd42f1684352a56fe929603e808c80d18dcf9369\", \"wm_unittests\": \"b9c4be20cc71fc06f8f9dcb95b9c7494c5e92a21\", \"wtf_unittests\": \"2df1548a7960f65869c7f6b4ff6c786d956a663e\", \"zlib_unittests\": \"9df684492d2162af5a75bbb4f09e9a13d9015d80\", \"zucchini_unittests\": \"79339efb835a4993ce8e1ca902387f919a78fc89\"}}, \"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-1-ckf5\"], \"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\": [\"5690-40331dd\"], \"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": "1613106594306760",
"status": "COMPLETED",
"status_changed_ts": "1613110407104105",
"tags": [
"build_address:luci.chromium.ci/win-asan/17293",
"builder:win-asan",
"buildset:commit/git/1c34619967f42cbc23d07c541ca89bce56835a8d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c34619967f42cbc23d07c541ca89bce56835a8d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020958922008356416",
"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/8855528859904583232/+/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:51ad76c0fd69f110",
"user_agent:luci-scheduler"
],
"updated_ts": "1613110407108522",
"url": "https://ci.chromium.org/b/8855528859904583232",
"utcnow_ts": "1613110407895752"
}
}
[06:13:27.907] Invocation finished in 1h3m38.974856459s with status SUCCEEDED