[17:52:32.546] New invocation is queued and will start shortly
[17:52:33.675] Starting the invocation (attempt 1)
[17:52:33.719] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:52:33.719] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:52:33.719] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029880035095534464",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d969fc734ff42299f199943c8dc0661ab7334ba5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029880035095534464",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/d969fc734ff42299f199943c8dc0661ab7334ba5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d969fc734ff42299f199943c8dc0661ab7334ba5",
"gitiles_ref:refs/heads/master"
]
}
[17:52:34.409] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604598753814064",
"id": "8864449973079229056",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d969fc734ff42299f199943c8dc0661ab7334ba5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604598754217797",
"tags": [
"build_address:luci.chromium.ci/win-asan/15482",
"builder:win-asan",
"buildset:commit/git/d969fc734ff42299f199943c8dc0661ab7334ba5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d969fc734ff42299f199943c8dc0661ab7334ba5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029880035095534464",
"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/8864449973079229056/+/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": "1604598754217924",
"url": "https://ci.chromium.org/b/8864449973079229056",
"utcnow_ts": "1604598754401758"
}
}
[17:52:34.409] Task URL: https://ci.chromium.org/b/8864449973079229056
[17:52:34.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:2:0) after 1m0s
[17:52:52.776] Received PubSub notification, asking Buildbucket for the build status
[17:52:52.855] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:34.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:2:0)
[17:53:34.480] Timer tick, asking Buildbucket for the build status
[17:53:34.554] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:34.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:4:0) after 1m0s
[17:54:34.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:4:0)
[17:54:34.536] Timer tick, asking Buildbucket for the build status
[17:54:34.640] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:34.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:5:0) after 1m0s
[17:55:34.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:5:0)
[17:55:34.665] Timer tick, asking Buildbucket for the build status
[17:55:34.779] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:34.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:6:0) after 1m0s
[17:56:34.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:6:0)
[17:56:34.828] Timer tick, asking Buildbucket for the build status
[17:56:35.234] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:35.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:7:0) after 1m0s
[17:57:35.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:7:0)
[17:57:35.282] Timer tick, asking Buildbucket for the build status
[17:57:35.415] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:35.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:8:0) after 1m0s
[17:58:35.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:8:0)
[17:58:35.467] Timer tick, asking Buildbucket for the build status
[17:58:35.744] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:35.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:9:0) after 1m0s
[17:59:35.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:9:0)
[17:59:35.761] Timer tick, asking Buildbucket for the build status
[17:59:35.987] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:35.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:10:0) after 1m0s
[18:00:36.030] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:10:0)
[18:00:36.030] Timer tick, asking Buildbucket for the build status
[18:00:36.110] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:36.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:11:0) after 1m0s
[18:01:36.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:11:0)
[18:01:36.139] Timer tick, asking Buildbucket for the build status
[18:01:36.236] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:36.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:12:0) after 1m0s
[18:02:36.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:12:0)
[18:02:36.292] Timer tick, asking Buildbucket for the build status
[18:02:36.721] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:36.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:13:0) after 1m0s
[18:03:36.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:13:0)
[18:03:36.818] Timer tick, asking Buildbucket for the build status
[18:03:37.234] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:37.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:14:0) after 1m0s
[18:04:37.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:14:0)
[18:04:37.330] Timer tick, asking Buildbucket for the build status
[18:04:37.405] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:37.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:15:0) after 1m0s
[18:05:37.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:15:0)
[18:05:37.511] Timer tick, asking Buildbucket for the build status
[18:05:37.992] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:37.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:16:0) after 1m0s
[18:06:38.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:16:0)
[18:06:38.049] Timer tick, asking Buildbucket for the build status
[18:06:38.393] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:38.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:17:0) after 1m0s
[18:07:38.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:17:0)
[18:07:38.459] Timer tick, asking Buildbucket for the build status
[18:07:38.560] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:38.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:18:0) after 1m0s
[18:08:38.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:18:0)
[18:08:38.630] Timer tick, asking Buildbucket for the build status
[18:08:38.815] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:38.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:19:0) after 1m0s
[18:09:38.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:19:0)
[18:09:38.800] Timer tick, asking Buildbucket for the build status
[18:09:38.859] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:38.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:20:0) after 1m0s
[18:10:38.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:20:0)
[18:10:38.945] Timer tick, asking Buildbucket for the build status
[18:10:39.035] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:39.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:21:0) after 1m0s
[18:11:39.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:21:0)
[18:11:39.110] Timer tick, asking Buildbucket for the build status
[18:11:39.243] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:39.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:22:0) after 1m0s
[18:12:39.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:22:0)
[18:12:39.258] Timer tick, asking Buildbucket for the build status
[18:12:39.525] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:39.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:23:0) after 1m0s
[18:13:39.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:23:0)
[18:13:39.549] Timer tick, asking Buildbucket for the build status
[18:13:40.013] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:40.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:24:0) after 1m0s
[18:14:40.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:24:0)
[18:14:40.142] Timer tick, asking Buildbucket for the build status
[18:14:40.309] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:40.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:25:0) after 1m0s
[18:15:40.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:25:0)
[18:15:40.380] Timer tick, asking Buildbucket for the build status
[18:15:40.625] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:40.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:26:0) after 1m0s
[18:16:40.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:26:0)
[18:16:40.707] Timer tick, asking Buildbucket for the build status
[18:16:40.865] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:40.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:27:0) after 1m0s
[18:17:40.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:27:0)
[18:17:40.914] Timer tick, asking Buildbucket for the build status
[18:17:41.523] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:41.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:28:0) after 1m0s
[18:18:41.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:28:0)
[18:18:41.538] Timer tick, asking Buildbucket for the build status
[18:18:41.716] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:41.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:29:0) after 1m0s
[18:19:41.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:29:0)
[18:19:41.768] Timer tick, asking Buildbucket for the build status
[18:19:41.910] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:41.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:30:0) after 1m0s
[18:20:41.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:30:0)
[18:20:41.945] Timer tick, asking Buildbucket for the build status
[18:20:42.187] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:42.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:31:0) after 1m0s
[18:21:42.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:31:0)
[18:21:42.217] Timer tick, asking Buildbucket for the build status
[18:21:42.324] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:42.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:32:0) after 1m0s
[18:22:42.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:32:0)
[18:22:42.375] Timer tick, asking Buildbucket for the build status
[18:22:42.520] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:42.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:33:0) after 1m0s
[18:23:42.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:33:0)
[18:23:42.628] Timer tick, asking Buildbucket for the build status
[18:23:43.235] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:43.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:34:0) after 1m0s
[18:24:43.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:34:0)
[18:24:43.297] Timer tick, asking Buildbucket for the build status
[18:24:43.864] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:43.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:35:0) after 1m0s
[18:25:43.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:35:0)
[18:25:43.930] Timer tick, asking Buildbucket for the build status
[18:25:44.069] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:44.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:36:0) after 1m0s
[18:26:44.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:36:0)
[18:26:44.145] Timer tick, asking Buildbucket for the build status
[18:26:44.255] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:44.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:37:0) after 1m0s
[18:27:44.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:37:0)
[18:27:44.309] Timer tick, asking Buildbucket for the build status
[18:27:44.472] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:44.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:38:0) after 1m0s
[18:28:44.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:38:0)
[18:28:44.493] Timer tick, asking Buildbucket for the build status
[18:28:44.651] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:44.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:39:0) after 1m0s
[18:29:44.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:39:0)
[18:29:44.765] Timer tick, asking Buildbucket for the build status
[18:29:44.862] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:44.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:40:0) after 1m0s
[18:30:44.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:40:0)
[18:30:44.877] Timer tick, asking Buildbucket for the build status
[18:30:45.560] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:45.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:41:0) after 1m0s
[18:31:45.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:41:0)
[18:31:45.742] Timer tick, asking Buildbucket for the build status
[18:31:46.087] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:46.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:42:0) after 1m0s
[18:32:46.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:42:0)
[18:32:46.174] Timer tick, asking Buildbucket for the build status
[18:32:46.428] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:46.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:43:0) after 1m0s
[18:33:46.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:43:0)
[18:33:46.418] Timer tick, asking Buildbucket for the build status
[18:33:46.816] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:46.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:44:0) after 1m0s
[18:34:46.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:44:0)
[18:34:46.845] Timer tick, asking Buildbucket for the build status
[18:34:46.946] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:46.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:45:0) after 1m0s
[18:35:46.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:45:0)
[18:35:46.993] Timer tick, asking Buildbucket for the build status
[18:35:47.221] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:47.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:46:0) after 1m0s
[18:36:47.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:46:0)
[18:36:47.334] Timer tick, asking Buildbucket for the build status
[18:36:47.488] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:47.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:47:0) after 1m0s
[18:37:47.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:47:0)
[18:37:47.549] Timer tick, asking Buildbucket for the build status
[18:37:47.993] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:47.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:48:0) after 1m0s
[18:38:48.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:48:0)
[18:38:48.087] Timer tick, asking Buildbucket for the build status
[18:38:48.164] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:48.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:49:0) after 1m0s
[18:39:48.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:49:0)
[18:39:48.149] Timer tick, asking Buildbucket for the build status
[18:39:48.293] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:48.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:50:0) after 1m0s
[18:40:48.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:50:0)
[18:40:48.309] Timer tick, asking Buildbucket for the build status
[18:40:48.401] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:48.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:51:0) after 1m0s
[18:41:48.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:51:0)
[18:41:48.454] Timer tick, asking Buildbucket for the build status
[18:41:48.591] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:48.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:52:0) after 1m0s
[18:42:48.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:52:0)
[18:42:48.670] Timer tick, asking Buildbucket for the build status
[18:42:48.864] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:48.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:53:0) after 1m0s
[18:43:48.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:53:0)
[18:43:48.903] Timer tick, asking Buildbucket for the build status
[18:43:49.156] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:49.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:54:0) after 1m0s
[18:44:49.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:54:0)
[18:44:49.188] Timer tick, asking Buildbucket for the build status
[18:44:49.454] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:49.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:55:0) after 1m0s
[18:45:49.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:55:0)
[18:45:49.566] Timer tick, asking Buildbucket for the build status
[18:45:49.778] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:49.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:56:0) after 1m0s
[18:46:49.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:56:0)
[18:46:49.805] Timer tick, asking Buildbucket for the build status
[18:46:50.472] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:50.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:57:0) after 1m0s
[18:47:50.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:57:0)
[18:47:50.577] Timer tick, asking Buildbucket for the build status
[18:47:50.701] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:50.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:58:0) after 1m0s
[18:48:50.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:58:0)
[18:48:50.774] Timer tick, asking Buildbucket for the build status
[18:48:50.874] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:50.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:59:0) after 1m0s
[18:49:50.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:59:0)
[18:49:50.973] Timer tick, asking Buildbucket for the build status
[18:49:51.043] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:51.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:60:0) after 1m0s
[18:50:51.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:60:0)
[18:50:51.229] Timer tick, asking Buildbucket for the build status
[18:50:51.314] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:51.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:61:0) after 1m0s
[18:51:51.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:61:0)
[18:51:51.374] Timer tick, asking Buildbucket for the build status
[18:51:51.463] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:51.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:62:0) after 1m0s
[18:52:51.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:62:0)
[18:52:51.503] Timer tick, asking Buildbucket for the build status
[18:52:51.931] Build 8864449973079229056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:51.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029880035095534464:63:0) after 1m0s
[18:53:38.754] Received PubSub notification, asking Buildbucket for the build status
[18:53:38.870] Build 8864449973079229056: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:53:38.870] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604602417416534",
"created_by": "project:chromium",
"created_ts": "1604598753814064",
"id": "8864449973079229056",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d969fc734ff42299f199943c8dc0661ab7334ba5\"}}",
"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\": \"d969fc734ff42299f199943c8dc0661ab7334ba5\", \"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\": 15482, \"clang_revision\": \"llvmorg-12-init-10666-gc9f69ee7-1\", \"got_angle_revision\": \"3942e801a65b780296f526f10704c9668d499942\", \"got_dawn_revision\": \"d42713de7aa3227f1feb405beddac4e37051b679\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"d969fc734ff42299f199943c8dc0661ab7334ba5\", \"got_revision_cp\": \"refs/heads/master@{#824481}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"ad54c71632cc0f18584a7009f1e6fc44f5453bc7\", \"got_v8_revision\": \"937e42d5f246b3d04092869127d2888668de7acd\", \"got_v8_revision_cp\": \"refs/heads/8.8.239@{#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\": \"d969fc734ff42299f199943c8dc0661ab7334ba5\", \"swarm_hashes_refs/heads/master(at){#824481}_without_patch\": {\"absl_hardening_tests\": \"1a58c4de56bad71c0d3a0bafc1bf9570c58f7308\", \"accessibility_unittests\": \"d8b1c66797d8f3efcd9f515322da92cff4bdb185\", \"angle_unittests\": \"abeca24c6b2129afbca0a874343ccbfe01e35d12\", \"app_shell_unittests\": \"cedc8f97aca99f5dac0fbffb1b384f2c0c42f247\", \"aura_unittests\": \"5c2b70bd63f1692e6565b554edbfb9959fb3c242\", \"base_unittests\": \"f4c368fd72283bcfc6375e0405755b8a416b4762\", \"base_util_unittests\": \"7e377a3b9dc48e41524342caed8777bb5e7da0b8\", \"blink_common_unittests\": \"fceb586ccca94b6ffce9a4790add7f61f3f266d1\", \"blink_fuzzer_unittests\": \"1af3dc3ee5c218ee6709d24f806a0a1503279a74\", \"blink_heap_unittests\": \"e7edaac163acf5d796f27f9b9c9f231583e12b55\", \"blink_platform_unittests\": \"d1ea2bb83566ec155c75fe32a2e1405f268054be\", \"blink_unittests\": \"a7700ba37b8c605e013bd294d61cace75af4d010\", \"boringssl_crypto_tests\": \"fc4ff2c3657880717dc15c2650d02c07f5fa2c5b\", \"boringssl_ssl_tests\": \"03bd4226e83c6ce44756e8367df980bc455bf8d6\", \"browser_switcher_bho_unittests\": \"73bb33eda9f990e37aec832b8718de947fbbb2c7\", \"browser_tests\": \"87e612ceb9a33c150c38e96c4dd851f34a5d4f2c\", \"capture_unittests\": \"94ab401acea3bf7666e2c0160e74be9d5cf01a21\", \"cast_unittests\": \"17fcc5c65089ac93ff003634fb6121e1bb1065e0\", \"cc_unittests\": \"55efc94c0834518342ff3ace198c522149dda915\", \"chrome_app_unittests\": \"bda60deee2faaf22237466e4884bee35e4007ce6\", \"chrome_cleaner_unittests\": \"0167b3c1aa6c7bcda9ee6570e2e1124b45afa66c\", \"chrome_elf_unittests\": \"8283287e87656e8146e51a386dc896a51201c183\", \"chromedriver_unittests\": \"eb3ec285570c46db6679900231ee2acef3cc6480\", \"components_browsertests\": \"9825f243ea4e87c5887fe372039cd59d6dc2046b\", \"components_unittests\": \"7cbe8c9db709d7cb8da82ca5e6f06ccf8e3c2079\", \"compositor_unittests\": \"f352076e52b15006543a05e3fd4946e0b8a1e026\", \"content_browsertests\": \"de5a8315213f280e1ef48a30aa94a676fafac8bb\", \"content_unittests\": \"0d07f16fcd08ff16d9ba62ab639307c171f6ee6d\", \"courgette_unittests\": \"794bc3227330654e1497b1e1fcae80fb90fcfbfc\", \"crashpad_tests\": \"ef1ef3886d728804e231ee4ad3091e087857da80\", \"cronet_tests\": \"27f8902ef85fc85f4f1440645958ea4fd857908e\", \"cronet_unittests\": \"a8f6663319076ae88a9b915025818ffadc086d40\", \"crypto_unittests\": \"1982626bd796a926eef5e41c66411b09e5df4aaf\", \"delayloads_unittests\": \"32ad866b5117a62dcfa98462c320448d583cde77\", \"device_unittests\": \"a8d313a88652019140fe1949348307f9179f215f\", \"display_unittests\": \"5d194f5d272a71d092dc4f102359edc8a69d20b3\", \"elevation_service_unittests\": \"dfa05e9f492faeaea57c27e171a7b06ecdef547f\", \"events_unittests\": \"280ef703e36d3c0880aa5a7db6e4892f0c60bd70\", \"extensions_browsertests\": \"fefbd0621b4b2fb36eea49fa7dba2aefa50f2b82\", \"extensions_unittests\": \"460a8867dd45afea84ad344443af9f750326464a\", \"filesystem_service_unittests\": \"72a0305a174ef58e52d494cb17c03e2eab552b84\", \"gcm_unit_tests\": \"5c5d0a881c266fc26a2afa62e0948aa338ef3621\", \"gcp_unittests\": \"ce65043a1ae23ad67cfde398e086b44880a7d63d\", \"gfx_unittests\": \"3db1ce2b27242ba7b24994c2e1031b9ff68c832c\", \"gin_unittests\": \"8395fb9ac6a108adb110b255398c8a3a4bf0eab4\", \"google_apis_unittests\": \"3933adb126ab111f9398d58dfdfb9a21c788cbad\", \"gpu_unittests\": \"b4998cbe36505e584984c0c9ffaa6d727d02555a\", \"gwp_asan_unittests\": \"7a584f516b08e2990645fa4ed314d6d798b83b4a\", \"headless_browsertests\": \"5d6f1751eaf37d25fb3b671bc2210a88241b9174\", \"headless_unittests\": \"c02d5dc4be9abac636961d1d03a679f624326057\", \"install_static_unittests\": \"37fcd91c763a4ace66ee1eb70eeecf3ca360340f\", \"installer_util_unittests\": \"550a068192da4e765510f25b117cd925135067f5\", \"interactive_ui_tests\": \"67d383f28e06a306e3c9344e3104828713d3fd5d\", \"ipc_tests\": \"8c9b1f0569e4a3a3a953b4ea9857c37e78d25348\", \"jingle_unittests\": \"130b8539bf8b312462b0ed6a419efc4839cf1d9d\", \"latency_unittests\": \"713e212d7907c1ee1e79c8b068f7856e0c040cc0\", \"libjingle_xmpp_unittests\": \"769bcd836d4e55e28851cae6779066d3fc82d5d1\", \"liburlpattern_unittests\": \"8d520040fa3e65153661156f61ca1f84b8fd3456\", \"media_blink_unittests\": \"21a74e4f7d688bc4b3202963e751800d87fd0165\", \"media_unittests\": \"38c79ef3d3ac3f738be80d89eda3d85e45eae585\", \"message_center_unittests\": \"2150cf66e79d00f66c1c15d5160f59f8646959ee\", \"midi_unittests\": \"c8656de07dc5772b835bfc2c43e1afdc5a71ad07\", \"mojo_core_unittests\": \"a4c868034691da41ab0ef299834cfea2432db3b1\", \"mojo_unittests\": \"953ebaf9827ae26cf9cc912c0db107ad6e94b126\", \"nacl_loader_unittests\": \"bb81da3a57e27860b2f896226cac994ae418fd38\", \"native_theme_unittests\": \"8d4dc1a6ff590329d29f73cdb4cccb0941e8dd64\", \"net_unittests\": \"0cc3c704ebc5baf5b98ff2842a3bb5a6b6871f7b\", \"notification_helper_unittests\": \"7653296989538c2ec8bbe6fb46084308f696ab84\", \"pdf_unittests\": \"91979ccb13724cc974d1f3cba33866a34dd1c7f7\", \"perfetto_unittests\": \"765753bc09dd162c75af1cf1994f27a07b1aaf42\", \"ppapi_unittests\": \"b4d4de0b5c7f1ccc9d5268f013e925e9ae8dfb56\", \"printing_unittests\": \"38adf5342cbc64dcd1fdcef471808b09253d307e\", \"remoting_unittests\": \"e48253a4711dee082d76e9c2023eca3046d7c04c\", \"sbox_integration_tests\": \"41821c0e4e84bc48d459cab9abd9cb7922520dab\", \"sbox_unittests\": \"c8d9a5f7a293091fc9f326dfab9e78c7be675f27\", \"sbox_validation_tests\": \"61ab5976eae26421b7cda3e0f7290d632881b291\", \"service_manager_unittests\": \"72dfcb1f8a9b36ed317808dd2c028ca44b2bbc38\", \"services_unittests\": \"4b372cd1701e5fb6d163a85ecb16c90ce7090977\", \"setup_unittests\": \"1e049e1b1f5fccbf2efe011ca9033232c4bcafd0\", \"shell_dialogs_unittests\": \"35274cccabe93617b526c48c251bbf69b9d3929f\", \"skia_unittests\": \"2c661356867723e9abbf04fe985b59b114643ce6\", \"snapshot_unittests\": \"7743f4ef246297379877e0fbcdf477903ba900dd\", \"sql_unittests\": \"386dfee6cea04b252c1f0d761274f8a0c041504e\", \"storage_unittests\": \"87113db8c6e39c0e0a9737a0465161b7b3aee3e6\", \"sync_integration_tests\": \"3bc5e60d27ecd57f0c84a7e547fbabe5e140000c\", \"traffic_annotation_auditor_unittests\": \"cde269efb73086987f1b60aa3623b17b55ba9bac\", \"ui_base_unittests\": \"0a7a68e250fa760a83629942928392b5048405ae\", \"ui_touch_selection_unittests\": \"8bace751e4a6c99b504c5ebcd61d2f9eeb931452\", \"unit_tests\": \"e8f7fd396de67e539cce7c7e78bb70a90824a0ac\", \"updater_tests\": \"fcda7bb700548d89e6f7c3f209def7de07456a13\", \"url_unittests\": \"5a9a1c499e60503d6b6c49b92b16de0d25a391de\", \"views_unittests\": \"08f83609efde04d1823de1c9b923627ded988105\", \"viz_unittests\": \"a0df778a01f3fc72ab8a123370427bc0873f9983\", \"vr_common_unittests\": \"df3700cba0c3fb8473ae331cda81c5f502be7f71\", \"vr_pixeltests\": \"7269c80abd2aa1b0c00283df4107c0f5938f49ae\", \"weblayer_browsertests\": \"edc2e09989fa1d9ee362765d3704f337f802b0da\", \"weblayer_unittests\": \"5fecab1a5af4238429d42b3a3bcb42a913dda19d\", \"wm_unittests\": \"ed543b3ccfeb609709e0cb12f6d1e2b368ccce98\", \"wtf_unittests\": \"a0c2f54387f3f3bffdb76945ec3c1e84d561f548\", \"zlib_unittests\": \"8e6c2c9982cb59581d4552955bc53cd5279c5bcf\", \"zucchini_unittests\": \"b28fbb52f9ff906a2e4b536de7487855de4b29d9\"}}, \"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": "1604598758885254",
"status": "COMPLETED",
"status_changed_ts": "1604602418204483",
"tags": [
"build_address:luci.chromium.ci/win-asan/15482",
"builder:win-asan",
"buildset:commit/git/d969fc734ff42299f199943c8dc0661ab7334ba5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d969fc734ff42299f199943c8dc0661ab7334ba5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029880035095534464",
"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/8864449973079229056/+/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:4fb25b7b8f5dce10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604602418212312",
"url": "https://ci.chromium.org/b/8864449973079229056",
"utcnow_ts": "1604602418851980"
}
}
[18:53:38.870] Invocation finished in 1h1m6.363442016s with status SUCCEEDED