[12:33:04.338] New invocation is queued and will start shortly
[12:33:05.595] Starting the invocation (attempt 1)
[12:33:05.639] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:33:05.639] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:33:05.639] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030081328328477968",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5584085b3fa204b8601bb0183b3fffd5f4c1354c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030081328328477968",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5584085b3fa204b8601bb0183b3fffd5f4c1354c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5584085b3fa204b8601bb0183b3fffd5f4c1354c",
"gitiles_ref:refs/heads/master"
]
}
[12:33:07.329] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604406785858629",
"id": "8864651266070341920",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5584085b3fa204b8601bb0183b3fffd5f4c1354c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604406787165354",
"tags": [
"build_address:luci.chromium.ci/win-asan/15433",
"builder:win-asan",
"buildset:commit/git/5584085b3fa204b8601bb0183b3fffd5f4c1354c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5584085b3fa204b8601bb0183b3fffd5f4c1354c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030081328328477968",
"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/8864651266070341920/+/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": "1604406787165465",
"url": "https://ci.chromium.org/b/8864651266070341920",
"utcnow_ts": "1604406787321645"
}
}
[12:33:07.329] Task URL: https://ci.chromium.org/b/8864651266070341920
[12:33:07.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:2:0) after 1m0s
[12:33:09.570] Received PubSub notification, asking Buildbucket for the build status
[12:33:09.670] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:07.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:2:0)
[12:34:07.511] Timer tick, asking Buildbucket for the build status
[12:34:07.618] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:07.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:4:0) after 1m0s
[12:35:07.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:4:0)
[12:35:07.726] Timer tick, asking Buildbucket for the build status
[12:35:07.997] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:07.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:5:0) after 1m0s
[12:36:08.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:5:0)
[12:36:08.061] Timer tick, asking Buildbucket for the build status
[12:36:08.261] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:08.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:6:0) after 1m0s
[12:37:08.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:6:0)
[12:37:08.401] Timer tick, asking Buildbucket for the build status
[12:37:08.504] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:08.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:7:0) after 1m0s
[12:38:08.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:7:0)
[12:38:08.601] Timer tick, asking Buildbucket for the build status
[12:38:08.747] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:08.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:8:0) after 1m0s
[12:39:08.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:8:0)
[12:39:08.868] Timer tick, asking Buildbucket for the build status
[12:39:08.991] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:08.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:9:0) after 1m0s
[12:40:09.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:9:0)
[12:40:09.152] Timer tick, asking Buildbucket for the build status
[12:40:09.599] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:09.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:10:0) after 1m0s
[12:41:09.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:10:0)
[12:41:09.685] Timer tick, asking Buildbucket for the build status
[12:41:09.763] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:09.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:11:0) after 1m0s
[12:42:09.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:11:0)
[12:42:09.827] Timer tick, asking Buildbucket for the build status
[12:42:09.921] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:09.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:12:0) after 1m0s
[12:43:10.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:12:0)
[12:43:10.130] Timer tick, asking Buildbucket for the build status
[12:43:10.384] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:10.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:13:0) after 1m0s
[12:44:10.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:13:0)
[12:44:10.435] Timer tick, asking Buildbucket for the build status
[12:44:10.729] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:10.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:14:0) after 1m0s
[12:45:10.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:14:0)
[12:45:10.824] Timer tick, asking Buildbucket for the build status
[12:45:11.045] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:11.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:15:0) after 1m0s
[12:46:11.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:15:0)
[12:46:11.142] Timer tick, asking Buildbucket for the build status
[12:46:11.236] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:11.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:16:0) after 1m0s
[12:47:11.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:16:0)
[12:47:11.255] Timer tick, asking Buildbucket for the build status
[12:47:11.578] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:11.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:17:0) after 1m0s
[12:48:11.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:17:0)
[12:48:11.649] Timer tick, asking Buildbucket for the build status
[12:48:11.830] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:11.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:18:0) after 1m0s
[12:49:11.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:18:0)
[12:49:11.916] Timer tick, asking Buildbucket for the build status
[12:49:12.374] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:12.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:19:0) after 1m0s
[12:50:12.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:19:0)
[12:50:12.504] Timer tick, asking Buildbucket for the build status
[12:50:13.009] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:13.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:20:0) after 1m0s
[12:51:13.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:20:0)
[12:51:13.006] Timer tick, asking Buildbucket for the build status
[12:51:13.183] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:13.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:21:0) after 1m0s
[12:52:13.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:21:0)
[12:52:13.222] Timer tick, asking Buildbucket for the build status
[12:52:13.585] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:13.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:22:0) after 1m0s
[12:53:13.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:22:0)
[12:53:13.629] Timer tick, asking Buildbucket for the build status
[12:53:13.950] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:13.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:23:0) after 1m0s
[12:54:14.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:23:0)
[12:54:14.117] Timer tick, asking Buildbucket for the build status
[12:54:16.005] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:16.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:24:0) after 1m0s
[12:55:16.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:24:0)
[12:55:16.058] Timer tick, asking Buildbucket for the build status
[12:55:16.207] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:16.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:25:0) after 1m0s
[12:56:16.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:25:0)
[12:56:16.231] Timer tick, asking Buildbucket for the build status
[12:56:16.358] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:16.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:26:0) after 1m0s
[12:57:16.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:26:0)
[12:57:16.387] Timer tick, asking Buildbucket for the build status
[12:57:16.513] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:16.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:27:0) after 1m0s
[12:58:16.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:27:0)
[12:58:16.572] Timer tick, asking Buildbucket for the build status
[12:58:16.813] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:16.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:28:0) after 1m0s
[12:59:16.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:28:0)
[12:59:16.886] Timer tick, asking Buildbucket for the build status
[12:59:17.212] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:17.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:29:0) after 1m0s
[13:00:17.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:29:0)
[13:00:17.273] Timer tick, asking Buildbucket for the build status
[13:00:17.448] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:17.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:30:0) after 1m0s
[13:01:17.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:30:0)
[13:01:17.474] Timer tick, asking Buildbucket for the build status
[13:01:17.627] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:17.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:31:0) after 1m0s
[13:02:17.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:31:0)
[13:02:17.649] Timer tick, asking Buildbucket for the build status
[13:02:17.795] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:17.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:32:0) after 1m0s
[13:03:17.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:32:0)
[13:03:17.825] Timer tick, asking Buildbucket for the build status
[13:03:17.959] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:17.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:33:0) after 1m0s
[13:04:18.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:33:0)
[13:04:18.024] Timer tick, asking Buildbucket for the build status
[13:04:18.415] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:18.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:34:0) after 1m0s
[13:05:18.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:34:0)
[13:05:18.524] Timer tick, asking Buildbucket for the build status
[13:05:18.604] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:18.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:35:0) after 1m0s
[13:06:18.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:35:0)
[13:06:18.657] Timer tick, asking Buildbucket for the build status
[13:06:18.764] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:18.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:36:0) after 1m0s
[13:07:18.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:36:0)
[13:07:18.781] Timer tick, asking Buildbucket for the build status
[13:07:19.163] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:19.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:37:0) after 1m0s
[13:08:19.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:37:0)
[13:08:19.185] Timer tick, asking Buildbucket for the build status
[13:08:19.316] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:19.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:38:0) after 1m0s
[13:09:20.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:38:0)
[13:09:20.174] Timer tick, asking Buildbucket for the build status
[13:09:20.360] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:20.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:39:0) after 1m0s
[13:10:20.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:39:0)
[13:10:20.472] Timer tick, asking Buildbucket for the build status
[13:10:20.569] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:20.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:40:0) after 1m0s
[13:11:20.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:40:0)
[13:11:20.592] Timer tick, asking Buildbucket for the build status
[13:11:20.686] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:20.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:41:0) after 1m0s
[13:12:20.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:41:0)
[13:12:20.761] Timer tick, asking Buildbucket for the build status
[13:12:21.124] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:21.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:42:0) after 1m0s
[13:13:21.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:42:0)
[13:13:21.281] Timer tick, asking Buildbucket for the build status
[13:13:21.477] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:21.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:43:0) after 1m0s
[13:14:21.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:43:0)
[13:14:21.571] Timer tick, asking Buildbucket for the build status
[13:14:21.688] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:21.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:44:0) after 1m0s
[13:15:22.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:44:0)
[13:15:22.355] Timer tick, asking Buildbucket for the build status
[13:15:22.439] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:22.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:45:0) after 1m0s
[13:16:22.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:45:0)
[13:16:22.458] Timer tick, asking Buildbucket for the build status
[13:16:22.570] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:22.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:46:0) after 1m0s
[13:17:22.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:46:0)
[13:17:22.782] Timer tick, asking Buildbucket for the build status
[13:17:22.944] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:22.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:47:0) after 1m0s
[13:18:23.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:47:0)
[13:18:23.180] Timer tick, asking Buildbucket for the build status
[13:18:23.437] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:23.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:48:0) after 1m0s
[13:19:23.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:48:0)
[13:19:23.577] Timer tick, asking Buildbucket for the build status
[13:19:23.726] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:23.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:49:0) after 1m0s
[13:20:23.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:49:0)
[13:20:23.829] Timer tick, asking Buildbucket for the build status
[13:20:24.046] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:24.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:50:0) after 1m0s
[13:21:24.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:50:0)
[13:21:24.126] Timer tick, asking Buildbucket for the build status
[13:21:24.778] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:24.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:51:0) after 1m0s
[13:22:24.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:51:0)
[13:22:24.953] Timer tick, asking Buildbucket for the build status
[13:22:25.329] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:25.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:52:0) after 1m0s
[13:23:25.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:52:0)
[13:23:25.405] Timer tick, asking Buildbucket for the build status
[13:23:25.626] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:25.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:53:0) after 1m0s
[13:24:25.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:53:0)
[13:24:25.665] Timer tick, asking Buildbucket for the build status
[13:24:25.789] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:25.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:54:0) after 1m0s
[13:25:25.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:54:0)
[13:25:25.861] Timer tick, asking Buildbucket for the build status
[13:25:26.021] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:26.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:55:0) after 1m0s
[13:26:27.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:55:0)
[13:26:27.140] Timer tick, asking Buildbucket for the build status
[13:26:27.361] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:27.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:56:0) after 1m0s
[13:27:27.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:56:0)
[13:27:27.688] Timer tick, asking Buildbucket for the build status
[13:27:27.788] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:27.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:57:0) after 1m0s
[13:28:27.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:57:0)
[13:28:27.800] Timer tick, asking Buildbucket for the build status
[13:28:27.948] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:27.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:58:0) after 1m0s
[13:29:28.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:58:0)
[13:29:28.004] Timer tick, asking Buildbucket for the build status
[13:29:28.080] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:28.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:59:0) after 1m0s
[13:30:28.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:59:0)
[13:30:28.123] Timer tick, asking Buildbucket for the build status
[13:30:28.321] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:28.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:60:0) after 1m0s
[13:31:28.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:60:0)
[13:31:28.396] Timer tick, asking Buildbucket for the build status
[13:31:29.233] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:29.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:61:0) after 1m0s
[13:32:29.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:61:0)
[13:32:29.376] Timer tick, asking Buildbucket for the build status
[13:32:29.527] Build 8864651266070341920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:29.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030081328328477968:62:0) after 1m0s
[13:32:56.347] Received PubSub notification, asking Buildbucket for the build status
[13:32:56.462] Build 8864651266070341920: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:32:56.463] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604410374688979",
"created_by": "project:chromium",
"created_ts": "1604406785858629",
"id": "8864651266070341920",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5584085b3fa204b8601bb0183b3fffd5f4c1354c\"}}",
"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\": \"5584085b3fa204b8601bb0183b3fffd5f4c1354c\", \"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\": 15433, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-3\", \"got_angle_revision\": \"ed916ddf61cc0021cebf55fb6901ba71fe9e6a2e\", \"got_dawn_revision\": \"43ef0a365b8443226916c090b88b3a0b09d6df96\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"5584085b3fa204b8601bb0183b3fffd5f4c1354c\", \"got_revision_cp\": \"refs/heads/master@{#823507}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"b5bf82693f85b53ce31846705d323764a582489b\", \"got_v8_revision\": \"e0ff8ffa26e56bf39b22eea7731e1b434d2d7378\", \"got_v8_revision_cp\": \"refs/heads/8.8.219@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5584085b3fa204b8601bb0183b3fffd5f4c1354c\", \"swarm_hashes_refs/heads/master(at){#823507}_without_patch\": {\"absl_hardening_tests\": \"0979e211dff75b2bedbff36038687ed6c868a44f\", \"accessibility_unittests\": \"e0d9718f767383b937fcc28ff0776ee0d5ef7f12\", \"angle_unittests\": \"4773d438f0ac2b7f11b3a820eec42f7dbb081b27\", \"app_shell_unittests\": \"71cbdd8d8a6bf283067050009b115a23add86059\", \"aura_unittests\": \"ff7523d49e2e8ced14ab180d9f5ac41969e8b151\", \"base_unittests\": \"b943c5697e581b32332d02b7a38b66464a8ab28a\", \"base_util_unittests\": \"34d8cb3b0907c95c3ec16fa47e471275f38ec7eb\", \"blink_common_unittests\": \"05c5a74e5f91a7803880058b1dc56200905ce9b8\", \"blink_fuzzer_unittests\": \"c7ad4c5c9fa606f42fee8a56e84f7519cc01a718\", \"blink_heap_unittests\": \"8ab10202da2f404986bfac79092d44ca6555ed91\", \"blink_platform_unittests\": \"b8a809a33386bbdaed2fb110ba5925ab8b6f4098\", \"blink_unittests\": \"f0552d9225b5b0201f38373278ac6d4aefd40195\", \"boringssl_crypto_tests\": \"4188a0deabd2561ec21e35ba72ce5417158ffeea\", \"boringssl_ssl_tests\": \"d220d7e48d053546c8098a82a6c677e97ebd13f9\", \"browser_switcher_bho_unittests\": \"204b9791280c4d4914a6ac8786aef4226cef7937\", \"browser_tests\": \"56add4fbfcd64cca358d5b849185283ad47e2a34\", \"capture_unittests\": \"453749850be9b09c373f5813372536e81b2f89a9\", \"cast_unittests\": \"0011f2c6038f0fd2754b1128b6ac128f62346f38\", \"cc_unittests\": \"861fd0521252ecaa8e684024208bd3b5921f3577\", \"chrome_app_unittests\": \"48a28a689c0c0684c2d12f7a63e0bbff94f413e4\", \"chrome_cleaner_unittests\": \"117752adf0943d7bcd8c7ddeb88e0add0839d216\", \"chrome_elf_unittests\": \"c8d6f6b489f673e3ce93dfe63c4ecb08fe5d0693\", \"chromedriver_unittests\": \"1d1c23f9021c4c44f9f156dc4db8fc117e52f85d\", \"components_browsertests\": \"93a3ad675adc471c1bf6c543bb4f76114b57d3e9\", \"components_unittests\": \"b985be5bce10ff347884ad67fc917e6714b90454\", \"compositor_unittests\": \"6d5b1b6979c7065256ca0d5bb3545162e1fc6b5b\", \"content_browsertests\": \"eb9ae24378ab20cba05e00bf5b8891bc8388e4a4\", \"content_unittests\": \"c434d1ad0109180863e10dedccb841663b5465ca\", \"courgette_unittests\": \"2eb97ce94f62c69ec62970508b0eab41e71fb5d0\", \"crashpad_tests\": \"ac1f05ace3e6cead49d9efeaa7a1702594e39a57\", \"cronet_tests\": \"6fe5c6216f70755f337cbb2528d44a871d0fa735\", \"cronet_unittests\": \"d0ff52fb100f7b0001d4e0fa1782e181d1bd04c5\", \"crypto_unittests\": \"93c3ecca9a65545b4986253cdde82af897ed6998\", \"delayloads_unittests\": \"2b40db15afda709693ed2a5c1ffb2d5783c568ad\", \"device_unittests\": \"a0f84f5f8bfd1b507ab6b0b20c44b77a3b660561\", \"display_unittests\": \"7e9733250e0903610e4d7f4b5d6d5eba835b56c1\", \"elevation_service_unittests\": \"f93382e8b4b3515980245b08277ed298b3aa4a5c\", \"events_unittests\": \"4ce0cf85c620205fb6021af1bfe43a937f96a8d0\", \"extensions_browsertests\": \"e51a6bdd817c425bb28b14205ace00c4fc52cc7f\", \"extensions_unittests\": \"3a307f941ef4784a76e9dd1efc1851f60933c45a\", \"filesystem_service_unittests\": \"03e8c3596ba43f3fa7dec051cf1514262b3ca47a\", \"gcm_unit_tests\": \"1be1d1afdb1a8d0025b5b23e8b7fa71e11e3e947\", \"gcp_unittests\": \"8b01f2f197bd2de1e793b5e478b279f46969953f\", \"gfx_unittests\": \"21c6df237a501a59746fbe5759853f891a4c8075\", \"gin_unittests\": \"95d5188371bf93fade558f20f1f2c753fa7b9e99\", \"google_apis_unittests\": \"2d5899451e73e27c36b9110b20ee9c471b136179\", \"gpu_unittests\": \"bcb2e35b98965d785fd60dec69167fafe438247d\", \"gwp_asan_unittests\": \"0b5a3f75e6c689ab3cf054fb93b981bf7885d739\", \"headless_browsertests\": \"4d968911ce73be62cc944af5721838287650daf2\", \"headless_unittests\": \"df267226cc3e33afc01ad991e93123e529b24a1d\", \"install_static_unittests\": \"29ec5a806c693e9d6d8738a84f7bd9d3100752c1\", \"installer_util_unittests\": \"6761be36039da99162fc384d8edfc1ad26207932\", \"interactive_ui_tests\": \"cbd23fea19bbd6c91fdc48e0d7b6b6f2eb251ef6\", \"ipc_tests\": \"29d91483963d0c303bc27a99fdf4bf5953aafca6\", \"jingle_unittests\": \"06aaa8603b1b79387b37d47cecd26fab699d177a\", \"latency_unittests\": \"ca45fd33a16725bd70c7adf6847440fb5838d3f1\", \"libjingle_xmpp_unittests\": \"a932424e1805bb50a6885586c2eee7e35092a869\", \"liburlpattern_unittests\": \"fbe139c3e4d0a82a33b09f020496b9bfbf60802a\", \"media_blink_unittests\": \"9d6d3fad1895515a7caa927adc9203ee76f562a3\", \"media_unittests\": \"b79b532e49cc38d3c7f614e3994ab31497a24381\", \"message_center_unittests\": \"339e9034e5b9a94024e533aedb10cc0840d4599c\", \"midi_unittests\": \"40d8deb0a61f84d9fe6e2d5444d6f09981cc33a1\", \"mojo_core_unittests\": \"3051f29f90dd6d75c2e1cc9ca7638f4eb89582bd\", \"mojo_unittests\": \"51bc398ba40f3456a4e38c6783ca83a219d24443\", \"nacl_loader_unittests\": \"872c1e2886f2ca3676f60c474a886f441d91766e\", \"native_theme_unittests\": \"f3c1c3d6936cec54920127a42fcb80809fe5bcb5\", \"net_unittests\": \"719d4d106d4abab9fbb5dcfa3436953825ae5dfa\", \"notification_helper_unittests\": \"01e103d3ff4d2d0486ee178ef4fda0acc3114fe6\", \"pdf_unittests\": \"72e01d74fbca13b35f372bbb47bb57dee7fa0067\", \"perfetto_unittests\": \"1824cd7a0b718afc2243dc065ad489f4e0077b7a\", \"ppapi_unittests\": \"6150c7baf6397aa4eb2e7f5787810d02a71e7ada\", \"printing_unittests\": \"5181145d005ee46c10540d6ba6e75536ed6ee35c\", \"remoting_unittests\": \"f378f17794132c38ed1ad9cffcd8423718c01b44\", \"sbox_integration_tests\": \"a16fb5cc19cd926d95ace79c998e51876515c5f9\", \"sbox_unittests\": \"a21b4c8cb1c0eeb88ec2bbb1554908253865a84e\", \"sbox_validation_tests\": \"03888581661332c067847984d48458b77c6c5af5\", \"service_manager_unittests\": \"3439921202a2be99f00369b1bc12321bc49313ee\", \"services_unittests\": \"6d12e494b747befb02425328820adcf54c48b1e6\", \"setup_unittests\": \"d17b0f9b186ccb1833288af93614b06440344252\", \"shell_dialogs_unittests\": \"2e2680b28a85491bfc42507b9c8fc6cee609a65c\", \"skia_unittests\": \"57a0256daf39ac79a61febe573aec52365c8052a\", \"snapshot_unittests\": \"6703ab3bee938bf9d3422990580ee6cea99e7184\", \"sql_unittests\": \"945443c20014d036b56e82ee39b247f562a0ed03\", \"storage_unittests\": \"02dd9ac2541bc33719708dc73af8b5c23452c675\", \"sync_integration_tests\": \"00ceb26222c50f388e8dfa0d72ea5895a581b29f\", \"traffic_annotation_auditor_unittests\": \"dac6d230774a3192ffc9ce952358472e7fdee2d7\", \"ui_base_unittests\": \"77150ee21b05deeff4b31f58aea4b85f8019ffbf\", \"ui_touch_selection_unittests\": \"4fae7678ff825fa9b1104ab8b609747ec13d75e0\", \"unit_tests\": \"c37bbe6e3263eaa4fa5376d5b6434c370a4e22b8\", \"updater_tests\": \"40b4b990a81b8647d4603aed434ef69dedc12546\", \"url_unittests\": \"3beb4107f711afda3a475270bc917040754972e3\", \"views_unittests\": \"c0b89506a24385c75b2890c709bd88dfcc80d1e7\", \"viz_unittests\": \"cc79d7e6d3812adb3e1e3802586bca619c6f8906\", \"vr_common_unittests\": \"f24c3b1d64e58de778ca263502ea65e9394fa38d\", \"vr_pixeltests\": \"065d25956bfd54182080839301589506286db3b5\", \"weblayer_browsertests\": \"f3b2cb3672efbfb86ea102c1434262ad4c64ec23\", \"weblayer_unittests\": \"2921ba8cae62156df16db25c30d1149fa7f6274d\", \"wm_unittests\": \"f8fc862e3f1e9d781322971ed194c677b9aacbca\", \"wtf_unittests\": \"7a30e30f2141b9c71b761509d83b406ead4cb9bb\", \"zlib_unittests\": \"e07821a9877f2b741464b9a344166e0e0b02ec24\", \"zucchini_unittests\": \"34a4a72b7a8fe827830b57d7cdb6715ee8240b02\"}}, \"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": "1604406788323623",
"status": "COMPLETED",
"status_changed_ts": "1604410375220597",
"tags": [
"build_address:luci.chromium.ci/win-asan/15433",
"builder:win-asan",
"buildset:commit/git/5584085b3fa204b8601bb0183b3fffd5f4c1354c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5584085b3fa204b8601bb0183b3fffd5f4c1354c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030081328328477968",
"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/8864651266070341920/+/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:4fa6ea46bf0b7a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604410375263115",
"url": "https://ci.chromium.org/b/8864651266070341920",
"utcnow_ts": "1604410376453526"
}
}
[13:32:56.463] Invocation finished in 59m52.141315514s with status SUCCEEDED