[00:30:46.793] New invocation is queued and will start shortly
[00:30:47.911] Starting the invocation (attempt 1)
[00:30:47.968] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:30:47.968] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:30:47.968] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029945577102865648",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"454cf29b1368087f02bec42c9e782439736e0dcb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029945577102865648",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/454cf29b1368087f02bec42c9e782439736e0dcb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/454cf29b1368087f02bec42c9e782439736e0dcb",
"gitiles_ref:refs/heads/master"
]
}
[00:30:48.872] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604536248089075",
"id": "8864515515081890512",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"454cf29b1368087f02bec42c9e782439736e0dcb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604536248595219",
"tags": [
"build_address:luci.chromium.ci/win-asan/15465",
"builder:win-asan",
"buildset:commit/git/454cf29b1368087f02bec42c9e782439736e0dcb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/454cf29b1368087f02bec42c9e782439736e0dcb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029945577102865648",
"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/8864515515081890512/+/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": "1604536248595379",
"url": "https://ci.chromium.org/b/8864515515081890512",
"utcnow_ts": "1604536248829370"
}
}
[00:30:48.872] Task URL: https://ci.chromium.org/b/8864515515081890512
[00:30:48.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:2:0) after 1m0s
[00:30:50.273] Received PubSub notification, asking Buildbucket for the build status
[00:30:50.552] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:48.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:2:0)
[00:31:48.969] Timer tick, asking Buildbucket for the build status
[00:31:49.095] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:49.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:4:0) after 1m0s
[00:32:49.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:4:0)
[00:32:49.156] Timer tick, asking Buildbucket for the build status
[00:32:49.287] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:49.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:5:0) after 1m0s
[00:33:49.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:5:0)
[00:33:49.384] Timer tick, asking Buildbucket for the build status
[00:33:49.506] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:49.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:6:0) after 1m0s
[00:34:49.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:6:0)
[00:34:49.654] Timer tick, asking Buildbucket for the build status
[00:34:49.768] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:49.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:7:0) after 1m0s
[00:35:49.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:7:0)
[00:35:49.854] Timer tick, asking Buildbucket for the build status
[00:35:50.201] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:50.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:8:0) after 1m0s
[00:36:50.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:8:0)
[00:36:50.216] Timer tick, asking Buildbucket for the build status
[00:36:50.286] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:50.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:9:0) after 1m0s
[00:37:50.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:9:0)
[00:37:50.377] Timer tick, asking Buildbucket for the build status
[00:37:50.452] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:50.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:10:0) after 1m0s
[00:38:50.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:10:0)
[00:38:50.650] Timer tick, asking Buildbucket for the build status
[00:38:50.731] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:50.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:11:0) after 1m0s
[00:39:50.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:11:0)
[00:39:50.964] Timer tick, asking Buildbucket for the build status
[00:39:51.150] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:51.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:12:0) after 1m0s
[00:40:51.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:12:0)
[00:40:51.240] Timer tick, asking Buildbucket for the build status
[00:40:51.353] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:51.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:13:0) after 1m0s
[00:41:51.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:13:0)
[00:41:51.401] Timer tick, asking Buildbucket for the build status
[00:41:51.701] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:51.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:14:0) after 1m0s
[00:42:51.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:14:0)
[00:42:51.829] Timer tick, asking Buildbucket for the build status
[00:42:51.959] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:51.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:15:0) after 1m0s
[00:43:52.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:15:0)
[00:43:52.026] Timer tick, asking Buildbucket for the build status
[00:43:52.312] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:52.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:16:0) after 1m0s
[00:44:52.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:16:0)
[00:44:52.398] Timer tick, asking Buildbucket for the build status
[00:44:52.469] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:52.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:17:0) after 1m0s
[00:45:52.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:17:0)
[00:45:52.505] Timer tick, asking Buildbucket for the build status
[00:45:52.628] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:52.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:18:0) after 1m0s
[00:46:52.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:18:0)
[00:46:52.635] Timer tick, asking Buildbucket for the build status
[00:46:52.766] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:52.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:19:0) after 1m0s
[00:47:52.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:19:0)
[00:47:52.791] Timer tick, asking Buildbucket for the build status
[00:47:53.132] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:53.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:20:0) after 1m0s
[00:48:53.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:20:0)
[00:48:53.193] Timer tick, asking Buildbucket for the build status
[00:48:53.593] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:53.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:21:0) after 1m0s
[00:49:53.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:21:0)
[00:49:53.664] Timer tick, asking Buildbucket for the build status
[00:49:53.886] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:53.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:22:0) after 1m0s
[00:50:53.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:22:0)
[00:50:53.897] Timer tick, asking Buildbucket for the build status
[00:50:54.391] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:54.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:23:0) after 1m0s
[00:51:54.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:23:0)
[00:51:54.468] Timer tick, asking Buildbucket for the build status
[00:51:54.775] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:54.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:24:0) after 1m0s
[00:52:54.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:24:0)
[00:52:54.890] Timer tick, asking Buildbucket for the build status
[00:52:55.249] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:55.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:25:0) after 1m0s
[00:53:55.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:25:0)
[00:53:55.760] Timer tick, asking Buildbucket for the build status
[00:53:55.915] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:55.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:26:0) after 1m0s
[00:54:55.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:26:0)
[00:54:55.953] Timer tick, asking Buildbucket for the build status
[00:54:56.379] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:56.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:27:0) after 1m0s
[00:55:56.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:27:0)
[00:55:56.557] Timer tick, asking Buildbucket for the build status
[00:55:56.675] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:56.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:28:0) after 1m0s
[00:56:56.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:28:0)
[00:56:56.812] Timer tick, asking Buildbucket for the build status
[00:56:57.014] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:57.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:29:0) after 1m0s
[00:57:57.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:29:0)
[00:57:57.124] Timer tick, asking Buildbucket for the build status
[00:57:57.276] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:57.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:30:0) after 1m0s
[00:58:57.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:30:0)
[00:58:57.461] Timer tick, asking Buildbucket for the build status
[00:58:57.582] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:57.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:31:0) after 1m0s
[00:59:57.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:31:0)
[00:59:57.801] Timer tick, asking Buildbucket for the build status
[00:59:58.283] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:58.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:32:0) after 1m0s
[01:00:58.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:32:0)
[01:00:58.403] Timer tick, asking Buildbucket for the build status
[01:00:58.523] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:58.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:33:0) after 1m0s
[01:01:58.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:33:0)
[01:01:58.627] Timer tick, asking Buildbucket for the build status
[01:01:59.646] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:59.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:34:0) after 1m0s
[01:02:59.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:34:0)
[01:02:59.733] Timer tick, asking Buildbucket for the build status
[01:02:59.887] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:59.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:35:0) after 1m0s
[01:03:59.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:35:0)
[01:03:59.933] Timer tick, asking Buildbucket for the build status
[01:04:00.293] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:00.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:36:0) after 1m0s
[01:05:00.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:36:0)
[01:05:00.681] Timer tick, asking Buildbucket for the build status
[01:05:01.241] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:01.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:37:0) after 1m0s
[01:06:01.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:37:0)
[01:06:01.264] Timer tick, asking Buildbucket for the build status
[01:06:01.417] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:01.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:38:0) after 1m0s
[01:07:01.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:38:0)
[01:07:01.930] Timer tick, asking Buildbucket for the build status
[01:07:02.053] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:02.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:39:0) after 1m0s
[01:08:02.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:39:0)
[01:08:02.618] Timer tick, asking Buildbucket for the build status
[01:08:02.819] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:02.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:40:0) after 1m0s
[01:09:02.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:40:0)
[01:09:02.863] Timer tick, asking Buildbucket for the build status
[01:09:03.004] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:03.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:41:0) after 1m0s
[01:10:03.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:41:0)
[01:10:03.065] Timer tick, asking Buildbucket for the build status
[01:10:03.183] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:03.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:42:0) after 1m0s
[01:11:03.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:42:0)
[01:11:03.239] Timer tick, asking Buildbucket for the build status
[01:11:03.599] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:03.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:43:0) after 1m0s
[01:12:03.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:43:0)
[01:12:03.621] Timer tick, asking Buildbucket for the build status
[01:12:03.758] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:03.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:44:0) after 1m0s
[01:13:03.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:44:0)
[01:13:03.844] Timer tick, asking Buildbucket for the build status
[01:13:04.040] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:04.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:45:0) after 1m0s
[01:14:04.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:45:0)
[01:14:04.154] Timer tick, asking Buildbucket for the build status
[01:14:04.265] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:04.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:46:0) after 1m0s
[01:15:04.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:46:0)
[01:15:04.361] Timer tick, asking Buildbucket for the build status
[01:15:05.170] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:05.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:47:0) after 1m0s
[01:16:05.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:47:0)
[01:16:05.232] Timer tick, asking Buildbucket for the build status
[01:16:05.378] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:05.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:48:0) after 1m0s
[01:17:05.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:48:0)
[01:17:05.468] Timer tick, asking Buildbucket for the build status
[01:17:05.597] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:05.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:49:0) after 1m0s
[01:18:06.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:49:0)
[01:18:06.315] Timer tick, asking Buildbucket for the build status
[01:18:06.401] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:06.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:50:0) after 1m0s
[01:19:06.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:50:0)
[01:19:06.499] Timer tick, asking Buildbucket for the build status
[01:19:06.634] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:06.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:51:0) after 1m0s
[01:20:06.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:51:0)
[01:20:06.695] Timer tick, asking Buildbucket for the build status
[01:20:06.814] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:06.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:52:0) after 1m0s
[01:21:07.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:52:0)
[01:21:07.192] Timer tick, asking Buildbucket for the build status
[01:21:07.512] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:07.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:53:0) after 1m0s
[01:22:07.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:53:0)
[01:22:07.713] Timer tick, asking Buildbucket for the build status
[01:22:07.797] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:07.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:54:0) after 1m0s
[01:23:07.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:54:0)
[01:23:07.934] Timer tick, asking Buildbucket for the build status
[01:23:08.038] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:08.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:55:0) after 1m0s
[01:24:08.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:55:0)
[01:24:08.188] Timer tick, asking Buildbucket for the build status
[01:24:08.268] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:08.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:56:0) after 1m0s
[01:25:08.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:56:0)
[01:25:08.443] Timer tick, asking Buildbucket for the build status
[01:25:09.687] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:09.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:57:0) after 1m0s
[01:26:09.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:57:0)
[01:26:09.773] Timer tick, asking Buildbucket for the build status
[01:26:10.063] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:10.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:58:0) after 1m0s
[01:27:10.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:58:0)
[01:27:10.087] Timer tick, asking Buildbucket for the build status
[01:27:10.471] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:10.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:59:0) after 1m0s
[01:28:10.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:59:0)
[01:28:10.669] Timer tick, asking Buildbucket for the build status
[01:28:10.799] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:10.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:60:0) after 1m0s
[01:29:10.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:60:0)
[01:29:10.932] Timer tick, asking Buildbucket for the build status
[01:29:11.115] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:11.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:61:0) after 1m0s
[01:30:11.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:61:0)
[01:30:11.193] Timer tick, asking Buildbucket for the build status
[01:30:11.351] Build 8864515515081890512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:11.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029945577102865648:62:0) after 1m0s
[01:31:06.310] Received PubSub notification, asking Buildbucket for the build status
[01:31:06.731] Build 8864515515081890512: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:31:06.732] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604539864304817",
"created_by": "project:chromium",
"created_ts": "1604536248089075",
"id": "8864515515081890512",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"454cf29b1368087f02bec42c9e782439736e0dcb\"}}",
"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\": \"454cf29b1368087f02bec42c9e782439736e0dcb\", \"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-5-vrjs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15465, \"clang_revision\": \"llvmorg-12-init-10666-gc9f69ee7-1\", \"got_angle_revision\": \"4989667a9c4c8f093a539ac45b9c93798e093890\", \"got_dawn_revision\": \"6564890116fd5629ad76948f12ad158d6f111cc3\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"454cf29b1368087f02bec42c9e782439736e0dcb\", \"got_revision_cp\": \"refs/heads/master@{#824227}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"ad54c71632cc0f18584a7009f1e6fc44f5453bc7\", \"got_v8_revision\": \"7ae0bc9f7258d206e0de93911c5987c3e04979b8\", \"got_v8_revision_cp\": \"refs/heads/8.8.228@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"454cf29b1368087f02bec42c9e782439736e0dcb\", \"swarm_hashes_refs/heads/master(at){#824227}_without_patch\": {\"absl_hardening_tests\": \"c579cf9eccf1410e2f757b1fcde431fa927e87db\", \"accessibility_unittests\": \"355044b99bc4bf883f60f3a011ea52499f3c7ee0\", \"angle_unittests\": \"4ff5b343c98498761819bcd08a4d280cc52d5f80\", \"app_shell_unittests\": \"6d31d00fc8905f96f99f8950c311dc56d6656ee4\", \"aura_unittests\": \"1b5697c1344ec5bb18e6cf38c62db11587007640\", \"base_unittests\": \"20349b072f72db69909161ff87e164f05dd07000\", \"base_util_unittests\": \"0c0c819cdf4d3759ae64a2ca4b35bb81dd3ccac8\", \"blink_common_unittests\": \"68541e6c5e5a003632eedf20ed737ddc99a6dce8\", \"blink_fuzzer_unittests\": \"a9e5f612f9a6437eb7e8f21dd0d290502c71d8ff\", \"blink_heap_unittests\": \"1ef93e2c806319123ad9c031bfeaf6bf08ac6b3a\", \"blink_platform_unittests\": \"a62afd2ba4ced93f035116c688b6124d95e0c1d4\", \"blink_unittests\": \"cc35488ee28a1404331068d04ce48ed1b7dec55b\", \"boringssl_crypto_tests\": \"ac50efc9f2d8aa35c6c9394bb7416364463b3590\", \"boringssl_ssl_tests\": \"da5a1707c0c714032818baf1d34ef53fd54e3297\", \"browser_switcher_bho_unittests\": \"74c023527e602357538c652a70c8d14c3e1030b1\", \"browser_tests\": \"43e9991f3fc000b616233cacaf88f0280a24e8f9\", \"capture_unittests\": \"04e11a72ab3df229f061d201429cfa452932191c\", \"cast_unittests\": \"5a94d5e4bc9e070bd249e364ff802c9e715bc2d0\", \"cc_unittests\": \"284443f1960767e08abb5960ef665c048944a05e\", \"chrome_app_unittests\": \"254834fbc3f89813e69424f435441a5fbec1678f\", \"chrome_cleaner_unittests\": \"141fdf4344dcedd69f817f27365bc4429e40826d\", \"chrome_elf_unittests\": \"b046bf888bda8d0edb5b5e8ed10a1328c781a22d\", \"chromedriver_unittests\": \"0814138556e6ef1f2a468425a11d9b4c6885f000\", \"components_browsertests\": \"042a557bf1fc652229d178648389efb0bd9168cb\", \"components_unittests\": \"8e469235575ebed519ec7981d977d323b5808916\", \"compositor_unittests\": \"e7d90d8bd3c1dd395b4b7244b1dfccabe4cfcf3e\", \"content_browsertests\": \"d48172bc45f733520b9a6f37592cb37f61dac0d2\", \"content_unittests\": \"61dfb561497549b76985f5233df372f237e708e2\", \"courgette_unittests\": \"d7a82196ce78446f738f0cb51b36f09dc023ad30\", \"crashpad_tests\": \"5bac3dc10a8a7e617f8f7b2519a3e356e5df4542\", \"cronet_tests\": \"169c15f2504aee9cef62813b91875f64f9ffffa6\", \"cronet_unittests\": \"e6f99e221b37749fa97d090c17770eb8307c2fdb\", \"crypto_unittests\": \"61bffc5c07292bc4b68893f407b06409fc3682f7\", \"delayloads_unittests\": \"4951582abe56e690e439b6c3a34ef57ca0848ec2\", \"device_unittests\": \"56ad2e26e2ff6e4d886e3912854214b4e49f2427\", \"display_unittests\": \"6a70ef20e2fd573ef22a43b37048fc71a879138a\", \"elevation_service_unittests\": \"5b97fb2d03b9c9858f6009f8124df243f6639bbf\", \"events_unittests\": \"784ef4ff1cb7d155e677044eb16ec6785e1f161c\", \"extensions_browsertests\": \"813b8d711a5aa7918c57b773da05e28d68a60911\", \"extensions_unittests\": \"6674ebc31a20ec1a1952ff76ed9e8824689226a6\", \"filesystem_service_unittests\": \"f0b56eadf6e5bb3c47bba51628d9b030769d67ab\", \"gcm_unit_tests\": \"7a6c17ca4fb8e2ca7fb07e5820b6b0858e0bc8f5\", \"gcp_unittests\": \"5b7fed001c651991b5942b9850fe18b1e06114af\", \"gfx_unittests\": \"0f3a9302398a977bc5eb5361b2c272e580edd43c\", \"gin_unittests\": \"12d586d0258b4e3866fe3608eacb12deeed82858\", \"google_apis_unittests\": \"b258d14494c6fc1f11947545992aea371302ce62\", \"gpu_unittests\": \"81a1e294853eab36c7a483327fae662f9035ea40\", \"gwp_asan_unittests\": \"bcab3c2fe02f43c50ef17bc364e2d3247b26c396\", \"headless_browsertests\": \"e6abbc1fbb7611fd7f6056e43554ceefde813129\", \"headless_unittests\": \"835d47cf08d4a0100a81704f72386b8ebe7adcb5\", \"install_static_unittests\": \"ccfb50573fc6c0b4517117ef36eb6a74114adc2e\", \"installer_util_unittests\": \"265867261c7239e8baac51b5eee2eed3c98b1dc4\", \"interactive_ui_tests\": \"f995c88f4e1f3e7635ec93130ec89adecbfb5da6\", \"ipc_tests\": \"951dcf3ef37c9b86bc00b206d52a9ed135956295\", \"jingle_unittests\": \"1d2b687e093fb427feb6a29ac6886d7d058c8c32\", \"latency_unittests\": \"ffd2542acd75c9af8ce7222dfbeed91946cecf22\", \"libjingle_xmpp_unittests\": \"4c2a04091b42ffdb4be4f4a2ffbdefd4037abc12\", \"liburlpattern_unittests\": \"b5944dcf0784f865fcfd12a24761a075004738a2\", \"media_blink_unittests\": \"96bed60ee06643630a3f339549712caece02093f\", \"media_unittests\": \"5615ebfcb49410afbf31d47c53e8eec052b99fe0\", \"message_center_unittests\": \"e30f9b736eb7d5de572ece1b4f66a705aca7b039\", \"midi_unittests\": \"a1b2aa71e7703066d1e8977feb8baeb4d3b3b3fc\", \"mojo_core_unittests\": \"b17bf33030a7807c34d467485e1384c275f7926e\", \"mojo_unittests\": \"686a529f5d6e12aa019f60484a83e724f1b86773\", \"nacl_loader_unittests\": \"d22ebea0ed2b3c5986cf4c33ed80afebfb438a46\", \"native_theme_unittests\": \"d912ee3c56c6e6f82c721441ee4ee813902a1f0d\", \"net_unittests\": \"052cc036fdef27073739c335952a5836d1071441\", \"notification_helper_unittests\": \"8d50572e3f20727fdedfa7ff41e89cf0363da9e7\", \"pdf_unittests\": \"def70e25e6e0d0af5f975ba728d71a72e35aeb4b\", \"perfetto_unittests\": \"cf381c31f2e864fb87f92012de2acdfece7ca4c9\", \"ppapi_unittests\": \"fc9482acac3c972dd51db94f2393b6eb94d14f72\", \"printing_unittests\": \"8a10f7bcf9d2438de85bfa62537c3ed707cdc3c3\", \"remoting_unittests\": \"46f6a48851a72aa7a1963b9fdcd704c2138d4938\", \"sbox_integration_tests\": \"510fba7d91280a37e9162b6cff7283723bce9c12\", \"sbox_unittests\": \"5e0505dd87bedb5371338e5c3b0648d6851ad3cd\", \"sbox_validation_tests\": \"58d896eff96535fe20999193167aedb2b7decd64\", \"service_manager_unittests\": \"b0a60e78368623c37c4c17599001931486df480a\", \"services_unittests\": \"483362be31f0cb6911594de2dfd9b7c47d35415b\", \"setup_unittests\": \"9727967a10cf39a075fede6bfebc49aaef811968\", \"shell_dialogs_unittests\": \"ccdfc6d46ccc1ebf62c2cc1c2ca8fc5b5fa80547\", \"skia_unittests\": \"7f09dda39511d6609bfd6a05b850556b39a1a539\", \"snapshot_unittests\": \"edc9d9f7433e3b29f32a523c404f3f4ce7a6019d\", \"sql_unittests\": \"81b8f72e2bfb60a9f9e971ba5d591c9f754f984b\", \"storage_unittests\": \"e6e4a2e61f823b0822b0d725b69b78e4329eb3d6\", \"sync_integration_tests\": \"92a70b09aa7d23add8996fe6cdd362ec444ec8b0\", \"traffic_annotation_auditor_unittests\": \"37310f8a063e9a54974418e31ee8de012b841661\", \"ui_base_unittests\": \"cbefc8651e34ba2e86dc6074e19d4ceae60eeaec\", \"ui_touch_selection_unittests\": \"0379dc2cf80d152afe51f425edb6d272bdd42465\", \"unit_tests\": \"17d9426b430ab5bfdafed1ecf0f3a9d2cd9741ad\", \"updater_tests\": \"a22045cecb9f3a4da5daa00cc73cc7e338db3458\", \"url_unittests\": \"d95b4c729f45e4b7c6db815037f851419f64f2c7\", \"views_unittests\": \"75c112ab0baa35d071ad77a4b83a7339ecd9612d\", \"viz_unittests\": \"3d9866f13375413fd7cc25aa710de1769e4a7ac4\", \"vr_common_unittests\": \"21ba133c1720f82c2b1d730970e9f2d09d39706b\", \"vr_pixeltests\": \"521d45c3a6f74960d6b78df39c0bb8b40c3c0a99\", \"weblayer_browsertests\": \"da46978847c257bb5a93ef37e2b2459f334a168a\", \"weblayer_unittests\": \"4511b1f8d3571ef3472aed7bfa56eb15aaf4d853\", \"wm_unittests\": \"a2848eaa883dfd5b70fbd28fd06ce68711ce4e63\", \"wtf_unittests\": \"727c1806dbd0d720d80bf34ee7e4ff4f6c742926\", \"zlib_unittests\": \"53becd8bc685c18d75288e98730510d1c3af56c2\", \"zucchini_unittests\": \"0b6186f20859a4d2af745d6798dfc9c3ca6dca9e\"}}, \"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-5-vrjs\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604536249338086",
"status": "COMPLETED",
"status_changed_ts": "1604539864750653",
"tags": [
"build_address:luci.chromium.ci/win-asan/15465",
"builder:win-asan",
"buildset:commit/git/454cf29b1368087f02bec42c9e782439736e0dcb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/454cf29b1368087f02bec42c9e782439736e0dcb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029945577102865648",
"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/8864515515081890512/+/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:4faea1b2a2496f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604539864785136",
"url": "https://ci.chromium.org/b/8864515515081890512",
"utcnow_ts": "1604539866681604"
}
}
[01:31:06.732] Invocation finished in 1h0m19.953658885s with status SUCCEEDED