[04:17:06.557] New invocation is queued and will start shortly
[04:17:08.760] Starting the invocation (attempt 1)
[04:17:08.806] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:17:08.806] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:17:08.806] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029931337690940816",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2abe176f0212dd8d027b33bc284b25ececdc5d4d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029931337690940816",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/2abe176f0212dd8d027b33bc284b25ececdc5d4d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2abe176f0212dd8d027b33bc284b25ececdc5d4d",
"gitiles_ref:refs/heads/master"
]
}
[04:17:10.765] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604549829988578",
"id": "8864501273428458848",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2abe176f0212dd8d027b33bc284b25ececdc5d4d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604549830487730",
"tags": [
"build_address:luci.chromium.ci/win-asan/15469",
"builder:win-asan",
"buildset:commit/git/2abe176f0212dd8d027b33bc284b25ececdc5d4d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2abe176f0212dd8d027b33bc284b25ececdc5d4d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029931337690940816",
"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/8864501273428458848/+/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": "1604549830487872",
"url": "https://ci.chromium.org/b/8864501273428458848",
"utcnow_ts": "1604549830749703"
}
}
[04:17:10.765] Task URL: https://ci.chromium.org/b/8864501273428458848
[04:17:10.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:2:0) after 1m0s
[04:17:14.916] Received PubSub notification, asking Buildbucket for the build status
[04:17:16.859] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:10.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:2:0)
[04:18:10.927] Timer tick, asking Buildbucket for the build status
[04:18:11.231] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:11.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:4:0) after 1m0s
[04:19:11.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:4:0)
[04:19:11.294] Timer tick, asking Buildbucket for the build status
[04:19:11.392] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:11.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:5:0) after 1m0s
[04:20:11.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:5:0)
[04:20:11.395] Timer tick, asking Buildbucket for the build status
[04:20:11.549] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:11.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:6:0) after 1m0s
[04:21:11.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:6:0)
[04:21:11.591] Timer tick, asking Buildbucket for the build status
[04:21:11.987] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:11.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:7:0) after 1m0s
[04:22:12.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:7:0)
[04:22:12.170] Timer tick, asking Buildbucket for the build status
[04:22:12.354] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:12.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:8:0) after 1m0s
[04:23:12.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:8:0)
[04:23:12.432] Timer tick, asking Buildbucket for the build status
[04:23:12.550] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:12.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:9:0) after 1m0s
[04:24:12.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:9:0)
[04:24:12.646] Timer tick, asking Buildbucket for the build status
[04:24:12.744] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:12.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:10:0) after 1m0s
[04:25:12.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:10:0)
[04:25:12.805] Timer tick, asking Buildbucket for the build status
[04:25:12.872] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:12.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:11:0) after 1m0s
[04:26:12.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:11:0)
[04:26:12.926] Timer tick, asking Buildbucket for the build status
[04:26:13.089] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:13.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:12:0) after 1m0s
[04:27:13.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:12:0)
[04:27:13.182] Timer tick, asking Buildbucket for the build status
[04:27:13.718] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:13.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:13:0) after 1m0s
[04:28:13.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:13:0)
[04:28:13.720] Timer tick, asking Buildbucket for the build status
[04:28:14.102] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:14.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:14:0) after 1m0s
[04:29:14.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:14:0)
[04:29:14.087] Timer tick, asking Buildbucket for the build status
[04:29:15.588] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:15.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:15:0) after 1m0s
[04:30:15.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:15:0)
[04:30:15.639] Timer tick, asking Buildbucket for the build status
[04:30:15.917] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:15.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:16:0) after 1m0s
[04:31:15.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:16:0)
[04:31:15.976] Timer tick, asking Buildbucket for the build status
[04:31:16.038] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:16.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:17:0) after 1m0s
[04:32:16.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:17:0)
[04:32:16.062] Timer tick, asking Buildbucket for the build status
[04:32:16.547] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:16.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:18:0) after 1m0s
[04:33:16.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:18:0)
[04:33:16.621] Timer tick, asking Buildbucket for the build status
[04:33:16.814] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:16.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:19:0) after 1m0s
[04:34:16.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:19:0)
[04:34:16.804] Timer tick, asking Buildbucket for the build status
[04:34:17.180] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:17.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:20:0) after 1m0s
[04:35:17.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:20:0)
[04:35:17.237] Timer tick, asking Buildbucket for the build status
[04:35:17.517] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:17.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:21:0) after 1m0s
[04:36:17.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:21:0)
[04:36:17.520] Timer tick, asking Buildbucket for the build status
[04:36:17.742] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:17.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:22:0) after 1m0s
[04:37:17.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:22:0)
[04:37:17.766] Timer tick, asking Buildbucket for the build status
[04:37:17.978] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:17.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:23:0) after 1m0s
[04:38:17.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:23:0)
[04:38:17.989] Timer tick, asking Buildbucket for the build status
[04:38:18.557] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:18.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:24:0) after 1m0s
[04:39:18.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:24:0)
[04:39:18.659] Timer tick, asking Buildbucket for the build status
[04:39:18.780] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:18.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:25:0) after 1m0s
[04:40:18.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:25:0)
[04:40:18.888] Timer tick, asking Buildbucket for the build status
[04:40:19.944] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:19.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:26:0) after 1m0s
[04:41:20.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:26:0)
[04:41:20.098] Timer tick, asking Buildbucket for the build status
[04:41:20.266] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:20.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:27:0) after 1m0s
[04:42:20.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:27:0)
[04:42:20.323] Timer tick, asking Buildbucket for the build status
[04:42:20.451] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:20.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:28:0) after 1m0s
[04:43:20.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:28:0)
[04:43:20.582] Timer tick, asking Buildbucket for the build status
[04:43:20.706] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:20.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:29:0) after 1m0s
[04:44:20.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:29:0)
[04:44:20.752] Timer tick, asking Buildbucket for the build status
[04:44:20.841] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:20.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:30:0) after 1m0s
[04:45:20.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:30:0)
[04:45:20.942] Timer tick, asking Buildbucket for the build status
[04:45:21.019] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:21.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:31:0) after 1m0s
[04:46:21.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:31:0)
[04:46:21.088] Timer tick, asking Buildbucket for the build status
[04:46:21.184] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:21.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:32:0) after 1m0s
[04:47:21.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:32:0)
[04:47:21.174] Timer tick, asking Buildbucket for the build status
[04:47:21.316] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:21.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:33:0) after 1m0s
[04:48:21.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:33:0)
[04:48:21.458] Timer tick, asking Buildbucket for the build status
[04:48:21.543] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:21.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:34:0) after 1m0s
[04:49:21.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:34:0)
[04:49:21.664] Timer tick, asking Buildbucket for the build status
[04:49:21.822] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:21.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:35:0) after 1m0s
[04:50:21.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:35:0)
[04:50:21.897] Timer tick, asking Buildbucket for the build status
[04:50:22.904] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:22.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:36:0) after 1m0s
[04:51:23.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:36:0)
[04:51:23.023] Timer tick, asking Buildbucket for the build status
[04:51:23.086] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:23.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:37:0) after 1m0s
[04:52:23.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:37:0)
[04:52:23.206] Timer tick, asking Buildbucket for the build status
[04:52:23.315] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:23.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:38:0) after 1m0s
[04:53:23.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:38:0)
[04:53:23.346] Timer tick, asking Buildbucket for the build status
[04:53:23.415] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:23.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:39:0) after 1m0s
[04:54:23.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:39:0)
[04:54:23.576] Timer tick, asking Buildbucket for the build status
[04:54:23.724] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:23.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:40:0) after 1m0s
[04:55:23.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:40:0)
[04:55:23.801] Timer tick, asking Buildbucket for the build status
[04:55:23.905] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:23.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:41:0) after 1m0s
[04:56:23.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:41:0)
[04:56:23.988] Timer tick, asking Buildbucket for the build status
[04:56:24.344] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:24.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:42:0) after 1m0s
[04:57:24.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:42:0)
[04:57:24.367] Timer tick, asking Buildbucket for the build status
[04:57:24.725] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:24.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:43:0) after 1m0s
[04:58:24.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:43:0)
[04:58:24.767] Timer tick, asking Buildbucket for the build status
[04:58:25.243] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:25.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:44:0) after 1m0s
[04:59:25.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:44:0)
[04:59:25.279] Timer tick, asking Buildbucket for the build status
[04:59:25.380] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:25.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:45:0) after 1m0s
[05:00:25.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:45:0)
[05:00:25.925] Timer tick, asking Buildbucket for the build status
[05:00:26.459] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:26.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:46:0) after 1m0s
[05:01:26.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:46:0)
[05:01:26.648] Timer tick, asking Buildbucket for the build status
[05:01:26.840] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:26.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:47:0) after 1m0s
[05:02:27.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:47:0)
[05:02:27.070] Timer tick, asking Buildbucket for the build status
[05:02:27.174] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:27.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:48:0) after 1m0s
[05:03:27.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:48:0)
[05:03:27.522] Timer tick, asking Buildbucket for the build status
[05:03:27.640] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:27.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:49:0) after 1m0s
[05:04:28.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:49:0)
[05:04:28.041] Timer tick, asking Buildbucket for the build status
[05:04:28.127] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:28.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:50:0) after 1m0s
[05:05:28.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:50:0)
[05:05:28.161] Timer tick, asking Buildbucket for the build status
[05:05:28.286] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:28.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:51:0) after 1m0s
[05:06:28.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:51:0)
[05:06:28.316] Timer tick, asking Buildbucket for the build status
[05:06:28.390] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:28.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:52:0) after 1m0s
[05:07:28.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:52:0)
[05:07:28.406] Timer tick, asking Buildbucket for the build status
[05:07:28.568] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:28.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:53:0) after 1m0s
[05:08:28.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:53:0)
[05:08:28.604] Timer tick, asking Buildbucket for the build status
[05:08:28.691] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:28.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:54:0) after 1m0s
[05:09:28.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:54:0)
[05:09:28.698] Timer tick, asking Buildbucket for the build status
[05:09:28.866] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:28.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:55:0) after 1m0s
[05:10:29.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:55:0)
[05:10:29.119] Timer tick, asking Buildbucket for the build status
[05:10:29.291] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:29.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:56:0) after 1m0s
[05:11:29.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:56:0)
[05:11:29.444] Timer tick, asking Buildbucket for the build status
[05:11:29.836] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:29.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:57:0) after 1m0s
[05:12:30.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:57:0)
[05:12:30.413] Timer tick, asking Buildbucket for the build status
[05:12:30.992] Build 8864501273428458848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:30.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029931337690940816:58:0) after 1m0s
[05:13:23.073] Received PubSub notification, asking Buildbucket for the build status
[05:13:23.192] Build 8864501273428458848: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:13:23.192] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604553201257226",
"created_by": "project:chromium",
"created_ts": "1604549829988578",
"id": "8864501273428458848",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2abe176f0212dd8d027b33bc284b25ececdc5d4d\"}}",
"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\": \"2abe176f0212dd8d027b33bc284b25ececdc5d4d\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-5-vrjs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15469, \"clang_revision\": \"llvmorg-12-init-10666-gc9f69ee7-1\", \"got_angle_revision\": \"658ede5d8c5caba6f5e2c4c0501bfbc345c7e2ef\", \"got_dawn_revision\": \"6564890116fd5629ad76948f12ad158d6f111cc3\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"2abe176f0212dd8d027b33bc284b25ececdc5d4d\", \"got_revision_cp\": \"refs/heads/master@{#824285}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"ad54c71632cc0f18584a7009f1e6fc44f5453bc7\", \"got_v8_revision\": \"7ae0bc9f7258d206e0de93911c5987c3e04979b8\", \"got_v8_revision_cp\": \"refs/heads/8.8.228@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2abe176f0212dd8d027b33bc284b25ececdc5d4d\", \"swarm_hashes_refs/heads/master(at){#824285}_without_patch\": {\"absl_hardening_tests\": \"c579cf9eccf1410e2f757b1fcde431fa927e87db\", \"accessibility_unittests\": \"a428884f17bc21009f6c0abf679186600700ace4\", \"angle_unittests\": \"0ca7e6ef04c358e1df78e9df5966f58fd2b0def1\", \"app_shell_unittests\": \"93ca9886fd8fa077f59b38711028195da8651d8d\", \"aura_unittests\": \"20062c21f4db430de3693a55840a1cbca5bbfb6f\", \"base_unittests\": \"20349b072f72db69909161ff87e164f05dd07000\", \"base_util_unittests\": \"0c0c819cdf4d3759ae64a2ca4b35bb81dd3ccac8\", \"blink_common_unittests\": \"eac854f5b7ef2c29963a57deab0e781d8c0a1e6f\", \"blink_fuzzer_unittests\": \"3c6ef07b5559bb2a627ba098dd43855bb4949fdc\", \"blink_heap_unittests\": \"cdf1de7c12018aeab0ff679cde2644a5483fb4f9\", \"blink_platform_unittests\": \"fd44a920e4b3f594e9a2736d72595a07ec19de13\", \"blink_unittests\": \"8d532822e713479393c84964005b67d51cff63f5\", \"boringssl_crypto_tests\": \"ac50efc9f2d8aa35c6c9394bb7416364463b3590\", \"boringssl_ssl_tests\": \"da5a1707c0c714032818baf1d34ef53fd54e3297\", \"browser_switcher_bho_unittests\": \"74c023527e602357538c652a70c8d14c3e1030b1\", \"browser_tests\": \"20033f9ec9dfe4e9b22f0c091a4b9995463d3302\", \"capture_unittests\": \"1d68fc417592ae014c669aa3aa0e65b9b28ec2cd\", \"cast_unittests\": \"b37f9a99a9799ac7cafd592084ddf4782a3eb265\", \"cc_unittests\": \"7811f40f2a443846a860c6d796bc33584c732739\", \"chrome_app_unittests\": \"6cb436473e9ea86db217318801d3ea28ebccb010\", \"chrome_cleaner_unittests\": \"f6072709ca0553dd0750c465426fc36f5e8882c9\", \"chrome_elf_unittests\": \"56ec484f441d4bbd73029d1232c353e710abd6d1\", \"chromedriver_unittests\": \"53f8d07fb0530bbaa47cf35d2e7aa07540e37867\", \"components_browsertests\": \"d525a507724e11eea660d20baf9b1eff72b59a74\", \"components_unittests\": \"38ac5ec2d0cd0894f0dd9b23620a34608f7f6139\", \"compositor_unittests\": \"36f393acdae03c90562a7f44c72c2661064a768b\", \"content_browsertests\": \"e263d0ee90087911dbc533fb27640622905ea82c\", \"content_unittests\": \"8cd5b5f00ccf97270a67bbc5027214bfefabb146\", \"courgette_unittests\": \"d7a82196ce78446f738f0cb51b36f09dc023ad30\", \"crashpad_tests\": \"5bac3dc10a8a7e617f8f7b2519a3e356e5df4542\", \"cronet_tests\": \"27d6cf3bbbcb637b4dd74a2fd41e4d7c45870884\", \"cronet_unittests\": \"1f6898a56b90d125b728b50bce94e2edaf6dbd7d\", \"crypto_unittests\": \"61bffc5c07292bc4b68893f407b06409fc3682f7\", \"delayloads_unittests\": \"15dbafc0bb59990e71a6ef8377e0c799fe917563\", \"device_unittests\": \"8a28d4576896dd2eeb77bcd898963c8cb09b9f16\", \"display_unittests\": \"2524b9eb034b86d65fa008941bafbc37772aff79\", \"elevation_service_unittests\": \"fadd1eca9c6c34dc56360caa5b3efd5fe6e98119\", \"events_unittests\": \"f979b099739a92e0c2da3fc25b90ad2f81e9b726\", \"extensions_browsertests\": \"87672695fca7e128a060896852a67714c85b02aa\", \"extensions_unittests\": \"6d6a481b8494a6d4b299858f561bae506d294da5\", \"filesystem_service_unittests\": \"c41be6307a09f20b520896515ceb2af33da70fc4\", \"gcm_unit_tests\": \"3f13d3313557ab26a145a9a6d78b4564df81adb0\", \"gcp_unittests\": \"bbdbfffa75d59e3f3f8ac94d1f8d4290f8c14f50\", \"gfx_unittests\": \"de1290e23a6003229e60ceededc16afa018299d6\", \"gin_unittests\": \"12d586d0258b4e3866fe3608eacb12deeed82858\", \"google_apis_unittests\": \"a709a63765f92cd9ec64b3d1ee7340c7252e9594\", \"gpu_unittests\": \"fbea5eaeb31bd30b759f5c0cdff574527a07f2aa\", \"gwp_asan_unittests\": \"bcab3c2fe02f43c50ef17bc364e2d3247b26c396\", \"headless_browsertests\": \"ff2cacc13757dc2b698d8772929a6ab1f4adb68b\", \"headless_unittests\": \"65350fa9f451cf1adecb23f899bb97e389be8989\", \"install_static_unittests\": \"c1daf2250d2cf31fd278b2bf4cbabda5b3742d69\", \"installer_util_unittests\": \"bd6f8dccae8abb15dcadb2cfdcbbd5d8c5a8ac85\", \"interactive_ui_tests\": \"2f38a1f16ba11948a2655f5db5878b82c50994b1\", \"ipc_tests\": \"b2e18b0dc7be31f125c8b4155421e86f58e49986\", \"jingle_unittests\": \"966b50074cae04d0a767aa4e3309387b3be80bd2\", \"latency_unittests\": \"601a8ea0b588d2116db42ce262a0c1d6c04eee10\", \"libjingle_xmpp_unittests\": \"e0bcb6c76f4d74e7c721929d8374739f297dae64\", \"liburlpattern_unittests\": \"f13d33fe5f86166691a060157499d416c819c89f\", \"media_blink_unittests\": \"19ee60677be173cc9a7b9de2ea5ce24515ed943d\", \"media_unittests\": \"179ebfbea2f1f9f683625bbd9bc3c5bb5661784f\", \"message_center_unittests\": \"3f077d7c3d375cb75cd035020bfabb44db48bd93\", \"midi_unittests\": \"b6434c392debd7fc1df43d5748fa8398a3ae9620\", \"mojo_core_unittests\": \"b17bf33030a7807c34d467485e1384c275f7926e\", \"mojo_unittests\": \"b30131419fe59ab1a2710bc32770ef049754e6a9\", \"nacl_loader_unittests\": \"af3d729142e38dbf8616229075af91114edeab48\", \"native_theme_unittests\": \"0bd0d19181466e097751319a78b128910a1fa8a0\", \"net_unittests\": \"0449485f8c7a7006097e13f06432f0155ecd6805\", \"notification_helper_unittests\": \"889e8ef9d6443dcd0b00f28acdb3864bb44fa7a7\", \"pdf_unittests\": \"e2d019f666cefd259c13ec11ed4fdb75637d741e\", \"perfetto_unittests\": \"cf381c31f2e864fb87f92012de2acdfece7ca4c9\", \"ppapi_unittests\": \"95b59a7b9233ab1b819e89e73d82397a1d3d2ba5\", \"printing_unittests\": \"d9c902568b4b5a49f4bf2a5ab9732b3c92b19a0d\", \"remoting_unittests\": \"6eca088b5bf4b222a3c6d4fe08af26f210ab7f2b\", \"sbox_integration_tests\": \"510fba7d91280a37e9162b6cff7283723bce9c12\", \"sbox_unittests\": \"5e0505dd87bedb5371338e5c3b0648d6851ad3cd\", \"sbox_validation_tests\": \"58d896eff96535fe20999193167aedb2b7decd64\", \"service_manager_unittests\": \"9cbefceeca30573a3ef57da20c29701aef95a72a\", \"services_unittests\": \"9ed36c8471d6b0fb40511a63d4d7e675812c29c6\", \"setup_unittests\": \"afe41ed2c1e429374dac8fa02a1aa9e1669ac71d\", \"shell_dialogs_unittests\": \"bbfa2844a727c9c6679ac868f2496fbc1496bc80\", \"skia_unittests\": \"ec60be309ce46198e98e02ad923975207af8e7ad\", \"snapshot_unittests\": \"cde4ce54a8150237df359e73d1c1267ef8ca6824\", \"sql_unittests\": \"81b8f72e2bfb60a9f9e971ba5d591c9f754f984b\", \"storage_unittests\": \"cdb63af827f6606f3bcbbbecaeff57a7be87bcc8\", \"sync_integration_tests\": \"74453b4b66e9ee1770838ff91d624a6bc51a34a5\", \"traffic_annotation_auditor_unittests\": \"24b0c8a10f17647befbdd58e68efcd0cbde545d1\", \"ui_base_unittests\": \"e09650bef67bbddad3630cadd82b067bea53ee0c\", \"ui_touch_selection_unittests\": \"9611e9a0b21567d17bc932c26edcd6338c3b10bb\", \"unit_tests\": \"cd4be967e3b393d214196a554ddd3f230f606ad7\", \"updater_tests\": \"af512d1d74e896bfb84b3c1454a08e646b183f56\", \"url_unittests\": \"d1b18bb6c8ae3dfdcc8834c4bcede87f63367ca0\", \"views_unittests\": \"1c654dfff2e0573da2ce8c1a738448ac869abfb4\", \"viz_unittests\": \"d96858314839ab781290838f6806a9adc8f09df3\", \"vr_common_unittests\": \"d36145e1cb8a459e1d386551fba345d5986f609a\", \"vr_pixeltests\": \"26e6cb26575e0336d78d96a268c192f90ceeded7\", \"weblayer_browsertests\": \"ec2de5566a40214eb6203b3e031a79a606074ee5\", \"weblayer_unittests\": \"6dd632d1553a7d7759d6d15bc42d4b65dc54d4fc\", \"wm_unittests\": \"a3bb52d0938c9984827627cfb37a27407820f7e1\", \"wtf_unittests\": \"cc9e10be514fc1b41d10d57c042f4d37ed65402b\", \"zlib_unittests\": \"53becd8bc685c18d75288e98730510d1c3af56c2\", \"zucchini_unittests\": \"0b6186f20859a4d2af745d6798dfc9c3ca6dca9e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-5-vrjs\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604549834108183",
"status": "COMPLETED",
"status_changed_ts": "1604553201603987",
"tags": [
"build_address:luci.chromium.ci/win-asan/15469",
"builder:win-asan",
"buildset:commit/git/2abe176f0212dd8d027b33bc284b25ececdc5d4d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2abe176f0212dd8d027b33bc284b25ececdc5d4d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029931337690940816",
"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/8864501273428458848/+/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:4faf70f0ed0c3310",
"user_agent:luci-scheduler"
],
"updated_ts": "1604553201619181",
"url": "https://ci.chromium.org/b/8864501273428458848",
"utcnow_ts": "1604553203182616"
}
}
[05:13:23.192] Invocation finished in 56m16.652577411s with status SUCCEEDED