[20:22:04.982] New invocation is queued and will start shortly
[20:22:06.295] Starting the invocation (attempt 1)
[20:22:06.338] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:22:06.338] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:22:06.338] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019633169586938432",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b9fbc46588f2d89e3ae2397ca002570b00782108\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019633169586938432",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/b9fbc46588f2d89e3ae2397ca002570b00782108",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9fbc46588f2d89e3ae2397ca002570b00782108",
"gitiles_ref:refs/heads/master"
]
}
[20:22:07.729] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614370926716835",
"id": "8854203107105580496",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b9fbc46588f2d89e3ae2397ca002570b00782108\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614370927522301",
"tags": [
"build_address:luci.chromium.ci/win-asan/17552",
"builder:win-asan",
"buildset:commit/git/b9fbc46588f2d89e3ae2397ca002570b00782108",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9fbc46588f2d89e3ae2397ca002570b00782108",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019633169586938432",
"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/8854203107105580496/+/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": "1614370927522423",
"url": "https://ci.chromium.org/b/8854203107105580496",
"utcnow_ts": "1614370927723801"
}
}
[20:22:07.729] Task URL: https://ci.chromium.org/b/8854203107105580496
[20:22:07.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:2:0) after 1m0s
[20:22:09.989] Received PubSub notification, asking Buildbucket for the build status
[20:22:10.145] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:07.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:2:0)
[20:23:07.919] Timer tick, asking Buildbucket for the build status
[20:23:08.033] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:08.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:4:0) after 1m0s
[20:24:08.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:4:0)
[20:24:08.061] Timer tick, asking Buildbucket for the build status
[20:24:08.231] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:08.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:5:0) after 1m0s
[20:25:08.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:5:0)
[20:25:08.347] Timer tick, asking Buildbucket for the build status
[20:25:08.693] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:08.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:6:0) after 1m0s
[20:26:08.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:6:0)
[20:26:08.704] Timer tick, asking Buildbucket for the build status
[20:26:08.762] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:08.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:7:0) after 1m0s
[20:27:08.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:7:0)
[20:27:08.793] Timer tick, asking Buildbucket for the build status
[20:27:09.013] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:09.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:8:0) after 1m0s
[20:28:09.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:8:0)
[20:28:09.087] Timer tick, asking Buildbucket for the build status
[20:28:09.158] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:09.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:9:0) after 1m0s
[20:29:09.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:9:0)
[20:29:09.137] Timer tick, asking Buildbucket for the build status
[20:29:09.209] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:09.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:10:0) after 1m0s
[20:30:09.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:10:0)
[20:30:09.317] Timer tick, asking Buildbucket for the build status
[20:30:09.434] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:09.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:11:0) after 1m0s
[20:31:09.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:11:0)
[20:31:09.489] Timer tick, asking Buildbucket for the build status
[20:31:09.574] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:09.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:12:0) after 1m0s
[20:32:09.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:12:0)
[20:32:09.623] Timer tick, asking Buildbucket for the build status
[20:32:10.008] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:10.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:13:0) after 1m0s
[20:33:10.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:13:0)
[20:33:10.080] Timer tick, asking Buildbucket for the build status
[20:33:10.379] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:10.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:14:0) after 1m0s
[20:34:10.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:14:0)
[20:34:10.409] Timer tick, asking Buildbucket for the build status
[20:34:10.732] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:10.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:15:0) after 1m0s
[20:35:10.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:15:0)
[20:35:10.802] Timer tick, asking Buildbucket for the build status
[20:35:10.878] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:10.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:16:0) after 1m0s
[20:36:10.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:16:0)
[20:36:10.936] Timer tick, asking Buildbucket for the build status
[20:36:11.020] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:11.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:17:0) after 1m0s
[20:37:11.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:17:0)
[20:37:11.044] Timer tick, asking Buildbucket for the build status
[20:37:11.125] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:11.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:18:0) after 1m0s
[20:38:11.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:18:0)
[20:38:11.207] Timer tick, asking Buildbucket for the build status
[20:38:11.341] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:11.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:19:0) after 1m0s
[20:39:11.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:19:0)
[20:39:11.360] Timer tick, asking Buildbucket for the build status
[20:39:12.075] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:12.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:20:0) after 1m0s
[20:40:12.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:20:0)
[20:40:12.201] Timer tick, asking Buildbucket for the build status
[20:40:12.290] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:12.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:21:0) after 1m0s
[20:41:12.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:21:0)
[20:41:12.423] Timer tick, asking Buildbucket for the build status
[20:41:12.618] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:12.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:22:0) after 1m0s
[20:42:12.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:22:0)
[20:42:12.630] Timer tick, asking Buildbucket for the build status
[20:42:12.935] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:12.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:23:0) after 1m0s
[20:43:12.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:23:0)
[20:43:12.988] Timer tick, asking Buildbucket for the build status
[20:43:13.130] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:13.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:24:0) after 1m0s
[20:44:13.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:24:0)
[20:44:13.119] Timer tick, asking Buildbucket for the build status
[20:44:13.229] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:13.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:25:0) after 1m0s
[20:45:13.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:25:0)
[20:45:13.488] Timer tick, asking Buildbucket for the build status
[20:45:13.567] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:13.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:26:0) after 1m0s
[20:46:13.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:26:0)
[20:46:13.751] Timer tick, asking Buildbucket for the build status
[20:46:13.824] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:13.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:27:0) after 1m0s
[20:47:13.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:27:0)
[20:47:13.893] Timer tick, asking Buildbucket for the build status
[20:47:14.111] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:14.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:28:0) after 1m0s
[20:48:14.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:28:0)
[20:48:14.299] Timer tick, asking Buildbucket for the build status
[20:48:14.677] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:14.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:29:0) after 1m0s
[20:49:14.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:29:0)
[20:49:14.884] Timer tick, asking Buildbucket for the build status
[20:49:14.970] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:14.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:30:0) after 1m0s
[20:50:15.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:30:0)
[20:50:15.373] Timer tick, asking Buildbucket for the build status
[20:50:15.465] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:15.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:31:0) after 1m0s
[20:51:15.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:31:0)
[20:51:15.676] Timer tick, asking Buildbucket for the build status
[20:51:15.838] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:15.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:32:0) after 1m0s
[20:52:15.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:32:0)
[20:52:15.867] Timer tick, asking Buildbucket for the build status
[20:52:15.967] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:15.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:33:0) after 1m0s
[20:53:16.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:33:0)
[20:53:16.078] Timer tick, asking Buildbucket for the build status
[20:53:16.148] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:16.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:34:0) after 1m0s
[20:54:16.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:34:0)
[20:54:16.303] Timer tick, asking Buildbucket for the build status
[20:54:16.559] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:16.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:35:0) after 1m0s
[20:55:16.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:35:0)
[20:55:16.668] Timer tick, asking Buildbucket for the build status
[20:55:16.738] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:16.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:36:0) after 1m0s
[20:56:16.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:36:0)
[20:56:16.800] Timer tick, asking Buildbucket for the build status
[20:56:17.098] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:17.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:37:0) after 1m0s
[20:57:17.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:37:0)
[20:57:17.261] Timer tick, asking Buildbucket for the build status
[20:57:17.634] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:17.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:38:0) after 1m0s
[20:58:17.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:38:0)
[20:58:17.850] Timer tick, asking Buildbucket for the build status
[20:58:17.938] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:17.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:39:0) after 1m0s
[20:59:18.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:39:0)
[20:59:18.018] Timer tick, asking Buildbucket for the build status
[20:59:18.095] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:18.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:40:0) after 1m0s
[21:00:18.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:40:0)
[21:00:18.145] Timer tick, asking Buildbucket for the build status
[21:00:18.523] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:18.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:41:0) after 1m0s
[21:01:18.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:41:0)
[21:01:18.536] Timer tick, asking Buildbucket for the build status
[21:01:18.690] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:18.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:42:0) after 1m0s
[21:02:18.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:42:0)
[21:02:18.732] Timer tick, asking Buildbucket for the build status
[21:02:18.839] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:18.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:43:0) after 1m0s
[21:03:19.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:43:0)
[21:03:19.007] Timer tick, asking Buildbucket for the build status
[21:03:19.214] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:19.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:44:0) after 1m0s
[21:04:19.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:44:0)
[21:04:19.319] Timer tick, asking Buildbucket for the build status
[21:04:19.487] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:19.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:45:0) after 1m0s
[21:05:19.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:45:0)
[21:05:19.525] Timer tick, asking Buildbucket for the build status
[21:05:19.617] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:19.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:46:0) after 1m0s
[21:06:19.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:46:0)
[21:06:19.743] Timer tick, asking Buildbucket for the build status
[21:06:19.828] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:19.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:47:0) after 1m0s
[21:07:19.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:47:0)
[21:07:19.925] Timer tick, asking Buildbucket for the build status
[21:07:20.021] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:20.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:48:0) after 1m0s
[21:08:20.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:48:0)
[21:08:20.086] Timer tick, asking Buildbucket for the build status
[21:08:20.251] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:20.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:49:0) after 1m0s
[21:09:20.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:49:0)
[21:09:20.362] Timer tick, asking Buildbucket for the build status
[21:09:21.218] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:21.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:50:0) after 1m0s
[21:10:21.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:50:0)
[21:10:21.327] Timer tick, asking Buildbucket for the build status
[21:10:21.402] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:21.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:51:0) after 1m0s
[21:11:21.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:51:0)
[21:11:21.429] Timer tick, asking Buildbucket for the build status
[21:11:21.608] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:21.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:52:0) after 1m0s
[21:12:21.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:52:0)
[21:12:21.699] Timer tick, asking Buildbucket for the build status
[21:12:21.793] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:21.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:53:0) after 1m0s
[21:13:21.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:53:0)
[21:13:21.863] Timer tick, asking Buildbucket for the build status
[21:13:22.164] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:22.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:54:0) after 1m0s
[21:14:22.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:54:0)
[21:14:22.277] Timer tick, asking Buildbucket for the build status
[21:14:22.378] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:22.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:55:0) after 1m0s
[21:15:22.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:55:0)
[21:15:22.410] Timer tick, asking Buildbucket for the build status
[21:15:22.507] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:22.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:56:0) after 1m0s
[21:16:22.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:56:0)
[21:16:22.526] Timer tick, asking Buildbucket for the build status
[21:16:22.816] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:22.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:57:0) after 1m0s
[21:17:22.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:57:0)
[21:17:22.928] Timer tick, asking Buildbucket for the build status
[21:17:23.049] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:23.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:58:0) after 1m0s
[21:18:23.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:58:0)
[21:18:23.072] Timer tick, asking Buildbucket for the build status
[21:18:23.168] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:23.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:59:0) after 1m0s
[21:19:23.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:59:0)
[21:19:23.283] Timer tick, asking Buildbucket for the build status
[21:19:23.700] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:23.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:60:0) after 1m0s
[21:20:23.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:60:0)
[21:20:23.779] Timer tick, asking Buildbucket for the build status
[21:20:24.209] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:24.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:61:0) after 1m0s
[21:21:24.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:61:0)
[21:21:24.255] Timer tick, asking Buildbucket for the build status
[21:21:24.349] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:24.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:62:0) after 1m0s
[21:22:24.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:62:0)
[21:22:24.385] Timer tick, asking Buildbucket for the build status
[21:22:24.664] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:24.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:63:0) after 1m0s
[21:23:24.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:63:0)
[21:23:24.699] Timer tick, asking Buildbucket for the build status
[21:23:24.776] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:24.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:64:0) after 1m0s
[21:24:24.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:64:0)
[21:24:24.809] Timer tick, asking Buildbucket for the build status
[21:24:24.898] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:24.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:65:0) after 1m0s
[21:25:24.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:65:0)
[21:25:24.918] Timer tick, asking Buildbucket for the build status
[21:25:25.245] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:25.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:66:0) after 1m0s
[21:26:25.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:66:0)
[21:26:25.292] Timer tick, asking Buildbucket for the build status
[21:26:25.388] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:25.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:67:0) after 1m0s
[21:27:25.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:67:0)
[21:27:25.466] Timer tick, asking Buildbucket for the build status
[21:27:25.559] Build 8854203107105580496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:25.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019633169586938432:68:0) after 1m0s
[21:28:11.486] Received PubSub notification, asking Buildbucket for the build status
[21:28:11.727] Build 8854203107105580496: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:28:11.727] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614374889913206",
"created_by": "project:chromium",
"created_ts": "1614370926716835",
"id": "8854203107105580496",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b9fbc46588f2d89e3ae2397ca002570b00782108\"}}",
"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\": \"b9fbc46588f2d89e3ae2397ca002570b00782108\", \"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-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17552, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"33907625dbcaab89bb7f62abea53f40eb9488754\", \"got_dawn_revision\": \"329c1c98fef964ea532a0579ae6f2553ff3382c5\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"b9fbc46588f2d89e3ae2397ca002570b00782108\", \"got_revision_cp\": \"refs/heads/master@{#858245}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"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\": \"b9fbc46588f2d89e3ae2397ca002570b00782108\", \"swarm_hashes_refs/heads/master(at){#858245}_without_patch\": {\"absl_hardening_tests\": \"22c4ef32ad78da5446eb11905d1993c0417e7eed\", \"accessibility_unittests\": \"dca77c563a9288e55c2b278625c7bab8f036077a\", \"angle_unittests\": \"683d9e866b2dfd6f60ae83d8496de462606ecb00\", \"app_shell_unittests\": \"aed9b1e9c383ec37182c59297cf0c42784698f4c\", \"aura_unittests\": \"7072ffdaed141c7bbf49115b46f705f563b5848c\", \"base_unittests\": \"aef2a88a30801a08fd3f1c52888ce60475dfb081\", \"base_util_unittests\": \"5c5d664f884d27dca7ae902729254b963d484cb0\", \"blink_common_unittests\": \"4418a21c7a32d4203125d4ee4c4a162955127a4d\", \"blink_fuzzer_unittests\": \"a1ecc57ed66f360cd9c1543c5a165db7ea323d6f\", \"blink_heap_unittests\": \"c91c198ec67ef51d6d0833fde2598373e3c15dc5\", \"blink_platform_unittests\": \"0894f9d5f1fe54621b7215c00bad65058edc2b5f\", \"blink_unittests\": \"760901d73495a80286d9d67db5e08b427a35a90d\", \"boringssl_crypto_tests\": \"7109778fe3bf46ef6dfa774d9d9835e80c4f0b8c\", \"boringssl_ssl_tests\": \"ea49708f3da9f8ac0f481bdb9f980a8e168d8df6\", \"browser_switcher_bho_unittests\": \"6be71c67fc664be6b83dac7f83ef0f1f252c3073\", \"browser_tests\": \"ea92fce26c6ada3b62db852149648885a4246aab\", \"capture_unittests\": \"9fc0349d0b69f920d1aa9a5767176628b27f5771\", \"cast_unittests\": \"10bb51461b4410ac17ea1518a6fcb34cdfd82ef6\", \"cc_unittests\": \"d3351e15c951ebaabe6d4c18c290d98e81afe7a5\", \"chrome_app_unittests\": \"325ec99486c0df8948075913b7c55268700b90c2\", \"chrome_cleaner_unittests\": \"66824e0e2907da0f97a852a0f1139e9b14a6193f\", \"chrome_elf_unittests\": \"a8f1f1249f5947e8a26518f3314bcf1a5bcdeaa2\", \"chromedriver_unittests\": \"0c2613afeb074b6c2969b27fbf5b3bd708de4e93\", \"color_unittests\": \"a784918f9ec53553793cd587fc6a056e24549309\", \"components_browsertests\": \"850b6633ec69baaa376348d6db4ecacb32318edc\", \"components_unittests\": \"60c8d7f11958c4bc07b191abb9f329d11f060ba0\", \"compositor_unittests\": \"5ba03242161bdb58e67973423e0761dc848806ec\", \"content_browsertests\": \"6ba1592f860a7ff494b0b8ad66759b4305ff03d9\", \"content_unittests\": \"a95a173af7414d1f3c0b326fd87be1ba1261a87f\", \"courgette_unittests\": \"71824731a73b3376672b4fe332073578414dfabe\", \"crashpad_tests\": \"9d163eb1c75c851c51b15d7e16d5962174c6c31b\", \"cronet_tests\": \"7841c748486b25207a975d5cf9d49baa20d9417f\", \"cronet_unittests\": \"a2477c197c4b00c08fb158fdee6dde0d9c0122fd\", \"crypto_unittests\": \"788bc7590f583350b7a43cdb4902ab096e019e92\", \"delayloads_unittests\": \"444048819e0696dbcd9431d68989349310cfd1ad\", \"device_unittests\": \"fb3dd0e51d29aca4700101246a04db8495b400b8\", \"display_unittests\": \"cbadda9eb156e7c940939a19442dbb245aeaade2\", \"elevation_service_unittests\": \"32c1157d70f91cb2fb45d778f9af4bcc747b9309\", \"events_unittests\": \"20d302e606bab294dfb439a58b1f19b0ccc072e8\", \"extensions_browsertests\": \"49d0d89e18f2d56703db835d62985d34ef95257d\", \"extensions_unittests\": \"0bbe7f871c875e8fee2fdf67acde5d68fd063317\", \"filesystem_service_unittests\": \"dfdd85d2be38c2484d5dc69a829b0953a861b8e9\", \"gcm_unit_tests\": \"e774b68feadfe9341fdec96552f0f5e7f6af3d6b\", \"gcp_unittests\": \"348413133167cc0aa1f7638606a70d70b928afb2\", \"gfx_unittests\": \"6bb370396a7782998d824e1e75b1d0a259bbc0ef\", \"gin_unittests\": \"49ec16f50a792a9a4c65f804ca58aa2fc4796236\", \"google_apis_unittests\": \"2bb29c10a6c050078e70215a4f7b72b8057cb49b\", \"gpu_unittests\": \"6dbd37bf872498c3877d733e0814a14c01176875\", \"gwp_asan_unittests\": \"745f51923ff210837bc774888c9074370d2f24d0\", \"headless_browsertests\": \"69d2d546e51fa84d160dfac66f21f9d7a880e8b1\", \"headless_unittests\": \"275a24abf0b2ad360d3aa3ac58320494b9907ae8\", \"install_static_unittests\": \"e5a1f5f7ddadedbbed483dd188adc0d0172aa7cd\", \"installer_util_unittests\": \"fb2aa7bdf83f1d680add0e3d64c73830bd039596\", \"interactive_ui_tests\": \"a6664625ff620e77e67925ad5e45fc5b4c445033\", \"ipc_tests\": \"c3c0184afeb68fc6622ddbb2105b55e40c584fcc\", \"jingle_unittests\": \"b8860c19c39f28caccfee20cf2c8f025add12dff\", \"latency_unittests\": \"2ad88e151dc55708cde67f7920dcc100f4198964\", \"libjingle_xmpp_unittests\": \"2ee5a8568c7bb86fa027823380eeb7848e0558b4\", \"liburlpattern_unittests\": \"7e3dcfd1a0460c6be5aefecb5c9f0e4ccef7aed0\", \"media_blink_unittests\": \"6d94c922d3211c988516ef1e9136bf559111173f\", \"media_unittests\": \"3be07bb5f6f8f9bf2310477797bb4c6c3fd6241f\", \"message_center_unittests\": \"2bdf9d437b0d1c0cfb0e3dc3a940097ab17e5d1a\", \"midi_unittests\": \"d39ebcaf709f0d9444c94a64d4fa827d974c944e\", \"mojo_core_unittests\": \"7838cb0ba0393697b96eae3bcd77cfde9a7accc4\", \"mojo_unittests\": \"8426e6ad7174d6a0521417e2d212668aa0399e92\", \"nacl_loader_unittests\": \"ef5f33dfa2865bb084fb437405a8475ce6eded9b\", \"native_theme_unittests\": \"9b51e972cc3df2a3e99faebc8ef8335dc39d6eef\", \"net_unittests\": \"1caddae1730b291eb84a596b467ad422e37b256e\", \"notification_helper_unittests\": \"dfcd75680c2f2d36a6f88ffaf1bfa186bf24207b\", \"pdf_unittests\": \"514f81c45d1e9254679ae88435eff56a06bb668b\", \"perfetto_unittests\": \"27ec4d29897faa53a0fb97bd6d3114b3def0fafc\", \"ppapi_unittests\": \"5b47a3650af7089a5cb5f56d9317f28d8b5eb1c4\", \"printing_unittests\": \"d1ccea667acb8e90358eddd4f3f2590d37fcae2b\", \"remoting_unittests\": \"b9f1515a2de1ceaaf2f8d3b6f4dcc96c84fb4212\", \"sbox_integration_tests\": \"c93b33e0f7bcac99abb2c16f08a8e7e38e3bcb02\", \"sbox_unittests\": \"6b17a59e8ed88a43179297c71f14d1c9a7b559dd\", \"sbox_validation_tests\": \"45b615d199e250bbfb089a1d4aada150139c1a5e\", \"service_manager_unittests\": \"937fbdcc6927c87644e0af18cfc6a70926ea43a6\", \"services_unittests\": \"02c74199e60aab0bda339fac0381ca22ed8936a7\", \"setup_unittests\": \"553073ca6103ccb8380a5fe48c8ecb257361a327\", \"shell_dialogs_unittests\": \"5790cd3aea344479006c21e82a65b12a783db3b8\", \"skia_unittests\": \"2dd2ba4db5461208960955d1a62bf690ff87fe35\", \"snapshot_unittests\": \"562d57d87251459bbadb1961f4a751ea960cd947\", \"sql_unittests\": \"0a52efcfc3619c26e690415644d0b47074d8fec2\", \"storage_unittests\": \"d7b29fb2e2d10d521c26a88c1fa15d0f379a2519\", \"sync_integration_tests\": \"1430bb9bf00503beb43dd6c378cfd0da3262be0b\", \"traffic_annotation_auditor_unittests\": \"566cb8beb3409bec15ea5ec1c54432c5fe4c7dfe\", \"ui_base_unittests\": \"b3d788506ebef0231131059d8f0d4cc7ee264d10\", \"ui_touch_selection_unittests\": \"65e9a421fc8fb60b239f98ba0859a8815c934a8e\", \"unit_tests\": \"c7b853090a908452ce236c35c4c4c12bcc759bbc\", \"updater_tests\": \"9b2d90889a37c7f224e19828e7bc25be026eb823\", \"url_unittests\": \"013bbcc291e2fb20dae2aca8727258fd964859ba\", \"views_unittests\": \"9d12b1e2d806a3eccc5b04bc8cce6e71b5e2ceec\", \"viz_unittests\": \"5525d5a0e7cada91dff7f2756c5479cf27a8b83a\", \"vr_common_unittests\": \"b204dcc30b1aa2bb3c1a5c1122a20e4e39f252f5\", \"vr_pixeltests\": \"8f74e8b542c0e35228867222f566527d46ed7ba6\", \"weblayer_browsertests\": \"fcea019156dea49de94c2007173fcbfc33c9add7\", \"weblayer_unittests\": \"513dcf9a80c2f85ba9d6a7ac9aad18b61ee4e496\", \"wm_unittests\": \"68c84912f8fb43e74eaf739e295d6af98d8cf9df\", \"wtf_unittests\": \"d696bcecd208e372e5e573d95c59a2a75a5bdb18\", \"zlib_unittests\": \"304e5304ebb2551374cb7f429c7a799557921172\", \"zucchini_unittests\": \"efcfe88cceff59ac54654106693c71c8b9336237\"}}, \"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-3-xpf4\"], \"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\": [\"5712-339372b\"], \"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": "1614370929188798",
"status": "COMPLETED",
"status_changed_ts": "1614374890336102",
"tags": [
"build_address:luci.chromium.ci/win-asan/17552",
"builder:win-asan",
"buildset:commit/git/b9fbc46588f2d89e3ae2397ca002570b00782108",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9fbc46588f2d89e3ae2397ca002570b00782108",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019633169586938432",
"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/8854203107105580496/+/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:51f8d2fcb5e7be10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614374890340034",
"url": "https://ci.chromium.org/b/8854203107105580496",
"utcnow_ts": "1614374891703780"
}
}
[21:28:11.727] Invocation finished in 1h6m6.759843273s with status SUCCEEDED