[06:38:49.873] New invocation is queued and will start shortly
[06:38:54.312] Starting the invocation (attempt 1)
[06:38:54.341] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:38:54.342] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:38:54.342] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020590933776658560",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5d609b8fb5879a9c5b4e432ec30c2a030dee1153\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020590933776658560",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5d609b8fb5879a9c5b4e432ec30c2a030dee1153",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5d609b8fb5879a9c5b4e432ec30c2a030dee1153",
"gitiles_ref:refs/heads/master"
]
}
[06:38:54.999] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613457534418795",
"id": "8855160868348744160",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5d609b8fb5879a9c5b4e432ec30c2a030dee1153\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613457534797374",
"tags": [
"build_address:luci.chromium.ci/win-asan/17370",
"builder:win-asan",
"buildset:commit/git/5d609b8fb5879a9c5b4e432ec30c2a030dee1153",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5d609b8fb5879a9c5b4e432ec30c2a030dee1153",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020590933776658560",
"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/8855160868348744160/+/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": "1613457534797497",
"url": "https://ci.chromium.org/b/8855160868348744160",
"utcnow_ts": "1613457534985088"
}
}
[06:38:54.999] Task URL: https://ci.chromium.org/b/8855160868348744160
[06:38:54.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:2:0) after 1m0s
[06:38:57.023] Received PubSub notification, asking Buildbucket for the build status
[06:38:57.146] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:55.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:2:0)
[06:39:55.149] Timer tick, asking Buildbucket for the build status
[06:39:55.238] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:55.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:4:0) after 1m0s
[06:40:55.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:4:0)
[06:40:55.403] Timer tick, asking Buildbucket for the build status
[06:40:55.486] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:55.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:5:0) after 1m0s
[06:41:55.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:5:0)
[06:41:55.615] Timer tick, asking Buildbucket for the build status
[06:41:55.688] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:55.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:6:0) after 1m0s
[06:42:55.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:6:0)
[06:42:55.836] Timer tick, asking Buildbucket for the build status
[06:42:55.912] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:55.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:7:0) after 1m0s
[06:43:55.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:7:0)
[06:43:55.978] Timer tick, asking Buildbucket for the build status
[06:43:56.085] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:56.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:8:0) after 1m0s
[06:44:56.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:8:0)
[06:44:56.173] Timer tick, asking Buildbucket for the build status
[06:44:56.253] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:56.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:9:0) after 1m0s
[06:45:56.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:9:0)
[06:45:56.330] Timer tick, asking Buildbucket for the build status
[06:45:56.415] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:56.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:10:0) after 1m0s
[06:46:56.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:10:0)
[06:46:56.504] Timer tick, asking Buildbucket for the build status
[06:46:56.623] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:56.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:11:0) after 1m0s
[06:47:56.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:11:0)
[06:47:56.665] Timer tick, asking Buildbucket for the build status
[06:47:56.746] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:56.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:12:0) after 1m0s
[06:48:56.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:12:0)
[06:48:56.847] Timer tick, asking Buildbucket for the build status
[06:48:56.944] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:56.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:13:0) after 1m0s
[06:49:57.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:13:0)
[06:49:57.145] Timer tick, asking Buildbucket for the build status
[06:49:57.404] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:57.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:14:0) after 1m0s
[06:50:57.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:14:0)
[06:50:57.536] Timer tick, asking Buildbucket for the build status
[06:50:57.652] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:57.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:15:0) after 1m0s
[06:51:57.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:15:0)
[06:51:57.670] Timer tick, asking Buildbucket for the build status
[06:51:57.752] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:57.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:16:0) after 1m0s
[06:52:57.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:16:0)
[06:52:57.833] Timer tick, asking Buildbucket for the build status
[06:52:57.926] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:57.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:17:0) after 1m0s
[06:53:57.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:17:0)
[06:53:57.994] Timer tick, asking Buildbucket for the build status
[06:53:58.183] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:58.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:18:0) after 1m0s
[06:54:58.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:18:0)
[06:54:58.155] Timer tick, asking Buildbucket for the build status
[06:54:58.239] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:58.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:19:0) after 1m0s
[06:55:58.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:19:0)
[06:55:58.340] Timer tick, asking Buildbucket for the build status
[06:55:58.455] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:58.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:20:0) after 1m0s
[06:56:58.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:20:0)
[06:56:58.509] Timer tick, asking Buildbucket for the build status
[06:56:58.602] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:58.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:21:0) after 1m0s
[06:57:58.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:21:0)
[06:57:58.777] Timer tick, asking Buildbucket for the build status
[06:57:59.177] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:59.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:22:0) after 1m0s
[06:58:59.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:22:0)
[06:58:59.263] Timer tick, asking Buildbucket for the build status
[06:58:59.361] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:59.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:23:0) after 1m0s
[06:59:59.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:23:0)
[06:59:59.342] Timer tick, asking Buildbucket for the build status
[06:59:59.437] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:59.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:24:0) after 1m0s
[07:00:59.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:24:0)
[07:00:59.489] Timer tick, asking Buildbucket for the build status
[07:00:59.666] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:59.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:25:0) after 1m0s
[07:01:59.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:25:0)
[07:01:59.691] Timer tick, asking Buildbucket for the build status
[07:01:59.771] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:59.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:26:0) after 1m0s
[07:02:59.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:26:0)
[07:02:59.839] Timer tick, asking Buildbucket for the build status
[07:02:59.941] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:59.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:27:0) after 1m0s
[07:03:59.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:27:0)
[07:03:59.962] Timer tick, asking Buildbucket for the build status
[07:04:00.257] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:00.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:28:0) after 1m0s
[07:05:00.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:28:0)
[07:05:00.277] Timer tick, asking Buildbucket for the build status
[07:05:00.371] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:00.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:29:0) after 1m0s
[07:06:00.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:29:0)
[07:06:00.403] Timer tick, asking Buildbucket for the build status
[07:06:00.513] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:00.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:30:0) after 1m0s
[07:07:00.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:30:0)
[07:07:00.533] Timer tick, asking Buildbucket for the build status
[07:07:00.625] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:00.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:31:0) after 1m0s
[07:08:00.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:31:0)
[07:08:00.649] Timer tick, asking Buildbucket for the build status
[07:08:00.756] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:00.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:32:0) after 1m0s
[07:09:01.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:32:0)
[07:09:01.053] Timer tick, asking Buildbucket for the build status
[07:09:01.385] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:01.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:33:0) after 1m0s
[07:10:02.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:33:0)
[07:10:02.391] Timer tick, asking Buildbucket for the build status
[07:10:02.822] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:02.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:34:0) after 1m0s
[07:11:03.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:34:0)
[07:11:03.492] Timer tick, asking Buildbucket for the build status
[07:11:03.565] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:03.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:35:0) after 1m0s
[07:12:03.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:35:0)
[07:12:03.756] Timer tick, asking Buildbucket for the build status
[07:12:04.115] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:04.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:36:0) after 1m0s
[07:13:04.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:36:0)
[07:13:04.230] Timer tick, asking Buildbucket for the build status
[07:13:04.500] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:04.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:37:0) after 1m0s
[07:14:04.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:37:0)
[07:14:04.620] Timer tick, asking Buildbucket for the build status
[07:14:04.745] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:04.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:38:0) after 1m0s
[07:15:04.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:38:0)
[07:15:04.812] Timer tick, asking Buildbucket for the build status
[07:15:04.924] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:04.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:39:0) after 1m0s
[07:16:04.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:39:0)
[07:16:04.976] Timer tick, asking Buildbucket for the build status
[07:16:05.080] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:05.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:40:0) after 1m0s
[07:17:05.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:40:0)
[07:17:05.125] Timer tick, asking Buildbucket for the build status
[07:17:05.216] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:05.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:41:0) after 1m0s
[07:18:05.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:41:0)
[07:18:05.235] Timer tick, asking Buildbucket for the build status
[07:18:05.323] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:05.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:42:0) after 1m0s
[07:19:05.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:42:0)
[07:19:05.343] Timer tick, asking Buildbucket for the build status
[07:19:05.818] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:05.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:43:0) after 1m0s
[07:20:05.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:43:0)
[07:20:05.839] Timer tick, asking Buildbucket for the build status
[07:20:05.933] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:05.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:44:0) after 1m0s
[07:21:06.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:44:0)
[07:21:06.038] Timer tick, asking Buildbucket for the build status
[07:21:06.263] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:06.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:45:0) after 1m0s
[07:22:06.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:45:0)
[07:22:06.327] Timer tick, asking Buildbucket for the build status
[07:22:06.409] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:06.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:46:0) after 1m0s
[07:23:06.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:46:0)
[07:23:06.437] Timer tick, asking Buildbucket for the build status
[07:23:06.570] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:06.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:47:0) after 1m0s
[07:24:06.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:47:0)
[07:24:06.622] Timer tick, asking Buildbucket for the build status
[07:24:06.738] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:06.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:48:0) after 1m0s
[07:25:06.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:48:0)
[07:25:06.833] Timer tick, asking Buildbucket for the build status
[07:25:06.931] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:06.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:49:0) after 1m0s
[07:26:06.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:49:0)
[07:26:06.991] Timer tick, asking Buildbucket for the build status
[07:26:07.078] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:07.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:50:0) after 1m0s
[07:27:07.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:50:0)
[07:27:07.245] Timer tick, asking Buildbucket for the build status
[07:27:07.380] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:07.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:51:0) after 1m0s
[07:28:07.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:51:0)
[07:28:07.541] Timer tick, asking Buildbucket for the build status
[07:28:07.620] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:07.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:52:0) after 1m0s
[07:29:07.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:52:0)
[07:29:07.808] Timer tick, asking Buildbucket for the build status
[07:29:07.955] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:07.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:53:0) after 1m0s
[07:30:07.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:53:0)
[07:30:07.977] Timer tick, asking Buildbucket for the build status
[07:30:08.068] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:08.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:54:0) after 1m0s
[07:31:10.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:54:0)
[07:31:10.102] Timer tick, asking Buildbucket for the build status
[07:31:10.194] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:10.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:55:0) after 1m0s
[07:32:10.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:55:0)
[07:32:10.959] Timer tick, asking Buildbucket for the build status
[07:32:11.216] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:11.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:56:0) after 1m0s
[07:33:11.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:56:0)
[07:33:11.637] Timer tick, asking Buildbucket for the build status
[07:33:11.827] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:11.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:57:0) after 1m0s
[07:34:12.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:57:0)
[07:34:12.037] Timer tick, asking Buildbucket for the build status
[07:34:12.399] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:12.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:58:0) after 1m0s
[07:35:12.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:58:0)
[07:35:12.484] Timer tick, asking Buildbucket for the build status
[07:35:12.670] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:12.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:59:0) after 1m0s
[07:36:12.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:59:0)
[07:36:12.869] Timer tick, asking Buildbucket for the build status
[07:36:13.145] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:13.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:60:0) after 1m0s
[07:37:13.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:60:0)
[07:37:13.247] Timer tick, asking Buildbucket for the build status
[07:37:13.343] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:13.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:61:0) after 1m0s
[07:38:13.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:61:0)
[07:38:13.396] Timer tick, asking Buildbucket for the build status
[07:38:13.482] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:13.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:62:0) after 1m0s
[07:39:13.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:62:0)
[07:39:13.614] Timer tick, asking Buildbucket for the build status
[07:39:13.755] Build 8855160868348744160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:13.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020590933776658560:63:0) after 1m0s
[07:39:15.563] Received PubSub notification, asking Buildbucket for the build status
[07:39:15.690] Build 8855160868348744160: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:39:15.690] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613461154781011",
"created_by": "project:chromium",
"created_ts": "1613457534418795",
"id": "8855160868348744160",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5d609b8fb5879a9c5b4e432ec30c2a030dee1153\"}}",
"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\": \"5d609b8fb5879a9c5b4e432ec30c2a030dee1153\", \"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-0-6l07\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17370, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-1\", \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"e61c9d86884145733d5763ab23c15bf09acf358c\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"5d609b8fb5879a9c5b4e432ec30c2a030dee1153\", \"got_revision_cp\": \"refs/heads/master@{#854169}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"539ef8e9426e10d7611d433ee60b94de0737344a\", \"got_v8_revision\": \"ebacb645b20531cd78dc088730fb72269822eeb6\", \"got_v8_revision_cp\": \"refs/heads/9.0.172@{#2}\", \"got_webrtc_revision\": \"8408c9938cb543a7cd5e7127927f3b4d0834ad59\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33272}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5d609b8fb5879a9c5b4e432ec30c2a030dee1153\", \"swarm_hashes_refs/heads/master(at){#854169}_without_patch\": {\"absl_hardening_tests\": \"e3b1188b5fad238d6f229bec22f1743b1238d9ff\", \"accessibility_unittests\": \"1ccf25a9850c22dd3139911d22cbc4c82f7832aa\", \"angle_unittests\": \"825cf5d49f56a119d15884eff60eb30d1f3f5fb8\", \"app_shell_unittests\": \"38b7ab3a842610046fc8155625d7d19b5e0d1b38\", \"aura_unittests\": \"918ad5e3112ba28438ffb2fe055018420ffd211a\", \"base_unittests\": \"fb3d7e615d02d5bcf79d0c46a6eb7870495a7a22\", \"base_util_unittests\": \"2cba5f008f6330d2c5c6059f173c454c47e888ea\", \"blink_common_unittests\": \"9c269f36ce28414d32d2b982ffc3596fa1846c2b\", \"blink_fuzzer_unittests\": \"f4ebfb5bad57e90c8f8bc363342e1a7687bd6bcb\", \"blink_heap_unittests\": \"205530a4997f0f3b0cefbd1e29179bece173e9f5\", \"blink_platform_unittests\": \"8ac7ac88b9b74ad1a0fa08a2cde1c74690b9589d\", \"blink_unittests\": \"3537e0a2a8e13db09226116677dedeec27572e2a\", \"boringssl_crypto_tests\": \"e453d8fb80cee363177f3af7c343f6c3a86b3d13\", \"boringssl_ssl_tests\": \"afa4a155e532b35133044e699839888f5d9bf45d\", \"browser_switcher_bho_unittests\": \"4d769f0b7cd5a4f8a51cf51be4378f0de55d1d20\", \"browser_tests\": \"5ddee22b6ee1ae8eb76872f69029002d3c000a34\", \"capture_unittests\": \"38307539d24b126c8d4299cc8faf17999e5c7773\", \"cast_unittests\": \"e7567ac154ccaabd401d59b014adeb1c22a33078\", \"cc_unittests\": \"8491972fbb994f9b2704cae59ab016ad6a616845\", \"chrome_app_unittests\": \"6a1020d776c2885e62dfa82fa0aceb12353cffc4\", \"chrome_cleaner_unittests\": \"f7d0ffcfbc31981b846ff1b13b294b2d366198a7\", \"chrome_elf_unittests\": \"f9988d99fb19f5d5bfb7898c501356c4f332a82f\", \"chromedriver_unittests\": \"4456a49c1d45959a7c0f40ef6f586c7cf4fea86e\", \"components_browsertests\": \"31aa0ac1460227ccb63802f0c9ea55c5a0f13d24\", \"components_unittests\": \"ad879d3b676231de0d7fae841d0f079c17d686b1\", \"compositor_unittests\": \"6339969c912ecab3bab815eda61f45569d9e988b\", \"content_browsertests\": \"4a9cbc09901708cc897d7f4a26f58f00daa10480\", \"content_unittests\": \"c46a3932391971b0fdb83042fe723c989125d95c\", \"courgette_unittests\": \"13418605631c010b5ace8d92ca36d5dc9f1fa485\", \"crashpad_tests\": \"c13ba8aa0d80dbd6fce4a1754159b76557e9f399\", \"cronet_tests\": \"76711b19fdf37e48a1c3fa18021a0e23e16c6d6a\", \"cronet_unittests\": \"cf9f295e690c912b19548511b94760a516ca2268\", \"crypto_unittests\": \"13812472fc3d571411430677e0f45e97d1bc6d50\", \"delayloads_unittests\": \"d100e7be698ae0e30e715de902fb93661c429b1d\", \"device_unittests\": \"5e9504d5f4223ebcadd06224a13da073f377f21c\", \"display_unittests\": \"213de280977e43505ce7aa82e9ff7d169363657e\", \"elevation_service_unittests\": \"87f4294ec25d6ecb15cc7e7e0f74844d4bd87835\", \"events_unittests\": \"d8b6b52a02e5ee02675c3469fdede25f50b5ef9e\", \"extensions_browsertests\": \"dfcae4118d910541d7af26522d12ea81e990bc90\", \"extensions_unittests\": \"a3a35f2c713a9a54e4d0bc44daddb698b5353cbd\", \"filesystem_service_unittests\": \"e6947499d792adcbee96f28fed3801e25da9d4a6\", \"gcm_unit_tests\": \"26f5d82d3c48b5d7ed55c3a4fd390f8310e03761\", \"gcp_unittests\": \"a809940d9db23268958b2dee798d00b93be70e9c\", \"gfx_unittests\": \"24e04a8d47cb8469e7197cb0df82b226d4c4d872\", \"gin_unittests\": \"8962850f51be3f38dd69ca9811c8d90f3f1bdf9b\", \"google_apis_unittests\": \"b59eb8b97ba79eccf1d682317fa51b0dc57c32d8\", \"gpu_unittests\": \"92cf8abd27df7a16a6496322bd2ee431a30cbfcc\", \"gwp_asan_unittests\": \"dbbd3ab0fa39fe49942932361a2ca26e23b0f886\", \"headless_browsertests\": \"859e081f1404fcf9bc1bf285e620d8b60bc42bff\", \"headless_unittests\": \"295a77f31e734770495d518c2b3897ce215a1d6e\", \"install_static_unittests\": \"4674ea72842cb78e98311838e5daffe199a0f25f\", \"installer_util_unittests\": \"6bfde302e6b52b30bdccc62594c8282e082c8435\", \"interactive_ui_tests\": \"a95f72b97439d62cd772857990bd027ccfe7c257\", \"ipc_tests\": \"34bd806c55bdfa9ecde15a181b1845d9799e2603\", \"jingle_unittests\": \"636f46e147ffd4ce3b444151604dba34ddbf1460\", \"latency_unittests\": \"3037e84063b23fbbe6c480b0f88528476f870a54\", \"libjingle_xmpp_unittests\": \"bdb664e4f2dc2c8af3840bacd5c27ceb59e49980\", \"liburlpattern_unittests\": \"e844e823a53d1a78b907d636fdb20126a9af5cfa\", \"media_blink_unittests\": \"99514f1f471fec27ea28c751b66e3a428394df33\", \"media_unittests\": \"4570cbb0a1f7f86d7c3cab5e0852a63c252a525b\", \"message_center_unittests\": \"2c145da3270d120e6052503f788b3b2d95fcde8c\", \"midi_unittests\": \"b078e9b7ae169caee43b6475a27d775d8c143f9c\", \"mojo_core_unittests\": \"8c818f763b75103bddf464ddd44804b3a7d6309a\", \"mojo_unittests\": \"5c915300fa43879fe183ffcd6d514bc6017d7b8f\", \"nacl_loader_unittests\": \"c60d24ccf215b43619712739d5ca109711805352\", \"native_theme_unittests\": \"c087feca606704d23f04785aef4c68b1cf166712\", \"net_unittests\": \"11d1fde15e58cd36b5c063f53e8234898c02f6ae\", \"notification_helper_unittests\": \"7bbb9f7a9e20535aec116d2e8f5d55b94c2506bf\", \"pdf_unittests\": \"fde1b7cb0c4758a78fba8eab9a2c0522e8c1a0d3\", \"perfetto_unittests\": \"39895e2b2700a8648315c2c2826544055acfbbd1\", \"ppapi_unittests\": \"de3598651ef5aa5cebfe0fd15e47cdfd261b831a\", \"printing_unittests\": \"c870eb9e50286c385cd695e26d6647860c7010d0\", \"remoting_unittests\": \"910f25536a56562777f48462eebd124a3087de69\", \"sbox_integration_tests\": \"860de998907bec8da442ece4ad0b0283c51c0dd8\", \"sbox_unittests\": \"630e831656cd65b1214c301b476fdb005e79deb0\", \"sbox_validation_tests\": \"7f7cdb2c92977ba383951e8b9c44295eb5a9260f\", \"service_manager_unittests\": \"cbb3500c51e60652796117746256b2a582ba24bc\", \"services_unittests\": \"ed0a9b5193dde3b0bbf617548cc56d60ed85b039\", \"setup_unittests\": \"b3a355093f980fa515f7fe458d9d6df81601075e\", \"shell_dialogs_unittests\": \"25e695ebb121122cc987f68fff1a8fc455ea1193\", \"skia_unittests\": \"aa39681752e724255a56d8af013c046725ee4c84\", \"snapshot_unittests\": \"e4c478f2107e266315b8a873227dda0a3f0ed7db\", \"sql_unittests\": \"e0cfcc8283db937a9caaed5706adde1a98beca2c\", \"storage_unittests\": \"fa5d9304e45b7302df81ccc28fc49eb3a14753f4\", \"sync_integration_tests\": \"a6c01ffb60c3af7d1ee523377ab307d9080cffec\", \"traffic_annotation_auditor_unittests\": \"7b74dba4503b3763242c069168c0fd0e0de8bafb\", \"ui_base_unittests\": \"312463b5abff1346b7fe3e1a3bacad76f6d6a91b\", \"ui_touch_selection_unittests\": \"bb4d5522692b90e15cabf69c6c620a8934733176\", \"unit_tests\": \"eb7bfbd48b70055409ec68e374ca8101635798d6\", \"updater_tests\": \"124e0fdf9f8da3a75195ec2f86e6accfe4b05edc\", \"url_unittests\": \"9025d78decb97b8a2fd4ab7a8efc534f36ef2607\", \"views_unittests\": \"a62b6338ba41da492c68267d1753e02d3bb111ff\", \"viz_unittests\": \"503c5c029ff98b5dfe567381c13d8c66b73ae745\", \"vr_common_unittests\": \"44d99df9de8d6960a593a9e936009cdeb8e69d3f\", \"vr_pixeltests\": \"bcf2ffc1f2772b92d474d58b44881dff827f37f3\", \"weblayer_browsertests\": \"44edc6437c4114104c900dce8f9fa0ae102d7219\", \"weblayer_unittests\": \"3481604728d8db0452fbfc8ec7eb59ac0e86b2af\", \"wm_unittests\": \"335a0c4f405ee9963626a19a2daf0744496683ed\", \"wtf_unittests\": \"dd6841398ba7cac5e3f9bc160bd1f7bd16cbd96b\", \"zlib_unittests\": \"2ec4857729edb34bc28a3e39ded86866b4a2c7b7\", \"zucchini_unittests\": \"d5fb8bac5ae5af03a2b58b0579838ff6ce2b83cf\"}}, \"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-0-6l07\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5700-b36f18b\"], \"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": "1613457536332045",
"status": "COMPLETED",
"status_changed_ts": "1613461155074339",
"tags": [
"build_address:luci.chromium.ci/win-asan/17370",
"builder:win-asan",
"buildset:commit/git/5d609b8fb5879a9c5b4e432ec30c2a030dee1153",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5d609b8fb5879a9c5b4e432ec30c2a030dee1153",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020590933776658560",
"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/8855160868348744160/+/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:51c261b843656010",
"user_agent:luci-scheduler"
],
"updated_ts": "1613461155078558",
"url": "https://ci.chromium.org/b/8855160868348744160",
"utcnow_ts": "1613461155680907"
}
}
[07:39:15.690] Invocation finished in 1h0m25.83141751s with status SUCCEEDED