[07:03:08.641] New invocation is queued and will start shortly
[07:03:09.755] Starting the invocation (attempt 1)
[07:03:09.790] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:03:09.790] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:03:09.790] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019592837517790528",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"80173c6bebed7f2085e5a7c6f5af7f76c63e79a3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019592837517790528",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/80173c6bebed7f2085e5a7c6f5af7f76c63e79a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/80173c6bebed7f2085e5a7c6f5af7f76c63e79a3",
"gitiles_ref:refs/heads/master"
]
}
[07:03:10.348] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614409389882875",
"id": "8854162775553722496",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"80173c6bebed7f2085e5a7c6f5af7f76c63e79a3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614409390250865",
"tags": [
"build_address:luci.chromium.ci/win-asan/17560",
"builder:win-asan",
"buildset:commit/git/80173c6bebed7f2085e5a7c6f5af7f76c63e79a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/80173c6bebed7f2085e5a7c6f5af7f76c63e79a3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019592837517790528",
"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/8854162775553722496/+/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": "1614409390250962",
"url": "https://ci.chromium.org/b/8854162775553722496",
"utcnow_ts": "1614409390336342"
}
}
[07:03:10.348] Task URL: https://ci.chromium.org/b/8854162775553722496
[07:03:10.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:2:0) after 1m0s
[07:03:12.496] Received PubSub notification, asking Buildbucket for the build status
[07:03:13.561] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:10.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:2:0)
[07:04:10.365] Timer tick, asking Buildbucket for the build status
[07:04:10.430] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:10.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:4:0) after 1m0s
[07:05:10.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:4:0)
[07:05:10.479] Timer tick, asking Buildbucket for the build status
[07:05:10.559] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:10.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:5:0) after 1m0s
[07:06:10.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:5:0)
[07:06:10.718] Timer tick, asking Buildbucket for the build status
[07:06:10.856] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:10.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:6:0) after 1m0s
[07:07:10.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:6:0)
[07:07:10.965] Timer tick, asking Buildbucket for the build status
[07:07:11.032] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:11.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:7:0) after 1m0s
[07:08:11.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:7:0)
[07:08:11.175] Timer tick, asking Buildbucket for the build status
[07:08:11.337] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:11.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:8:0) after 1m0s
[07:09:11.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:8:0)
[07:09:11.462] Timer tick, asking Buildbucket for the build status
[07:09:11.531] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:11.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:9:0) after 1m0s
[07:10:11.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:9:0)
[07:10:11.669] Timer tick, asking Buildbucket for the build status
[07:10:11.737] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:11.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:10:0) after 1m0s
[07:11:11.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:10:0)
[07:11:11.867] Timer tick, asking Buildbucket for the build status
[07:11:11.931] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:11.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:11:0) after 1m0s
[07:12:11.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:11:0)
[07:12:11.958] Timer tick, asking Buildbucket for the build status
[07:12:12.129] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:12.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:12:0) after 1m0s
[07:13:12.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:12:0)
[07:13:12.176] Timer tick, asking Buildbucket for the build status
[07:13:12.314] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:12.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:13:0) after 1m0s
[07:14:12.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:13:0)
[07:14:12.345] Timer tick, asking Buildbucket for the build status
[07:14:12.420] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:12.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:14:0) after 1m0s
[07:15:12.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:14:0)
[07:15:12.514] Timer tick, asking Buildbucket for the build status
[07:15:12.591] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:12.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:15:0) after 1m0s
[07:16:12.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:15:0)
[07:16:12.724] Timer tick, asking Buildbucket for the build status
[07:16:12.850] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:12.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:16:0) after 1m0s
[07:17:12.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:16:0)
[07:17:12.996] Timer tick, asking Buildbucket for the build status
[07:17:13.228] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:13.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:17:0) after 1m0s
[07:18:13.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:17:0)
[07:18:13.272] Timer tick, asking Buildbucket for the build status
[07:18:13.366] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:13.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:18:0) after 1m0s
[07:19:13.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:18:0)
[07:19:13.460] Timer tick, asking Buildbucket for the build status
[07:19:13.558] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:13.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:19:0) after 1m0s
[07:20:13.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:19:0)
[07:20:13.622] Timer tick, asking Buildbucket for the build status
[07:20:13.863] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:13.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:20:0) after 1m0s
[07:21:13.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:20:0)
[07:21:13.900] Timer tick, asking Buildbucket for the build status
[07:21:14.022] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:14.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:21:0) after 1m0s
[07:22:14.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:21:0)
[07:22:14.074] Timer tick, asking Buildbucket for the build status
[07:22:14.145] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:14.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:22:0) after 1m0s
[07:23:14.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:22:0)
[07:23:14.250] Timer tick, asking Buildbucket for the build status
[07:23:14.358] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:14.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:23:0) after 1m0s
[07:24:14.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:23:0)
[07:24:14.405] Timer tick, asking Buildbucket for the build status
[07:24:14.524] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:14.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:24:0) after 1m0s
[07:25:14.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:24:0)
[07:25:14.605] Timer tick, asking Buildbucket for the build status
[07:25:14.864] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:14.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:25:0) after 1m0s
[07:26:14.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:25:0)
[07:26:14.973] Timer tick, asking Buildbucket for the build status
[07:26:15.218] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:15.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:26:0) after 1m0s
[07:27:15.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:26:0)
[07:27:15.322] Timer tick, asking Buildbucket for the build status
[07:27:15.444] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:15.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:27:0) after 1m0s
[07:28:15.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:27:0)
[07:28:15.521] Timer tick, asking Buildbucket for the build status
[07:28:15.612] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:15.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:28:0) after 1m0s
[07:29:15.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:28:0)
[07:29:15.718] Timer tick, asking Buildbucket for the build status
[07:29:15.796] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:15.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:29:0) after 1m0s
[07:30:15.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:29:0)
[07:30:15.835] Timer tick, asking Buildbucket for the build status
[07:30:15.901] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:15.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:30:0) after 1m0s
[07:31:15.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:30:0)
[07:31:15.921] Timer tick, asking Buildbucket for the build status
[07:31:15.999] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:15.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:31:0) after 1m0s
[07:32:16.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:31:0)
[07:32:16.014] Timer tick, asking Buildbucket for the build status
[07:32:16.107] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:16.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:32:0) after 1m0s
[07:33:16.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:32:0)
[07:33:16.123] Timer tick, asking Buildbucket for the build status
[07:33:16.249] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:16.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:33:0) after 1m0s
[07:34:16.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:33:0)
[07:34:16.273] Timer tick, asking Buildbucket for the build status
[07:34:16.413] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:16.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:34:0) after 1m0s
[07:35:16.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:34:0)
[07:35:16.467] Timer tick, asking Buildbucket for the build status
[07:35:16.537] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:16.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:35:0) after 1m0s
[07:36:16.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:35:0)
[07:36:16.552] Timer tick, asking Buildbucket for the build status
[07:36:16.616] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:16.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:36:0) after 1m0s
[07:37:16.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:36:0)
[07:37:16.639] Timer tick, asking Buildbucket for the build status
[07:37:16.719] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:16.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:37:0) after 1m0s
[07:38:16.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:37:0)
[07:38:16.734] Timer tick, asking Buildbucket for the build status
[07:38:16.944] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:16.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:38:0) after 1m0s
[07:39:16.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:38:0)
[07:39:16.942] Timer tick, asking Buildbucket for the build status
[07:39:17.053] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:17.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:39:0) after 1m0s
[07:40:17.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:39:0)
[07:40:17.072] Timer tick, asking Buildbucket for the build status
[07:40:17.161] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:17.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:40:0) after 1m0s
[07:41:17.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:40:0)
[07:41:17.177] Timer tick, asking Buildbucket for the build status
[07:41:17.619] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:17.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:41:0) after 1m0s
[07:42:17.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:41:0)
[07:42:17.635] Timer tick, asking Buildbucket for the build status
[07:42:17.724] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:17.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:42:0) after 1m0s
[07:43:17.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:42:0)
[07:43:17.756] Timer tick, asking Buildbucket for the build status
[07:43:17.842] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:17.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:43:0) after 1m0s
[07:44:17.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:43:0)
[07:44:17.887] Timer tick, asking Buildbucket for the build status
[07:44:17.993] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:17.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:44:0) after 1m0s
[07:45:18.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:44:0)
[07:45:18.044] Timer tick, asking Buildbucket for the build status
[07:45:18.114] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:18.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:45:0) after 1m0s
[07:46:18.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:45:0)
[07:46:18.135] Timer tick, asking Buildbucket for the build status
[07:46:18.250] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:18.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:46:0) after 1m0s
[07:47:18.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:46:0)
[07:47:18.258] Timer tick, asking Buildbucket for the build status
[07:47:18.328] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:18.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:47:0) after 1m0s
[07:48:18.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:47:0)
[07:48:18.378] Timer tick, asking Buildbucket for the build status
[07:48:18.737] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:18.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:48:0) after 1m0s
[07:49:18.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:48:0)
[07:49:18.761] Timer tick, asking Buildbucket for the build status
[07:49:18.867] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:18.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:49:0) after 1m0s
[07:50:18.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:49:0)
[07:50:18.921] Timer tick, asking Buildbucket for the build status
[07:50:19.001] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:19.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:50:0) after 1m0s
[07:51:19.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:50:0)
[07:51:19.024] Timer tick, asking Buildbucket for the build status
[07:51:19.247] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:19.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:51:0) after 1m0s
[07:52:19.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:51:0)
[07:52:19.265] Timer tick, asking Buildbucket for the build status
[07:52:19.353] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:19.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:52:0) after 1m0s
[07:53:19.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:52:0)
[07:53:19.374] Timer tick, asking Buildbucket for the build status
[07:53:19.473] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:19.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:53:0) after 1m0s
[07:54:19.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:53:0)
[07:54:19.502] Timer tick, asking Buildbucket for the build status
[07:54:19.575] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:19.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:54:0) after 1m0s
[07:55:19.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:54:0)
[07:55:19.594] Timer tick, asking Buildbucket for the build status
[07:55:19.759] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:19.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:55:0) after 1m0s
[07:56:19.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:55:0)
[07:56:19.777] Timer tick, asking Buildbucket for the build status
[07:56:19.858] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:19.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:56:0) after 1m0s
[07:57:19.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:56:0)
[07:57:19.877] Timer tick, asking Buildbucket for the build status
[07:57:19.960] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:19.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:57:0) after 1m0s
[07:58:20.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:57:0)
[07:58:20.141] Timer tick, asking Buildbucket for the build status
[07:58:20.246] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:20.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:58:0) after 1m0s
[07:59:20.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:58:0)
[07:59:20.371] Timer tick, asking Buildbucket for the build status
[07:59:20.453] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:20.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:59:0) after 1m0s
[08:00:20.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:59:0)
[08:00:20.522] Timer tick, asking Buildbucket for the build status
[08:00:20.621] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:20.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:60:0) after 1m0s
[08:01:20.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:60:0)
[08:01:20.726] Timer tick, asking Buildbucket for the build status
[08:01:20.848] Build 8854162775553722496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:20.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019592837517790528:61:0) after 1m0s
[08:02:12.064] Received PubSub notification, asking Buildbucket for the build status
[08:02:12.143] Build 8854162775553722496: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:02:12.143] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614412931329849",
"created_by": "project:chromium",
"created_ts": "1614409389882875",
"id": "8854162775553722496",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"80173c6bebed7f2085e5a7c6f5af7f76c63e79a3\"}}",
"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\": \"80173c6bebed7f2085e5a7c6f5af7f76c63e79a3\", \"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\": 17560, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"c508e707f9a18cd20d04a02a4274364835113e95\", \"got_dawn_revision\": \"329c1c98fef964ea532a0579ae6f2553ff3382c5\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"80173c6bebed7f2085e5a7c6f5af7f76c63e79a3\", \"got_revision_cp\": \"refs/heads/master@{#858409}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"45e415150419491d6d12428ba6ee98876a405ba4\", \"got_v8_revision_cp\": \"refs/heads/9.1.1@{#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\": \"80173c6bebed7f2085e5a7c6f5af7f76c63e79a3\", \"swarm_hashes_refs/heads/master(at){#858409}_without_patch\": {\"absl_hardening_tests\": \"f5e1ce4ad7e40415ddea9e8ef815f5b57a0d2c18\", \"accessibility_unittests\": \"dc617f521f311e3f14aaf7ed5af2643b5f28debc\", \"angle_unittests\": \"3f5f6201046f46da609f19d5642dbc427e491c6a\", \"app_shell_unittests\": \"6dd110c934c7b81ef9b34d6cc48424cfae2a9ba6\", \"aura_unittests\": \"f8f49e349442a1d64185fefa132b72488dd0aa89\", \"base_unittests\": \"01853cb85811b8c8365282ed0afb8268d2f22314\", \"base_util_unittests\": \"390d6530e4fa409c433121b77371dd212ad1af41\", \"blink_common_unittests\": \"b6c3573c7c0a906cebd758c793066919006f9141\", \"blink_fuzzer_unittests\": \"953c076ffc2993ba240c35fa7006bcff7c7f398a\", \"blink_heap_unittests\": \"585b23708a59e022b0962a14f529543cb74601ea\", \"blink_platform_unittests\": \"d63bd4120be04368a3c113c4fdbf52c2e899a6e6\", \"blink_unittests\": \"bcbb01e9ffbfcfc8dd87fcff11fb2bee2649999c\", \"boringssl_crypto_tests\": \"528ebea48188e1814a6ee22def74c8231dc74003\", \"boringssl_ssl_tests\": \"d596a7b849e989259aeec311e9cc7773ee3c68b4\", \"browser_switcher_bho_unittests\": \"3dbf17372a522a319ccc3069a550a9967bdaf8cb\", \"browser_tests\": \"7611c46a2991ecfe16f2fc735d58067deae9138b\", \"capture_unittests\": \"9a8aae9a10154b43af556b2fe3cae884c32e7604\", \"cast_unittests\": \"459f189891df4fc71bd0f4bcb0cf07c562258250\", \"cc_unittests\": \"a2f710a28499310e1fd73491ac5de1cf7f566b59\", \"chrome_app_unittests\": \"c4e6c7f88ab3e1dd7d2a32c09d45ce8f6abcd374\", \"chrome_cleaner_unittests\": \"c6eaa8e9965177b955066aacc0ea61f26cca06b8\", \"chrome_elf_unittests\": \"23258da034f5ad6d26d0798f5ce0b6686f793003\", \"chromedriver_unittests\": \"6856ded4dd36aef376006fb4b67a1f8874af49fe\", \"color_unittests\": \"9f2857a8c9aabca5753df9e774773f0c0e9a0628\", \"components_browsertests\": \"b1a8aef56d6358dee60d70fc15e13f969df7a511\", \"components_unittests\": \"8b3e6fd1cd85c639589da8af08c0e31d0f6cea5c\", \"compositor_unittests\": \"fe849ae1ef5665592a45e7e504216edf94617edc\", \"content_browsertests\": \"1d43c97497d175eae2b4fc463e7067065b10bc94\", \"content_unittests\": \"509aa16f53980d912c731de9b89af0735cd9a023\", \"courgette_unittests\": \"59c59bab3c51ca8e94da45218a5dde1d2ef5ab67\", \"crashpad_tests\": \"1b9426b7d4fc6473e0a161573de9a49794f93a07\", \"cronet_tests\": \"02a68f04bebc71f9be3723df8ee374480082e25c\", \"cronet_unittests\": \"61ac55c271dc87a1d6295131cdf78cec207d73ac\", \"crypto_unittests\": \"cddc7164b413377d2e880e5ca5487d9c924cb581\", \"delayloads_unittests\": \"10858016dcc1f4d1a604723b3a1eb6360b7c53ad\", \"device_unittests\": \"ac98c4bc25b1447a979023cddc04f95ac5a0ceb0\", \"display_unittests\": \"f46abbd3e213f03e143f8aae407a5d2c16225b28\", \"elevation_service_unittests\": \"07df0fa274f206c00fd94c9ede9bd02be791c355\", \"events_unittests\": \"af73b5f226fdb494f301f4b553814223ee4cc667\", \"extensions_browsertests\": \"765807b93b4d86449d78a6fe1ce83f9b9812d785\", \"extensions_unittests\": \"6f3fa9f590cf3507f91fd62382ebf071100db4ec\", \"filesystem_service_unittests\": \"3740d7cf7caa91938db85552bf144969e9b0ebc6\", \"gcm_unit_tests\": \"4c85b9c957a2bc0b473906e8eb0175709ea35672\", \"gcp_unittests\": \"c8ec0ae586f7c38c78de1ca6a625396a0cccedf0\", \"gfx_unittests\": \"caa51e27ced7d93c0c4fac8890974f8628b0482a\", \"gin_unittests\": \"15e9e19310b2e9e4d3570b5c40b1de28de387dbf\", \"google_apis_unittests\": \"1bf692dc5b5b90d21e737b6da9b5a155cb791d17\", \"gpu_unittests\": \"0f396855f393a1dc7740b046a31d499390e1dc82\", \"gwp_asan_unittests\": \"6deb2b30fcdd3958858f8ec97653377a5dca3a2f\", \"headless_browsertests\": \"cd0f6669a3921f7a020daf9a570dd4c75b89c413\", \"headless_unittests\": \"89a211fe5218a9852b636a1f4329a971ee67b5c2\", \"install_static_unittests\": \"d91b1ef4641858528f2983787e7107c0e7cb7526\", \"installer_util_unittests\": \"b95877ab65e6f6a69482f24d4e4d0f2b449137c5\", \"interactive_ui_tests\": \"3e06599c707b286065a2ebc95ee16b9a2b9c9f8e\", \"ipc_tests\": \"77eed354d06216f932acb4cf85779c9c037ae581\", \"jingle_unittests\": \"c62ec3844d5daf0a17cb34db1389537a1f5f40f0\", \"latency_unittests\": \"7de82f818606be24697dbc67bb4aa77f45bab4a3\", \"libjingle_xmpp_unittests\": \"fae9c8c71df405a6f926c4fcd79bc03fed54a728\", \"liburlpattern_unittests\": \"978bab83b37b4e12fcc9513bcc904ee465d152ac\", \"media_blink_unittests\": \"227f40a726ffd0dab3a28d579974c838365e4067\", \"media_unittests\": \"edf0394e72d6f0ea5e0c6f4614bfbc69a2485f15\", \"message_center_unittests\": \"dc64644ab54298859fee7b7de2548b8160cdcc44\", \"midi_unittests\": \"9fe42ccfd3f40545fd96d1d656f81f8798aa9b78\", \"mojo_core_unittests\": \"ca9350603b6c3e09a40423b98aecac4bed421aa6\", \"mojo_unittests\": \"b0f65ec26a43f06a0eea9ab963207436079268bb\", \"nacl_loader_unittests\": \"f2d1922e6b3b6bd8ed62001ce07b1b23530400a0\", \"native_theme_unittests\": \"c0710c312ade76fc0d12c146e769cefe67a1b725\", \"net_unittests\": \"b447b1b0d498b5461bbbc80bbb41531f516b1009\", \"notification_helper_unittests\": \"52942c4338318207b62f92ee762e9ab601d02465\", \"pdf_unittests\": \"bd183f5cf4d8929dcae3d1db20d3ecd220270602\", \"perfetto_unittests\": \"05d2c7111aa4ba8c37b26a3013e1090d026aeecf\", \"ppapi_unittests\": \"54493dce73be6778303667113d010dd724e34183\", \"printing_unittests\": \"705373536574919d996d03ccfdd9305ee0dc4062\", \"remoting_unittests\": \"bb1125de80db7b4ef646685dda6727f024706343\", \"sbox_integration_tests\": \"d6a13f3919e3bfcb0c4410a459492d3dad92ca01\", \"sbox_unittests\": \"4f542d709d703943db53b68924d3f0bc0ac786c0\", \"sbox_validation_tests\": \"8d4b53e9f495aa47eae28d07f5ff7c3586da1251\", \"service_manager_unittests\": \"2e721f4dd2fc6681e542539d9ca20d9c5a63e7f7\", \"services_unittests\": \"e2dbee2bcfe9ee584e5e376726755f4e8dbb4194\", \"setup_unittests\": \"887112f0c6b0f5a840053c226c0d6a1b01c11ba8\", \"shell_dialogs_unittests\": \"90a1d7c4b95223765296948f14ffc82c245ed07d\", \"skia_unittests\": \"3075204c19c7cfb90ed9aaca2771568bb37db2b2\", \"snapshot_unittests\": \"018412f868293c95235d5f2ee29ff2b06fa2df73\", \"sql_unittests\": \"42bfde58e860dffbc3c5d69c33dddbdf4ad6b498\", \"storage_unittests\": \"2e8bb7d37a8b34f7511df603aeb8db691fef5dfb\", \"sync_integration_tests\": \"ca5dca8fe3872b9d78495460026a008a7073c71d\", \"traffic_annotation_auditor_unittests\": \"f6e79206220783feb2785c5a84ebdf0e62a3f2c6\", \"ui_base_unittests\": \"f048ffe9ed6d95ee19bee7d3e24ffe5e76a150dd\", \"ui_touch_selection_unittests\": \"96959a1338377be3823a5ff0b8d4ce65293199cf\", \"unit_tests\": \"60896e8fd9327fc99b25444da1246127e4e3cca2\", \"updater_tests\": \"108928d880365e735893ac0370e3a9bbcbdcde58\", \"url_unittests\": \"03fe1428d3355b45ee34acd690eb451fca4c9f58\", \"views_unittests\": \"a1fb18ff5e65cf018afd214bd61c927e5e824838\", \"viz_unittests\": \"f40ff675885ee343ca746e66c5dc7620fa917ccb\", \"vr_common_unittests\": \"ac0e177c7c2b4a04879cd794e0bd14150a2d085f\", \"vr_pixeltests\": \"ff3b2c39f5d9e2a03d2c8484a2b8bee9636ffb4a\", \"weblayer_browsertests\": \"fd4f343324e64cd1acc3807874e75bf1b94814ae\", \"weblayer_unittests\": \"12e60398d0b0e9a55fd81529725aacd17c03b3fd\", \"wm_unittests\": \"0f6b79b98023886cb87e3b7d26ba2a94266dd8ef\", \"wtf_unittests\": \"655a119d3c1e3149fc8573b01bcc51f0ac070577\", \"zlib_unittests\": \"2d4ff3fa2ab1390d2327e6f5a9a147984bfad37e\", \"zucchini_unittests\": \"312d32fda38a462031f96f88a44c6a8f9e23ae22\"}}, \"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": "1614409391948848",
"status": "COMPLETED",
"status_changed_ts": "1614412931579031",
"tags": [
"build_address:luci.chromium.ci/win-asan/17560",
"builder:win-asan",
"buildset:commit/git/80173c6bebed7f2085e5a7c6f5af7f76c63e79a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/80173c6bebed7f2085e5a7c6f5af7f76c63e79a3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019592837517790528",
"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/8854162775553722496/+/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:51fb1de52646e010",
"user_agent:luci-scheduler"
],
"updated_ts": "1614412931582884",
"url": "https://ci.chromium.org/b/8854162775553722496",
"utcnow_ts": "1614412932136148"
}
}
[08:02:12.143] Invocation finished in 59m3.518287946s with status SUCCEEDED