[13:23:13.647] New invocation is queued and will start shortly
[13:23:14.761] Starting the invocation (attempt 1)
[13:23:14.819] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:23:14.819] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:23:14.819] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026997875981788144",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f0ebcd6c25de872bb92c894d9177085431dae642\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026997875981788144",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f0ebcd6c25de872bb92c894d9177085431dae642",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f0ebcd6c25de872bb92c894d9177085431dae642",
"gitiles_ref:refs/heads/master"
]
}
[13:23:15.801] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607347395191932",
"id": "8861567813698423632",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f0ebcd6c25de872bb92c894d9177085431dae642\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607347395675556",
"tags": [
"build_address:luci.chromium.ci/win-asan/16082",
"builder:win-asan",
"buildset:commit/git/f0ebcd6c25de872bb92c894d9177085431dae642",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f0ebcd6c25de872bb92c894d9177085431dae642",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026997875981788144",
"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/8861567813698423632/+/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": "1607347395675778",
"url": "https://ci.chromium.org/b/8861567813698423632",
"utcnow_ts": "1607347395791220"
}
}
[13:23:15.801] Task URL: https://ci.chromium.org/b/8861567813698423632
[13:23:15.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:2:0) after 1m0s
[13:23:19.005] Received PubSub notification, asking Buildbucket for the build status
[13:23:19.161] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:15.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:2:0)
[13:24:15.820] Timer tick, asking Buildbucket for the build status
[13:24:15.999] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:15.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:4:0) after 1m0s
[13:25:16.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:4:0)
[13:25:16.045] Timer tick, asking Buildbucket for the build status
[13:25:16.143] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:16.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:5:0) after 1m0s
[13:26:16.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:5:0)
[13:26:16.169] Timer tick, asking Buildbucket for the build status
[13:26:16.509] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:16.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:6:0) after 1m0s
[13:27:16.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:6:0)
[13:27:16.536] Timer tick, asking Buildbucket for the build status
[13:27:16.816] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:16.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:7:0) after 1m0s
[13:28:16.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:7:0)
[13:28:16.875] Timer tick, asking Buildbucket for the build status
[13:28:17.095] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:17.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:8:0) after 1m0s
[13:29:17.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:8:0)
[13:29:17.207] Timer tick, asking Buildbucket for the build status
[13:29:17.267] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:17.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:9:0) after 1m0s
[13:30:17.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:9:0)
[13:30:17.459] Timer tick, asking Buildbucket for the build status
[13:30:17.655] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:17.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:10:0) after 1m0s
[13:31:17.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:10:0)
[13:31:17.799] Timer tick, asking Buildbucket for the build status
[13:31:17.909] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:17.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:11:0) after 1m0s
[13:32:17.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:11:0)
[13:32:17.929] Timer tick, asking Buildbucket for the build status
[13:32:18.038] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:18.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:12:0) after 1m0s
[13:33:18.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:12:0)
[13:33:18.106] Timer tick, asking Buildbucket for the build status
[13:33:18.206] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:18.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:13:0) after 1m0s
[13:34:18.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:13:0)
[13:34:18.269] Timer tick, asking Buildbucket for the build status
[13:34:18.540] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:18.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:14:0) after 1m0s
[13:35:18.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:14:0)
[13:35:18.596] Timer tick, asking Buildbucket for the build status
[13:35:18.707] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:18.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:15:0) after 1m0s
[13:36:18.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:15:0)
[13:36:18.730] Timer tick, asking Buildbucket for the build status
[13:36:18.951] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:18.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:16:0) after 1m0s
[13:37:18.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:16:0)
[13:37:18.999] Timer tick, asking Buildbucket for the build status
[13:37:19.426] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:19.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:17:0) after 1m0s
[13:38:19.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:17:0)
[13:38:19.519] Timer tick, asking Buildbucket for the build status
[13:38:19.920] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:19.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:18:0) after 1m0s
[13:39:19.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:18:0)
[13:39:19.971] Timer tick, asking Buildbucket for the build status
[13:39:20.243] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:20.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:19:0) after 1m0s
[13:40:20.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:19:0)
[13:40:20.285] Timer tick, asking Buildbucket for the build status
[13:40:20.371] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:20.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:20:0) after 1m0s
[13:41:20.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:20:0)
[13:41:20.504] Timer tick, asking Buildbucket for the build status
[13:41:20.655] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:20.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:21:0) after 1m0s
[13:42:20.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:21:0)
[13:42:20.726] Timer tick, asking Buildbucket for the build status
[13:42:20.836] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:20.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:22:0) after 1m0s
[13:43:20.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:22:0)
[13:43:20.909] Timer tick, asking Buildbucket for the build status
[13:43:21.012] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:21.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:23:0) after 1m0s
[13:44:21.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:23:0)
[13:44:21.086] Timer tick, asking Buildbucket for the build status
[13:44:21.233] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:21.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:24:0) after 1m0s
[13:45:21.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:24:0)
[13:45:21.237] Timer tick, asking Buildbucket for the build status
[13:45:21.374] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:21.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:25:0) after 1m0s
[13:46:21.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:25:0)
[13:46:21.422] Timer tick, asking Buildbucket for the build status
[13:46:21.565] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:21.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:26:0) after 1m0s
[13:47:21.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:26:0)
[13:47:21.591] Timer tick, asking Buildbucket for the build status
[13:47:22.166] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:22.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:27:0) after 1m0s
[13:48:22.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:27:0)
[13:48:22.187] Timer tick, asking Buildbucket for the build status
[13:48:22.325] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:22.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:28:0) after 1m0s
[13:49:22.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:28:0)
[13:49:22.375] Timer tick, asking Buildbucket for the build status
[13:49:22.518] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:22.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:29:0) after 1m0s
[13:50:22.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:29:0)
[13:50:22.592] Timer tick, asking Buildbucket for the build status
[13:50:22.705] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:22.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:30:0) after 1m0s
[13:51:22.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:30:0)
[13:51:22.811] Timer tick, asking Buildbucket for the build status
[13:51:23.088] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:23.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:31:0) after 1m0s
[13:52:23.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:31:0)
[13:52:23.166] Timer tick, asking Buildbucket for the build status
[13:52:23.504] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:23.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:32:0) after 1m0s
[13:53:23.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:32:0)
[13:53:23.665] Timer tick, asking Buildbucket for the build status
[13:53:23.788] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:23.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:33:0) after 1m0s
[13:54:23.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:33:0)
[13:54:23.813] Timer tick, asking Buildbucket for the build status
[13:54:23.903] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:23.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:34:0) after 1m0s
[13:55:24.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:34:0)
[13:55:24.047] Timer tick, asking Buildbucket for the build status
[13:55:24.167] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:24.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:35:0) after 1m0s
[13:56:24.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:35:0)
[13:56:24.247] Timer tick, asking Buildbucket for the build status
[13:56:24.432] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:24.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:36:0) after 1m0s
[13:57:24.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:36:0)
[13:57:24.417] Timer tick, asking Buildbucket for the build status
[13:57:24.497] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:24.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:37:0) after 1m0s
[13:58:24.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:37:0)
[13:58:24.600] Timer tick, asking Buildbucket for the build status
[13:58:24.732] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:24.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:38:0) after 1m0s
[13:59:24.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:38:0)
[13:59:24.824] Timer tick, asking Buildbucket for the build status
[13:59:24.923] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:24.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:39:0) after 1m0s
[14:00:25.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:39:0)
[14:00:25.050] Timer tick, asking Buildbucket for the build status
[14:00:25.209] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:25.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:40:0) after 1m0s
[14:01:25.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:40:0)
[14:01:25.411] Timer tick, asking Buildbucket for the build status
[14:01:25.836] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:25.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:41:0) after 1m0s
[14:02:25.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:41:0)
[14:02:25.911] Timer tick, asking Buildbucket for the build status
[14:02:25.985] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:25.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:42:0) after 1m0s
[14:03:26.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:42:0)
[14:03:26.191] Timer tick, asking Buildbucket for the build status
[14:03:26.331] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:26.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:43:0) after 1m0s
[14:04:26.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:43:0)
[14:04:26.385] Timer tick, asking Buildbucket for the build status
[14:04:26.703] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:26.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:44:0) after 1m0s
[14:05:26.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:44:0)
[14:05:26.969] Timer tick, asking Buildbucket for the build status
[14:05:27.108] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:27.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:45:0) after 1m0s
[14:06:27.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:45:0)
[14:06:27.234] Timer tick, asking Buildbucket for the build status
[14:06:27.392] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:27.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:46:0) after 1m0s
[14:07:27.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:46:0)
[14:07:27.501] Timer tick, asking Buildbucket for the build status
[14:07:27.572] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:27.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:47:0) after 1m0s
[14:08:27.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:47:0)
[14:08:27.642] Timer tick, asking Buildbucket for the build status
[14:08:28.020] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:28.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:48:0) after 1m0s
[14:09:28.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:48:0)
[14:09:28.099] Timer tick, asking Buildbucket for the build status
[14:09:28.174] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:28.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:49:0) after 1m0s
[14:10:28.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:49:0)
[14:10:28.228] Timer tick, asking Buildbucket for the build status
[14:10:28.408] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:28.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:50:0) after 1m0s
[14:11:28.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:50:0)
[14:11:28.596] Timer tick, asking Buildbucket for the build status
[14:11:28.838] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:28.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:51:0) after 1m0s
[14:12:28.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:51:0)
[14:12:28.881] Timer tick, asking Buildbucket for the build status
[14:12:29.054] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:29.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:52:0) after 1m0s
[14:13:29.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:52:0)
[14:13:29.200] Timer tick, asking Buildbucket for the build status
[14:13:29.555] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:29.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:53:0) after 1m0s
[14:14:29.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:53:0)
[14:14:29.533] Timer tick, asking Buildbucket for the build status
[14:14:29.623] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:29.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:54:0) after 1m0s
[14:15:29.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:54:0)
[14:15:29.682] Timer tick, asking Buildbucket for the build status
[14:15:29.796] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:29.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:55:0) after 1m0s
[14:16:30.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:55:0)
[14:16:30.037] Timer tick, asking Buildbucket for the build status
[14:16:30.125] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:30.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:56:0) after 1m0s
[14:17:30.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:56:0)
[14:17:30.328] Timer tick, asking Buildbucket for the build status
[14:17:30.408] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:30.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:57:0) after 1m0s
[14:18:30.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:57:0)
[14:18:30.468] Timer tick, asking Buildbucket for the build status
[14:18:30.608] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:30.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:58:0) after 1m0s
[14:19:30.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:58:0)
[14:19:30.799] Timer tick, asking Buildbucket for the build status
[14:19:30.906] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:30.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:59:0) after 1m0s
[14:20:30.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:59:0)
[14:20:30.935] Timer tick, asking Buildbucket for the build status
[14:20:31.018] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:31.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:60:0) after 1m0s
[14:21:31.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:60:0)
[14:21:31.047] Timer tick, asking Buildbucket for the build status
[14:21:31.996] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:31.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:61:0) after 1m0s
[14:22:32.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:61:0)
[14:22:32.102] Timer tick, asking Buildbucket for the build status
[14:22:32.306] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:32.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:62:0) after 1m0s
[14:23:32.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:62:0)
[14:23:32.365] Timer tick, asking Buildbucket for the build status
[14:23:32.491] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:32.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:63:0) after 1m0s
[14:24:32.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:63:0)
[14:24:32.602] Timer tick, asking Buildbucket for the build status
[14:24:33.684] Build 8861567813698423632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:33.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026997875981788144:64:0) after 1m0s
[14:25:31.465] Received PubSub notification, asking Buildbucket for the build status
[14:25:31.570] Build 8861567813698423632: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:25:31.570] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607351127497640",
"created_by": "project:chromium",
"created_ts": "1607347395191932",
"id": "8861567813698423632",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f0ebcd6c25de872bb92c894d9177085431dae642\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"f0ebcd6c25de872bb92c894d9177085431dae642\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-5-jjte\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16082, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"71bbb3b22e98a6035ef8dd6655b705012630d886\", \"got_dawn_revision\": \"d63d562d1b26e01b369d9a58eddeaccfaa688a02\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"f0ebcd6c25de872bb92c894d9177085431dae642\", \"got_revision_cp\": \"refs/heads/master@{#834177}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"7c84426d2abe231f3087a2942fd831137eb3b0aa\", \"got_v8_revision\": \"61f3e61408129afc0d9e1268d3354a283ea3c881\", \"got_v8_revision_cp\": \"refs/heads/8.9.100@{#1}\", \"got_webrtc_revision\": \"393159147685c9294e423eb8b2bdc92dc78acfd4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32780}\", \"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\": \"f0ebcd6c25de872bb92c894d9177085431dae642\", \"swarm_hashes_refs/heads/master(at){#834177}_without_patch\": {\"absl_hardening_tests\": \"00ba6220386788a668d8b4291f289aaddef3fbdc\", \"accessibility_unittests\": \"6818217e6db16d0b9a1b3a4b186772e9deb3a00d\", \"angle_unittests\": \"0659d0013c4818d423c9466bf01ad539a36f9964\", \"app_shell_unittests\": \"849c9f7403efd141bb2b4a06282d38c7e5dc5abc\", \"aura_unittests\": \"434101aa1910f51b87f10203407203857eeb0772\", \"base_unittests\": \"3d6e04d130ce8a2c2d2986ea2e9239fd2a4176ea\", \"base_util_unittests\": \"3c42ba091a18b9b88663a4ea941c6d371f19bb4a\", \"blink_common_unittests\": \"e606688ae1a0e28e3bf4e73f429b9715271ae861\", \"blink_fuzzer_unittests\": \"c8671797b12b9f9675ccaf16c660f07e2aa9337e\", \"blink_heap_unittests\": \"03c8fd469777c24d40fca0e4eae9dbc46ecaa247\", \"blink_platform_unittests\": \"9740ee0612c1f9b7dc26b2b4be3b76abe2f1997f\", \"blink_unittests\": \"3a20070129e4a52716c775561dcdda9c1b30b686\", \"boringssl_crypto_tests\": \"2c86aeeb2335ed32b3a9269746560fdee7fab4a0\", \"boringssl_ssl_tests\": \"4c04060b734aa2066f6073879cd37b81d6a1cf51\", \"browser_switcher_bho_unittests\": \"b860abe4bce4e13a17767afeba2f29b9c3dd70ea\", \"browser_tests\": \"41f9c6766a5ae863405c154e96a2ca702647e7ea\", \"capture_unittests\": \"30f49417ac660f3ac39e4b6e93ad80963c5faba0\", \"cast_unittests\": \"0a1851ffd909c807504f72dd39b18e79d624685c\", \"cc_unittests\": \"cf24e62f2a27a56f1fa2f5411574735e77838b28\", \"chrome_app_unittests\": \"4a179d2250ca52bc113a0dd0307a9df3d2731a5e\", \"chrome_cleaner_unittests\": \"83d2d5974731585e0abc4f10a16819c4eda8521a\", \"chrome_elf_unittests\": \"cb75c98c93f9d40cf8902a82d706f525a66ffd4a\", \"chromedriver_unittests\": \"42976f4e98bab7c47c6206f49dc70f19a00bc46e\", \"components_browsertests\": \"916713c66674b21d74db6abe7f0f2db1e222d897\", \"components_unittests\": \"46a13795b7c74162daaa9a87d2baa7893ce33c9c\", \"compositor_unittests\": \"9469ab82fd8ae78e403bd2164ebf09bdab112912\", \"content_browsertests\": \"c6b8e83e89040ff930661def8a4b8f75c44faa37\", \"content_unittests\": \"c925df05da7ed34e29b33c8ee06050ea6cf5bb5e\", \"courgette_unittests\": \"6be6d5622283823b83cc1fba0cad52f2eed30d33\", \"crashpad_tests\": \"9cdd6072b7e725a1975d831b5accd819053d62cf\", \"cronet_tests\": \"d73d166ac90eedfb32da17575e7fa4c11c1f8485\", \"cronet_unittests\": \"0c7f1462d7315d9fd51fc8caf84bb8236e406853\", \"crypto_unittests\": \"cb6e07acafe2f73212bdd10c829d4536981b730a\", \"delayloads_unittests\": \"7c2c2657b795b69d2f4464aff2e6ab69cdf4a68c\", \"device_unittests\": \"0aaee47379a8d2a5acbe77d942de3aa9908def80\", \"display_unittests\": \"7656c2c787da0d2a6fc731d8add1a3e75a68ec8b\", \"elevation_service_unittests\": \"4f9f122e17a4c82b1b4647c44a9cb0afea947d73\", \"events_unittests\": \"0398934d24604f8e6a25488e19542c7d5d8f7c90\", \"extensions_browsertests\": \"b57fddb8bac558446752a130bc0bba82ad807c5f\", \"extensions_unittests\": \"f1fdac9a7c363968996124a70fd97df7497958a6\", \"filesystem_service_unittests\": \"f157c600cfd9e3d52ae28f82a31402be790508b6\", \"gcm_unit_tests\": \"e38698f9fa2183319b9772ece5034ba60e508fc1\", \"gcp_unittests\": \"756efa2a7dbf2f15dac76bc76bb847086230b9e0\", \"gfx_unittests\": \"993db5343576ac1f5a025aa629b1d3b1c07de148\", \"gin_unittests\": \"3e609348b43ddd0037f43b8ad7578816dc86de11\", \"google_apis_unittests\": \"aeb0fa8039af80a1518d888c6674f2565e3718fe\", \"gpu_unittests\": \"f08fc4f76a933034d260a3157770243c309abc01\", \"gwp_asan_unittests\": \"c728b74b2ed0f08093a3e154dd0977962f6c3f4c\", \"headless_browsertests\": \"62c00146ebfd3e42c0663cbe88168ab9286fd043\", \"headless_unittests\": \"8bdd8cec9b3e4a23bdb161194429b2359a4dd847\", \"install_static_unittests\": \"bcc62b66eff51bab000599cd5f3c8c55d0c57d39\", \"installer_util_unittests\": \"bcca658df23794d9fd64c37d1e5e5ec8a7d1e8a6\", \"interactive_ui_tests\": \"61aff4eacad93bd23418f2cf47616cdcb217a43a\", \"ipc_tests\": \"2863c7f95868177f94724dca935ed1c96df43aa8\", \"jingle_unittests\": \"34c25192b443c16205b9aca1718644f5b22c84b3\", \"latency_unittests\": \"18516d1c31e391a0a3ad019cf31c90e070000133\", \"libjingle_xmpp_unittests\": \"3b9331c14f644b2ed21de8f2d9581e815fd22ecc\", \"liburlpattern_unittests\": \"8a37e41a567066da216a5826f639b7f2d351cb33\", \"media_blink_unittests\": \"25f49bead561297cca55140974449b62fca6a32e\", \"media_unittests\": \"25728962f8588f3fd153690bf9bc191a652adef2\", \"message_center_unittests\": \"a01e4889e45bc0e114d58f206951a61db69866ba\", \"midi_unittests\": \"d67a69c6cf6cf4371143a0295ea20d8a0799e24a\", \"mojo_core_unittests\": \"435fad5a05d2187e1c3bcdb729b480af547a2b7d\", \"mojo_unittests\": \"2c1cc6161766b8c821d5724a3350c44c137745c7\", \"nacl_loader_unittests\": \"5f945097095d38a0d4918e5d993b36946b63bea5\", \"native_theme_unittests\": \"f9b8977de0173c1db720c4596d968d415933ee30\", \"net_unittests\": \"48c15d5b6169f5edfe65529c2bf5f0f1dc24992f\", \"notification_helper_unittests\": \"f2c6f7d65a5586eeee2bb84f96bf66cfc800fa24\", \"pdf_unittests\": \"9f639326fe60c6ea2344ddd0d0bb613a92e6a8c9\", \"perfetto_unittests\": \"066d4b2369a28fe943f70f69949c6a08a6102c11\", \"ppapi_unittests\": \"0cc177db5d113ccb03b28039e6ed8f70ddf50ecb\", \"printing_unittests\": \"d3c23734b9918d7ebf56e105769c855150dd80a8\", \"remoting_unittests\": \"eba074b76569f06343b8eef3482722aa04336f85\", \"sbox_integration_tests\": \"792ea9118f4ea13ce9146988e972a3dbb0346abe\", \"sbox_unittests\": \"6d25f681df7bd1d5d46759e1841b0c13f5db66b4\", \"sbox_validation_tests\": \"9a3d6e21c7a9677d1bd787e864bf8556c7512f5c\", \"service_manager_unittests\": \"ae33fb5e032dad4a0d1eaa4ac8618be338ad2759\", \"services_unittests\": \"771fedb94308d3c5436a4b6b7f8d29c74609bfd0\", \"setup_unittests\": \"e4fcebd897cf508a85d9223c643916fd5c97a534\", \"shell_dialogs_unittests\": \"802f6d81bbcf1f56f6ec4df39f1cfcd2f3bfd965\", \"skia_unittests\": \"50ca1e5f7afca04f94aa8e940369f8631e7c5c29\", \"snapshot_unittests\": \"dca6aab8842fbce1243eee2efb9e040977d64a56\", \"sql_unittests\": \"436fd85eef15d8af4f40887366c1d25f948fe470\", \"storage_unittests\": \"bda902200c1a2c27d9513e8e36714b1e63948f78\", \"sync_integration_tests\": \"3354ba215e59639c51852509bfb007266352b43f\", \"traffic_annotation_auditor_unittests\": \"9395f461c2608d205d3edcb90ad346e083d7eca7\", \"ui_base_unittests\": \"9ddf43e51c7bdd53ffa636d3ce7a5a273efcd4bd\", \"ui_touch_selection_unittests\": \"e82090e44a364f31d37b82b305630c3f00690148\", \"unit_tests\": \"d9ec3ebb5e7b50d9cf50aa8db5cce4646142acec\", \"updater_tests\": \"1c08dfe5556d1604a7af3b38e8eebd42c5a12d8a\", \"url_unittests\": \"debb5cebf6ecfce8daa6e515f1d39e2f8b789003\", \"views_unittests\": \"ed7f1996c73d16a3d4b7c3d52c04ec9badf3011d\", \"viz_unittests\": \"b4937b80301b1e65b52e23a8acd50fde4e2d7d28\", \"vr_common_unittests\": \"96585337056f9d6c11497e8e99c61105d74971e6\", \"vr_pixeltests\": \"2e32dd90c214332d1ec18d870d8ba1aa61c719fc\", \"weblayer_browsertests\": \"b7efa257b85ae94246ce960bf49d5d9c5a002ee9\", \"weblayer_unittests\": \"69c7c214de57357fffb8e8dc7bc164fb36b54a57\", \"wm_unittests\": \"1ed3a42219ff1dc428d0897e16afb8e847c909b6\", \"wtf_unittests\": \"a6e51ea873a3072a101ac60cb29b2424649ee962\", \"zlib_unittests\": \"275b1b3fb379c29111eb78bf2363942f5e1ff9f8\", \"zucchini_unittests\": \"f5f40204ac7dafedb3ff1f84a15a4f9ca6515c9a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-5-jjte\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5640-d0eb544\"], \"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": "1607347398217480",
"status": "COMPLETED",
"status_changed_ts": "1607351130728830",
"tags": [
"build_address:luci.chromium.ci/win-asan/16082",
"builder:win-asan",
"buildset:commit/git/f0ebcd6c25de872bb92c894d9177085431dae642",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f0ebcd6c25de872bb92c894d9177085431dae642",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026997875981788144",
"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/8861567813698423632/+/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:50563066612da110",
"user_agent:luci-scheduler"
],
"updated_ts": "1607351130734726",
"url": "https://ci.chromium.org/b/8861567813698423632",
"utcnow_ts": "1607351131558531"
}
}
[14:25:31.570] Invocation finished in 1h2m17.939980356s with status SUCCEEDED