[05:38:59.474] New invocation is queued and will start shortly
[05:39:00.540] Starting the invocation (attempt 1)
[05:39:00.575] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:39:00.575] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:39:00.575] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025486934769560896",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8baa5d6f121f1d2f249112da784dd5e5dafdecdc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025486934769560896",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/8baa5d6f121f1d2f249112da784dd5e5dafdecdc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8baa5d6f121f1d2f249112da784dd5e5dafdecdc",
"gitiles_ref:refs/heads/master"
]
}
[05:39:01.645] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608788340683317",
"id": "8860056872837924080",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8baa5d6f121f1d2f249112da784dd5e5dafdecdc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608788341401361",
"tags": [
"build_address:luci.chromium.ci/win-asan/16424",
"builder:win-asan",
"buildset:commit/git/8baa5d6f121f1d2f249112da784dd5e5dafdecdc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8baa5d6f121f1d2f249112da784dd5e5dafdecdc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025486934769560896",
"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/8860056872837924080/+/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": "1608788341401573",
"url": "https://ci.chromium.org/b/8860056872837924080",
"utcnow_ts": "1608788341636099"
}
}
[05:39:01.646] Task URL: https://ci.chromium.org/b/8860056872837924080
[05:39:01.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:2:0) after 1m0s
[05:39:04.454] Received PubSub notification, asking Buildbucket for the build status
[05:39:05.584] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:01.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:2:0)
[05:40:01.869] Timer tick, asking Buildbucket for the build status
[05:40:01.980] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:01.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:4:0) after 1m0s
[05:41:02.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:4:0)
[05:41:02.071] Timer tick, asking Buildbucket for the build status
[05:41:02.193] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:02.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:5:0) after 1m0s
[05:42:02.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:5:0)
[05:42:02.314] Timer tick, asking Buildbucket for the build status
[05:42:03.350] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:03.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:6:0) after 1m0s
[05:43:03.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:6:0)
[05:43:03.368] Timer tick, asking Buildbucket for the build status
[05:43:03.730] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:03.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:7:0) after 1m0s
[05:44:03.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:7:0)
[05:44:03.735] Timer tick, asking Buildbucket for the build status
[05:44:03.942] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:03.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:8:0) after 1m0s
[05:45:03.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:8:0)
[05:45:03.942] Timer tick, asking Buildbucket for the build status
[05:45:04.098] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:04.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:9:0) after 1m0s
[05:46:04.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:9:0)
[05:46:04.096] Timer tick, asking Buildbucket for the build status
[05:46:04.165] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:04.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:10:0) after 1m0s
[05:47:04.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:10:0)
[05:47:04.197] Timer tick, asking Buildbucket for the build status
[05:47:04.264] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:04.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:11:0) after 1m0s
[05:48:04.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:11:0)
[05:48:04.270] Timer tick, asking Buildbucket for the build status
[05:48:04.657] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:04.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:12:0) after 1m0s
[05:49:04.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:12:0)
[05:49:04.710] Timer tick, asking Buildbucket for the build status
[05:49:04.933] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:04.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:13:0) after 1m0s
[05:50:05.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:13:0)
[05:50:05.002] Timer tick, asking Buildbucket for the build status
[05:50:05.074] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:05.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:14:0) after 1m0s
[05:51:05.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:14:0)
[05:51:05.241] Timer tick, asking Buildbucket for the build status
[05:51:05.346] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:05.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:15:0) after 1m0s
[05:52:05.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:15:0)
[05:52:05.344] Timer tick, asking Buildbucket for the build status
[05:52:05.459] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:05.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:16:0) after 1m0s
[05:53:05.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:16:0)
[05:53:05.480] Timer tick, asking Buildbucket for the build status
[05:53:05.596] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:05.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:17:0) after 1m0s
[05:54:05.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:17:0)
[05:54:05.662] Timer tick, asking Buildbucket for the build status
[05:54:05.891] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:05.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:18:0) after 1m0s
[05:55:06.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:18:0)
[05:55:06.260] Timer tick, asking Buildbucket for the build status
[05:55:06.348] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:06.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:19:0) after 1m0s
[05:56:06.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:19:0)
[05:56:06.360] Timer tick, asking Buildbucket for the build status
[05:56:06.701] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:06.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:20:0) after 1m0s
[05:57:06.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:20:0)
[05:57:06.725] Timer tick, asking Buildbucket for the build status
[05:57:06.799] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:06.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:21:0) after 1m0s
[05:58:06.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:21:0)
[05:58:06.877] Timer tick, asking Buildbucket for the build status
[05:58:06.976] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:06.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:22:0) after 1m0s
[05:59:07.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:22:0)
[05:59:07.005] Timer tick, asking Buildbucket for the build status
[05:59:07.141] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:07.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:23:0) after 1m0s
[06:00:07.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:23:0)
[06:00:07.185] Timer tick, asking Buildbucket for the build status
[06:00:07.429] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:07.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:24:0) after 1m0s
[06:01:07.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:24:0)
[06:01:07.772] Timer tick, asking Buildbucket for the build status
[06:01:07.903] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:07.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:25:0) after 1m0s
[06:02:07.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:25:0)
[06:02:07.944] Timer tick, asking Buildbucket for the build status
[06:02:08.075] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:08.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:26:0) after 1m0s
[06:03:08.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:26:0)
[06:03:08.125] Timer tick, asking Buildbucket for the build status
[06:03:08.284] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:08.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:27:0) after 1m0s
[06:04:08.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:27:0)
[06:04:08.259] Timer tick, asking Buildbucket for the build status
[06:04:08.378] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:08.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:28:0) after 1m0s
[06:05:08.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:28:0)
[06:05:08.439] Timer tick, asking Buildbucket for the build status
[06:05:08.570] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:08.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:29:0) after 1m0s
[06:06:08.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:29:0)
[06:06:08.571] Timer tick, asking Buildbucket for the build status
[06:06:09.214] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:09.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:30:0) after 1m0s
[06:07:09.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:30:0)
[06:07:09.243] Timer tick, asking Buildbucket for the build status
[06:07:09.370] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:09.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:31:0) after 1m0s
[06:08:09.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:31:0)
[06:08:09.414] Timer tick, asking Buildbucket for the build status
[06:08:09.556] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:09.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:32:0) after 1m0s
[06:09:09.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:32:0)
[06:09:09.571] Timer tick, asking Buildbucket for the build status
[06:09:09.793] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:09.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:33:0) after 1m0s
[06:10:09.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:33:0)
[06:10:09.792] Timer tick, asking Buildbucket for the build status
[06:10:09.854] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:09.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:34:0) after 1m0s
[06:11:09.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:34:0)
[06:11:09.874] Timer tick, asking Buildbucket for the build status
[06:11:09.936] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:09.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:35:0) after 1m0s
[06:12:10.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:35:0)
[06:12:10.318] Timer tick, asking Buildbucket for the build status
[06:12:10.391] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:10.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:36:0) after 1m0s
[06:13:10.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:36:0)
[06:13:10.402] Timer tick, asking Buildbucket for the build status
[06:13:10.509] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:10.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:37:0) after 1m0s
[06:14:10.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:37:0)
[06:14:10.528] Timer tick, asking Buildbucket for the build status
[06:14:10.690] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:10.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:38:0) after 1m0s
[06:15:10.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:38:0)
[06:15:10.711] Timer tick, asking Buildbucket for the build status
[06:15:10.803] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:10.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:39:0) after 1m0s
[06:16:10.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:39:0)
[06:16:10.810] Timer tick, asking Buildbucket for the build status
[06:16:10.913] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:10.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:40:0) after 1m0s
[06:17:10.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:40:0)
[06:17:10.960] Timer tick, asking Buildbucket for the build status
[06:17:11.043] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:11.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:41:0) after 1m0s
[06:18:11.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:41:0)
[06:18:11.114] Timer tick, asking Buildbucket for the build status
[06:18:11.197] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:11.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:42:0) after 1m0s
[06:19:11.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:42:0)
[06:19:11.213] Timer tick, asking Buildbucket for the build status
[06:19:11.334] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:11.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:43:0) after 1m0s
[06:20:11.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:43:0)
[06:20:11.397] Timer tick, asking Buildbucket for the build status
[06:20:11.919] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:11.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:44:0) after 1m0s
[06:21:11.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:44:0)
[06:21:11.908] Timer tick, asking Buildbucket for the build status
[06:21:12.025] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:12.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:45:0) after 1m0s
[06:22:12.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:45:0)
[06:22:12.045] Timer tick, asking Buildbucket for the build status
[06:22:12.183] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:12.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:46:0) after 1m0s
[06:23:12.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:46:0)
[06:23:12.225] Timer tick, asking Buildbucket for the build status
[06:23:12.808] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:12.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:47:0) after 1m0s
[06:24:12.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:47:0)
[06:24:12.803] Timer tick, asking Buildbucket for the build status
[06:24:13.177] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:13.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:48:0) after 1m0s
[06:25:13.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:48:0)
[06:25:13.209] Timer tick, asking Buildbucket for the build status
[06:25:13.385] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:13.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:49:0) after 1m0s
[06:26:13.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:49:0)
[06:26:13.412] Timer tick, asking Buildbucket for the build status
[06:26:14.483] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:14.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:50:0) after 1m0s
[06:27:14.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:50:0)
[06:27:14.566] Timer tick, asking Buildbucket for the build status
[06:27:14.701] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:14.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:51:0) after 1m0s
[06:28:14.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:51:0)
[06:28:14.705] Timer tick, asking Buildbucket for the build status
[06:28:14.885] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:14.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:52:0) after 1m0s
[06:29:14.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:52:0)
[06:29:14.942] Timer tick, asking Buildbucket for the build status
[06:29:15.397] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:15.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:53:0) after 1m0s
[06:30:15.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:53:0)
[06:30:15.511] Timer tick, asking Buildbucket for the build status
[06:30:15.648] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:15.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:54:0) after 1m0s
[06:31:15.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:54:0)
[06:31:15.703] Timer tick, asking Buildbucket for the build status
[06:31:15.916] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:15.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:55:0) after 1m0s
[06:32:15.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:55:0)
[06:32:15.949] Timer tick, asking Buildbucket for the build status
[06:32:16.035] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:16.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:56:0) after 1m0s
[06:33:16.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:56:0)
[06:33:16.078] Timer tick, asking Buildbucket for the build status
[06:33:16.201] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:16.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:57:0) after 1m0s
[06:34:16.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:57:0)
[06:34:16.285] Timer tick, asking Buildbucket for the build status
[06:34:16.445] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:16.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:58:0) after 1m0s
[06:35:16.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:58:0)
[06:35:16.474] Timer tick, asking Buildbucket for the build status
[06:35:16.539] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:16.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:59:0) after 1m0s
[06:36:16.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:59:0)
[06:36:16.562] Timer tick, asking Buildbucket for the build status
[06:36:16.642] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:16.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:60:0) after 1m0s
[06:37:16.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:60:0)
[06:37:16.687] Timer tick, asking Buildbucket for the build status
[06:37:17.017] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:17.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:61:0) after 1m0s
[06:38:17.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:61:0)
[06:38:17.038] Timer tick, asking Buildbucket for the build status
[06:38:17.283] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:17.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:62:0) after 1m0s
[06:39:17.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:62:0)
[06:39:17.313] Timer tick, asking Buildbucket for the build status
[06:39:17.517] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:17.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:63:0) after 1m0s
[06:40:17.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:63:0)
[06:40:17.538] Timer tick, asking Buildbucket for the build status
[06:40:17.797] Build 8860056872837924080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:17.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025486934769560896:64:0) after 1m0s
[06:40:18.422] Received PubSub notification, asking Buildbucket for the build status
[06:40:18.512] Build 8860056872837924080: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:40:18.512] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608792016740713",
"created_by": "project:chromium",
"created_ts": "1608788340683317",
"id": "8860056872837924080",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8baa5d6f121f1d2f249112da784dd5e5dafdecdc\"}}",
"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\": \"8baa5d6f121f1d2f249112da784dd5e5dafdecdc\", \"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-gpyc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16424, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"cde0e3ab3d710ca9838d226873462c5f093f60bd\", \"got_dawn_revision\": \"700809a7f75bab9df6d1195153ea1cbfb078046c\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"8baa5d6f121f1d2f249112da784dd5e5dafdecdc\", \"got_revision_cp\": \"refs/heads/master@{#839243}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"711cf5ad4a12abe0f6b50058cb2248d7fb80374e\", \"got_v8_revision_cp\": \"refs/heads/8.9.187@{#1}\", \"got_webrtc_revision\": \"fec83cc8602580ce1c078f7cd4e7f4d2be4ac850\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32877}\", \"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\": \"8baa5d6f121f1d2f249112da784dd5e5dafdecdc\", \"swarm_hashes_refs/heads/master(at){#839243}_without_patch\": {\"absl_hardening_tests\": \"ce1f0f9b9b25e3cdf6a07a0e0b76af9716afb48f\", \"accessibility_unittests\": \"7d0a55b00275fa9588a503fa6f7e09d289fd2c47\", \"angle_unittests\": \"67cd23f99a5073ad7c8b5b3f06dcdc0ce55ee69a\", \"app_shell_unittests\": \"a81146725613d1f5b6c4086a131ad05059c10d28\", \"aura_unittests\": \"6f830de594e1704f27eeaec0187d4c1f14623e1b\", \"base_unittests\": \"e4a41f788bc9fce6eaa8930a80427275a9b311d7\", \"base_util_unittests\": \"5c845bf46e78057b517371484642806e1e521fd4\", \"blink_common_unittests\": \"158c1b7e622827998d9033d183d5daa6153377c9\", \"blink_fuzzer_unittests\": \"f00225d73b94dfc934392ec285ea7446ef9f393b\", \"blink_heap_unittests\": \"41f8693e500f91c6581023ee544d46dfd224ec6e\", \"blink_platform_unittests\": \"c830e512306873ce1234e1d9aee4e41fcfd46202\", \"blink_unittests\": \"7410a474fea2b86813d2b083a97d5c29d148f933\", \"boringssl_crypto_tests\": \"2504f21ea2ffdfb788e76dcef30b9541551ef265\", \"boringssl_ssl_tests\": \"cedf376879c94d77766e67635c228f52690a5ae8\", \"browser_switcher_bho_unittests\": \"a778b9e8cd53b81c550f894474346318a7bfba9d\", \"browser_tests\": \"95b7f129671e49dee3e3770e64006c32c8429446\", \"capture_unittests\": \"d9c1880d4b2759fd93b947bf855b6d0b89777531\", \"cast_unittests\": \"3aeed45bf6b14c1ed3d34e658933064169b42cd6\", \"cc_unittests\": \"e14eee720e3f46464f3cd5cec7cc2b7e8eaffe13\", \"chrome_app_unittests\": \"bc93bee16d25c71e2c2a279cd600780de34c8813\", \"chrome_cleaner_unittests\": \"49c9a9d39d84062e3e50ae4c71f75ec6f5a444a7\", \"chrome_elf_unittests\": \"ce295634917d8fca9ce27ead8639498eb035c0df\", \"chromedriver_unittests\": \"d0e3c88fcfa2e01f83bdadc680add8e119569c5a\", \"components_browsertests\": \"28668cc88c2df7c17642dfb2446a25adf10a2f81\", \"components_unittests\": \"eaeaad56297a054e586be9ee2543d61f775b898b\", \"compositor_unittests\": \"e1d269dfbc72ede1b828351297af5cbc1090e613\", \"content_browsertests\": \"e8de6acfe6c13434636d68e8f7959ffe21d5c801\", \"content_unittests\": \"8cd75ad4497550eaf2bbec4dbe8abfe2be8e2344\", \"courgette_unittests\": \"9255ed270c746a4277c076afb8e4ecc0765a665e\", \"crashpad_tests\": \"505482293273a34ff74cf1fe369e89dbddb15977\", \"cronet_tests\": \"42306de6bc0a9e370d0af711b6144b02d405132a\", \"cronet_unittests\": \"baedfee418e3573dbbd2715ef8071caf00e0008d\", \"crypto_unittests\": \"c48cfbbd3fe8af209aefad43c9d29265df725895\", \"delayloads_unittests\": \"9e6eea31fb37e7ae37c50c0c719bdcb4510f1887\", \"device_unittests\": \"3bca1a8e09d2d21d3c685417e53aeea985b52d0e\", \"display_unittests\": \"4c751897a0fdf1f82ded067ce1c7c30a4849857e\", \"elevation_service_unittests\": \"6cf675714067460d7a14870682c5a7b253544540\", \"events_unittests\": \"f3baf68fe194767fe38a7aad1322d97a2655db8b\", \"extensions_browsertests\": \"ee2d8218d5226a7ba4afe645af8116f1bfaee3fa\", \"extensions_unittests\": \"15f27df352e139445c3cfa440934991eed80fa79\", \"filesystem_service_unittests\": \"ad643e4ab3e0696cce39bd415a38d0fab5220f9d\", \"gcm_unit_tests\": \"b44716eb2b0260f73432322e4f4ea36341112344\", \"gcp_unittests\": \"0368f406e50cb68892ae55ab30df7fe8b5391c7b\", \"gfx_unittests\": \"5ec6414eb1420d1674dc78883aee4864f4ce37d3\", \"gin_unittests\": \"be87c5dc4264fc574b938f678825984e75e90b79\", \"google_apis_unittests\": \"5e7ad49de41cf8b66a9d149a4219eade9ef3a819\", \"gpu_unittests\": \"e59595c843ae78331b331f74866947d95f0e7cee\", \"gwp_asan_unittests\": \"4ee736f8e9eb3049d0288af289c5b9b8a75d030f\", \"headless_browsertests\": \"aec171700fa12b4d889ab43161e0dc2d584a1351\", \"headless_unittests\": \"c1895b1de7e25de857a9f376dee6aee52fdcbdf0\", \"install_static_unittests\": \"dbe67ad51cbd2c5819bbb6f002b027aa1114b09d\", \"installer_util_unittests\": \"2871f2e22bc045bddf858d4a3744cc04069100aa\", \"interactive_ui_tests\": \"a1061112af47ba0f3b9d1ddc197c2b021323b6db\", \"ipc_tests\": \"99e2c4ff9f7e7e28456f14bcdd517f543d012247\", \"jingle_unittests\": \"7773ef92d7dd8ef8aca5bc1f152c024197bfd77f\", \"latency_unittests\": \"11d58eb3c757086f39e861dac62f6f9e19d3f602\", \"libjingle_xmpp_unittests\": \"2ca994cb2da85c1c6fd970340d7618f54a90e9c3\", \"liburlpattern_unittests\": \"e83204cc574022d43bc66ff187ae4be4bfd1d3a0\", \"media_blink_unittests\": \"7f395cb094eebc4b8d08fe8924b8dd591fcac2c3\", \"media_unittests\": \"b633dac64f1eb5769f133094af82570821ca7719\", \"message_center_unittests\": \"951a89a964e023d604c27bdd4b15ea7af353f637\", \"midi_unittests\": \"135cf2ff92e76ecc53e8ac69eb28f006ba502668\", \"mojo_core_unittests\": \"19501930761909f9a2dc66e5f038640dca795c62\", \"mojo_unittests\": \"c8c0464ef2a6d4b172398ff3a4443ed2b8bd1d1e\", \"nacl_loader_unittests\": \"747510d4ab6fd936c7721e3ba0a6ab59d6bb8db6\", \"native_theme_unittests\": \"120ab8a1975ee892adbfb6a4435aa49b241f6886\", \"net_unittests\": \"174af46ccf001969f8170a643cf29fcf07e28d7a\", \"notification_helper_unittests\": \"65a18df1726f97addd439dbb127438f217db4a48\", \"pdf_unittests\": \"dc18762057c8bf1b81d442ee713b9e6ac4600231\", \"perfetto_unittests\": \"579be7f61edfec382e75f1d9d552160b7eec9df3\", \"ppapi_unittests\": \"882ec65d0bf5410be7a2c3664ad06ba54b8520c2\", \"printing_unittests\": \"533fbf3fce15e3be01e39e6abf33b1151c267d33\", \"remoting_unittests\": \"035c909f064275abaa19c7c8a3d5842d7593b4c4\", \"sbox_integration_tests\": \"be56f3e66968a0273b92354c28e94f781ea5c76f\", \"sbox_unittests\": \"ebbdcfed84b280a25f8a8f05ce6b0571b4498c4e\", \"sbox_validation_tests\": \"5b1f6785a0e202c160a932a0d96b671710dc02e9\", \"service_manager_unittests\": \"7ea207dd06c97113b7c1a1194d302d201cc5492a\", \"services_unittests\": \"771d84bc895561a5ac7814548b5b307c816815c3\", \"setup_unittests\": \"0ef32934ff016a7604002f7fd6dc8e69fda3a0d4\", \"shell_dialogs_unittests\": \"436d7dc1ebbd556cad674dc34467a3e56423ec0f\", \"skia_unittests\": \"a8c9226af9023bb4d9938d591269613be7c1cd58\", \"snapshot_unittests\": \"f65f2a4dcfd2ea6bd00c3126e5949424e3102aec\", \"sql_unittests\": \"b45de565cabf3f41cf03e4f4dcf579a0121ba3a4\", \"storage_unittests\": \"67981bc27b30bad4adfa37e7b25aeea07c55bbb5\", \"sync_integration_tests\": \"da8d1e9e2c53cf43b4b03243beb3f89c5bd2b8d7\", \"traffic_annotation_auditor_unittests\": \"3f34342a60a5a98b898c5e1a338714f05fa8f51c\", \"ui_base_unittests\": \"83c37ffcf6d48607246f55cee25475860fe18c9e\", \"ui_touch_selection_unittests\": \"8c8966f216ae25e7efe6cb2eec7b4c54465e37ff\", \"unit_tests\": \"3c544d289a8e6132cfa68e02d639d7239848a53a\", \"updater_tests\": \"8f70a67b20a5d0aceb742a9ef838aba9012bcf07\", \"url_unittests\": \"36ba39195cb043b45bc22d4352af6dfd9e6fb6a6\", \"views_unittests\": \"b29dda1bddc80af50f2a20f82e890dc0d0af41ae\", \"viz_unittests\": \"7f9631b0b9b5f40a8b2ec2ddd26aa893d9c92b0b\", \"vr_common_unittests\": \"ce31351b3ea32a683e30732d910f619ed4ed6ca2\", \"vr_pixeltests\": \"0c8788e623540df23300f7318d0e34c11e0e0a9f\", \"weblayer_browsertests\": \"a6af8c186f4b3b3927aac85a0ee194744160e6e3\", \"weblayer_unittests\": \"5b018efcaf5f355fad031ba40b21dc48b2aff610\", \"wm_unittests\": \"e854899fede55b634836a83dabd7ec241968ce73\", \"wtf_unittests\": \"8665f5b88621aa1f792394146cf17fbc0e80cc64\", \"zlib_unittests\": \"e628db89766469d0cdb585c401b421df55196a2b\", \"zucchini_unittests\": \"cdb5b6a1297b26bd6c43ad609c532815f0456100\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-gpyc\"], \"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": "1608788343705663",
"status": "COMPLETED",
"status_changed_ts": "1608792017223614",
"tags": [
"build_address:luci.chromium.ci/win-asan/16424",
"builder:win-asan",
"buildset:commit/git/8baa5d6f121f1d2f249112da784dd5e5dafdecdc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8baa5d6f121f1d2f249112da784dd5e5dafdecdc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025486934769560896",
"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/8860056872837924080/+/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:50ac137b9053ed10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608792017237143",
"url": "https://ci.chromium.org/b/8860056872837924080",
"utcnow_ts": "1608792018498006"
}
}
[06:40:18.512] Invocation finished in 1h1m19.053352167s with status SUCCEEDED