[14:37:16.380] New invocation is queued and will start shortly
[14:37:17.474] Starting the invocation (attempt 1)
[14:37:17.507] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:37:17.507] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:37:17.508] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018567699560674000",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"707d212b01c53966e9a8401764c8c668ac870171\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018567699560674000",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/707d212b01c53966e9a8401764c8c668ac870171",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/707d212b01c53966e9a8401764c8c668ac870171",
"gitiles_ref:refs/heads/master"
]
}
[14:37:18.210] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615387037601355",
"id": "8853137637618893648",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"707d212b01c53966e9a8401764c8c668ac870171\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615387038060773",
"tags": [
"build_address:luci.chromium.ci/win-asan/17740",
"builder:win-asan",
"buildset:commit/git/707d212b01c53966e9a8401764c8c668ac870171",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/707d212b01c53966e9a8401764c8c668ac870171",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018567699560674000",
"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/8853137637618893648/+/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": "1615387038060885",
"url": "https://ci.chromium.org/b/8853137637618893648",
"utcnow_ts": "1615387038199202"
}
}
[14:37:18.210] Task URL: https://ci.chromium.org/b/8853137637618893648
[14:37:18.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:2:0) after 1m0s
[14:37:21.572] Received PubSub notification, asking Buildbucket for the build status
[14:37:21.695] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:18.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:2:0)
[14:38:18.258] Timer tick, asking Buildbucket for the build status
[14:38:18.330] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:18.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:4:0) after 1m0s
[14:39:18.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:4:0)
[14:39:18.383] Timer tick, asking Buildbucket for the build status
[14:39:18.603] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:18.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:5:0) after 1m0s
[14:40:18.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:5:0)
[14:40:18.614] Timer tick, asking Buildbucket for the build status
[14:40:18.755] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:18.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:6:0) after 1m0s
[14:41:18.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:6:0)
[14:41:18.765] Timer tick, asking Buildbucket for the build status
[14:41:18.828] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:18.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:7:0) after 1m0s
[14:42:18.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:7:0)
[14:42:18.872] Timer tick, asking Buildbucket for the build status
[14:42:18.947] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:18.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:8:0) after 1m0s
[14:43:19.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:8:0)
[14:43:19.015] Timer tick, asking Buildbucket for the build status
[14:43:19.334] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:19.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:9:0) after 1m0s
[14:44:19.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:9:0)
[14:44:19.423] Timer tick, asking Buildbucket for the build status
[14:44:19.514] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:19.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:10:0) after 1m0s
[14:45:19.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:10:0)
[14:45:19.539] Timer tick, asking Buildbucket for the build status
[14:45:19.623] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:19.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:11:0) after 1m0s
[14:46:19.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:11:0)
[14:46:19.662] Timer tick, asking Buildbucket for the build status
[14:46:19.892] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:19.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:12:0) after 1m0s
[14:47:19.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:12:0)
[14:47:19.949] Timer tick, asking Buildbucket for the build status
[14:47:20.066] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:20.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:13:0) after 1m0s
[14:48:20.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:13:0)
[14:48:20.080] Timer tick, asking Buildbucket for the build status
[14:48:20.454] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:20.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:14:0) after 1m0s
[14:49:20.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:14:0)
[14:49:20.545] Timer tick, asking Buildbucket for the build status
[14:49:20.858] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:20.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:15:0) after 1m0s
[14:50:20.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:15:0)
[14:50:20.889] Timer tick, asking Buildbucket for the build status
[14:50:20.973] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:20.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:16:0) after 1m0s
[14:51:21.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:16:0)
[14:51:21.125] Timer tick, asking Buildbucket for the build status
[14:51:21.261] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:21.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:17:0) after 1m0s
[14:52:21.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:17:0)
[14:52:21.282] Timer tick, asking Buildbucket for the build status
[14:52:21.374] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:21.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:18:0) after 1m0s
[14:53:21.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:18:0)
[14:53:21.422] Timer tick, asking Buildbucket for the build status
[14:53:21.526] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:21.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:19:0) after 1m0s
[14:54:21.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:19:0)
[14:54:21.568] Timer tick, asking Buildbucket for the build status
[14:54:21.799] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:21.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:20:0) after 1m0s
[14:55:21.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:20:0)
[14:55:21.856] Timer tick, asking Buildbucket for the build status
[14:55:21.939] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:21.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:21:0) after 1m0s
[14:56:21.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:21:0)
[14:56:21.984] Timer tick, asking Buildbucket for the build status
[14:56:22.143] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:22.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:22:0) after 1m0s
[14:57:22.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:22:0)
[14:57:22.228] Timer tick, asking Buildbucket for the build status
[14:57:22.312] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:22.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:23:0) after 1m0s
[14:58:22.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:23:0)
[14:58:22.322] Timer tick, asking Buildbucket for the build status
[14:58:22.404] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:22.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:24:0) after 1m0s
[14:59:22.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:24:0)
[14:59:22.507] Timer tick, asking Buildbucket for the build status
[14:59:22.700] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:22.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:25:0) after 1m0s
[15:00:22.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:25:0)
[15:00:22.780] Timer tick, asking Buildbucket for the build status
[15:00:23.265] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:23.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:26:0) after 1m0s
[15:01:23.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:26:0)
[15:01:23.352] Timer tick, asking Buildbucket for the build status
[15:01:23.545] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:23.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:27:0) after 1m0s
[15:02:23.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:27:0)
[15:02:23.631] Timer tick, asking Buildbucket for the build status
[15:02:23.837] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:23.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:28:0) after 1m0s
[15:03:23.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:28:0)
[15:03:23.842] Timer tick, asking Buildbucket for the build status
[15:03:23.915] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:23.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:29:0) after 1m0s
[15:04:23.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:29:0)
[15:04:23.982] Timer tick, asking Buildbucket for the build status
[15:04:24.063] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:24.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:30:0) after 1m0s
[15:05:24.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:30:0)
[15:05:24.053] Timer tick, asking Buildbucket for the build status
[15:05:24.142] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:24.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:31:0) after 1m0s
[15:06:24.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:31:0)
[15:06:24.159] Timer tick, asking Buildbucket for the build status
[15:06:24.243] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:24.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:32:0) after 1m0s
[15:07:24.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:32:0)
[15:07:24.302] Timer tick, asking Buildbucket for the build status
[15:07:24.428] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:24.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:33:0) after 1m0s
[15:08:24.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:33:0)
[15:08:24.475] Timer tick, asking Buildbucket for the build status
[15:08:24.713] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:24.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:34:0) after 1m0s
[15:09:24.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:34:0)
[15:09:24.727] Timer tick, asking Buildbucket for the build status
[15:09:24.805] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:24.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:35:0) after 1m0s
[15:10:24.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:35:0)
[15:10:24.828] Timer tick, asking Buildbucket for the build status
[15:10:24.939] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:24.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:36:0) after 1m0s
[15:11:24.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:36:0)
[15:11:24.938] Timer tick, asking Buildbucket for the build status
[15:11:25.025] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:25.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:37:0) after 1m0s
[15:12:25.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:37:0)
[15:12:25.042] Timer tick, asking Buildbucket for the build status
[15:12:25.132] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:25.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:38:0) after 1m0s
[15:13:25.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:38:0)
[15:13:25.195] Timer tick, asking Buildbucket for the build status
[15:13:25.289] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:25.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:39:0) after 1m0s
[15:14:25.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:39:0)
[15:14:25.365] Timer tick, asking Buildbucket for the build status
[15:14:25.633] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:25.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:40:0) after 1m0s
[15:15:25.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:40:0)
[15:15:25.691] Timer tick, asking Buildbucket for the build status
[15:15:25.774] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:25.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:41:0) after 1m0s
[15:16:25.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:41:0)
[15:16:25.772] Timer tick, asking Buildbucket for the build status
[15:16:25.874] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:25.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:42:0) after 1m0s
[15:17:26.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:42:0)
[15:17:26.002] Timer tick, asking Buildbucket for the build status
[15:17:26.093] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:26.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:43:0) after 1m0s
[15:18:26.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:43:0)
[15:18:26.131] Timer tick, asking Buildbucket for the build status
[15:18:26.200] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:26.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:44:0) after 1m0s
[15:19:26.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:44:0)
[15:19:26.279] Timer tick, asking Buildbucket for the build status
[15:19:26.380] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:26.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:45:0) after 1m0s
[15:20:26.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:45:0)
[15:20:26.402] Timer tick, asking Buildbucket for the build status
[15:20:26.613] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:26.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:46:0) after 1m0s
[15:21:26.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:46:0)
[15:21:26.679] Timer tick, asking Buildbucket for the build status
[15:21:26.762] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:26.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:47:0) after 1m0s
[15:22:26.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:47:0)
[15:22:26.899] Timer tick, asking Buildbucket for the build status
[15:22:26.997] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:26.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:48:0) after 1m0s
[15:23:27.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:48:0)
[15:23:27.000] Timer tick, asking Buildbucket for the build status
[15:23:27.183] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:27.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:49:0) after 1m0s
[15:24:27.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:49:0)
[15:24:27.334] Timer tick, asking Buildbucket for the build status
[15:24:27.398] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:27.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:50:0) after 1m0s
[15:25:27.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:50:0)
[15:25:27.470] Timer tick, asking Buildbucket for the build status
[15:25:27.742] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:27.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:51:0) after 1m0s
[15:26:27.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:51:0)
[15:26:27.763] Timer tick, asking Buildbucket for the build status
[15:26:27.857] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:27.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:52:0) after 1m0s
[15:27:27.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:52:0)
[15:27:27.974] Timer tick, asking Buildbucket for the build status
[15:27:28.304] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:28.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:53:0) after 1m0s
[15:28:28.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:53:0)
[15:28:28.363] Timer tick, asking Buildbucket for the build status
[15:28:28.527] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:28.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:54:0) after 1m0s
[15:29:28.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:54:0)
[15:29:28.601] Timer tick, asking Buildbucket for the build status
[15:29:28.721] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:28.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:55:0) after 1m0s
[15:30:28.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:55:0)
[15:30:28.781] Timer tick, asking Buildbucket for the build status
[15:30:28.925] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:28.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:56:0) after 1m0s
[15:31:28.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:56:0)
[15:31:28.993] Timer tick, asking Buildbucket for the build status
[15:31:29.407] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:29.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:57:0) after 1m0s
[15:32:29.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:57:0)
[15:32:29.462] Timer tick, asking Buildbucket for the build status
[15:32:30.025] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:30.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:58:0) after 1m0s
[15:33:30.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:58:0)
[15:33:30.056] Timer tick, asking Buildbucket for the build status
[15:33:30.166] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:30.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:59:0) after 1m0s
[15:34:30.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:59:0)
[15:34:30.177] Timer tick, asking Buildbucket for the build status
[15:34:30.266] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:30.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:60:0) after 1m0s
[15:35:30.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:60:0)
[15:35:30.282] Timer tick, asking Buildbucket for the build status
[15:35:30.563] Build 8853137637618893648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:30.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018567699560674000:61:0) after 1m0s
[15:35:34.548] Received PubSub notification, asking Buildbucket for the build status
[15:35:34.826] Build 8853137637618893648: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:35:34.826] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615390533722683",
"created_by": "project:chromium",
"created_ts": "1615387037601355",
"id": "8853137637618893648",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"707d212b01c53966e9a8401764c8c668ac870171\"}}",
"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\": \"707d212b01c53966e9a8401764c8c668ac870171\", \"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-8-2xaw\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17740, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"5f4a3250678b1d098814100256506953cce991ab\", \"got_dawn_revision\": \"6d4617a2d28d8d3d4aa946abdbabb368d025b6b7\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"707d212b01c53966e9a8401764c8c668ac870171\", \"got_revision_cp\": \"refs/heads/master@{#861561}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"023914a27b634063766fee96f2e6763513f5b059\", \"got_v8_revision\": \"1eba7bc10f6caa3878c5a1fd9f45ee5056ae62ed\", \"got_v8_revision_cp\": \"refs/heads/9.1.89@{#1}\", \"got_webrtc_revision\": \"92d12707e00837a978485f731d72ef30cf19d2a2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33402}\", \"is_cached\": true, \"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\": \"707d212b01c53966e9a8401764c8c668ac870171\", \"swarm_hashes_refs/heads/master(at){#861561}_without_patch\": {\"absl_hardening_tests\": \"bd549f83710be3ac77f6ac6a227d84b2b9d49909\", \"accessibility_unittests\": \"d3308001fbe607185119373eaef190ca061f640a\", \"angle_unittests\": \"f2f4f172275dbbc34270c10b12c7551113ce84b7\", \"app_shell_unittests\": \"7476f291202064f3fff92592a32ae2547b3e8c53\", \"aura_unittests\": \"7f15bdf629ab6f121bd60d92f05f766649f704a9\", \"base_unittests\": \"6480e96ae217ff59bfa5e46479e38bb4f1eeb189\", \"base_util_unittests\": \"510c4ef9ce6344b2106ddef0033b3f2d832a492e\", \"blink_common_unittests\": \"3212297b2cb78a975b1759f3a870b50a33931486\", \"blink_fuzzer_unittests\": \"1f17046dd5af3ef0781cd27ddd24868c0fca35db\", \"blink_heap_unittests\": \"6e639f4ab8c5e58008fa82a2e4cd44cd78e974cb\", \"blink_platform_unittests\": \"ca26429a40a7ab674c215d0ccdd70b86b7aa0c09\", \"blink_unittests\": \"b2f0984e7280f15633a2cdfd6111b72ac642d6cd\", \"boringssl_crypto_tests\": \"008871d283ef58013b368aa176a514ebb15efa49\", \"boringssl_ssl_tests\": \"830c20f051732f8cd9f5272d98157b5240910858\", \"browser_switcher_bho_unittests\": \"9eb17c46c54b7603770bae7362ceb2d1bd30c9ab\", \"browser_tests\": \"468cd49827c5210dc641a6888ffa5595593a8328\", \"capture_unittests\": \"bb0019cc3e26abb05fc272fcf5617348b0d80943\", \"cast_unittests\": \"1edd3f5cf7518b3d6e005623bdc0abf92973a743\", \"cc_unittests\": \"e2b253b1658dc29bbc006d4877bf31a3d37112fa\", \"chrome_app_unittests\": \"59f8e92b1130527a9eb3391638e0519ceb7aeaff\", \"chrome_cleaner_unittests\": \"d12c086b7877b25782b88896b7a9565919bcd2cb\", \"chrome_elf_unittests\": \"f84aa01bea9820ea7b002661f2737ace14d31502\", \"chromedriver_unittests\": \"7c0fa3bf74193ddb1d728bc71c109450d3beb4a7\", \"color_unittests\": \"5d13158da796b77b8b8e6e07f2859c03bac98726\", \"components_browsertests\": \"439f1aca822ed2c9d52cffbf1005d2c35fec6b4d\", \"components_unittests\": \"31c3ea46b7184d0c4f9a8eaab93095ed8c79fb21\", \"compositor_unittests\": \"c129c6d40b799b7d953a15563128bda630c41d9f\", \"content_browsertests\": \"e803f6561652bf1ac9ab3af0aa73a0bb4e469468\", \"content_unittests\": \"4fb72e46eee2c89c60d2b0f048e8399eb91fe77d\", \"courgette_unittests\": \"74bcaa9264a7e1904d3bb41a143a1d8bf9203c08\", \"crashpad_tests\": \"033724b8d5f13e21679e792ede4f72fa0e3df982\", \"cronet_tests\": \"06007fc57ee2a3600b54749e8c7d79b024cac786\", \"cronet_unittests\": \"9c9d5df81b121d4fcd81e7f61d16c1c52e82f027\", \"crypto_unittests\": \"ceecbe3d9926cdf0712633c82e1eb1611a5edcdd\", \"delayloads_unittests\": \"0d9f347feb62d5600b95f2cd36811021d88d83fd\", \"device_unittests\": \"8dd2a51e3d5c694f07cb3a8f96ae2c909ecd28d5\", \"display_unittests\": \"f35fef4e8fb6d6aad3b68a880481d7f4d372bebb\", \"elevation_service_unittests\": \"84a346fa592cae92fe4480e9ac54802143a01b6f\", \"events_unittests\": \"edf746383be9ead10fa00ec9981cdd37ed64b2c4\", \"extensions_browsertests\": \"34d5b718a09bd6c3caf0ae2ee4384c43850424f4\", \"extensions_unittests\": \"1e5ac7deb69e3384232b8fb7e2b823d3633807f2\", \"filesystem_service_unittests\": \"e89a83320f5a72045bd6b104b222b447de20ff7a\", \"gcm_unit_tests\": \"5b5f6ee372d690e1d30d662516e4234ba55b0b65\", \"gcp_unittests\": \"a0843e3d9f0935a858282094c2ff82eba508c58d\", \"gfx_unittests\": \"33eae50ab4f0e138bad15cbc411c23b20f15bc52\", \"gin_unittests\": \"53e358d66f4d265196172138e44216080dc1bf00\", \"google_apis_unittests\": \"a2bba4760c11b87193796f14b9bfe7d3550161da\", \"gpu_unittests\": \"1da7ff1ff781161fdafe851f128754163cb939d2\", \"gwp_asan_unittests\": \"9cfee60ab124cf411c0a72526478db3a8ab885d3\", \"headless_browsertests\": \"66283d11eecbb3ee1e30fb8d41260c067a02f5e2\", \"headless_unittests\": \"e39b172a393e93a91fe0fa58f7c498b476f4ba0b\", \"install_static_unittests\": \"519eec4207e9ecfd6a4ca0c9c397a4f3cf1a8419\", \"installer_util_unittests\": \"9fd3993f586dea183a4250684b14d727a1b88639\", \"interactive_ui_tests\": \"6c8fce602acb39743a479f1742b1afde0be235ef\", \"ipc_tests\": \"253314f06eb930c2384ccbfa05ef14327c253664\", \"jingle_unittests\": \"f4ff9ffa80632d70573956a33cca08bdb739e17e\", \"latency_unittests\": \"fa1539ac7ab8ed3d96e168fd13f6f5bbae9fc362\", \"libjingle_xmpp_unittests\": \"b65cb830a3493277035aa57de504b9f6bef34913\", \"liburlpattern_unittests\": \"68a04a39ec4e17ec6c8652347f11c7eb094840d0\", \"media_blink_unittests\": \"297169786d4c7da4f457dbbc48345f10722a1d32\", \"media_unittests\": \"b9570051cfaa42f771994cbe9fb0fef36fb81e2f\", \"message_center_unittests\": \"e96dee837d9f65430f57ea9ab8e70fa0c2c22498\", \"midi_unittests\": \"0dc88be101fad4a9fd52f49325884e9711cfa80d\", \"mojo_core_unittests\": \"b485546fee263cc742ded8d8c4eabc47f3a81893\", \"mojo_unittests\": \"dcab36f20683b2a6536b16e7579c8a63fe874f41\", \"nacl_loader_unittests\": \"1c5b04c194808db6db048be90e0a28cda7bc5cd0\", \"native_theme_unittests\": \"c9af15c94394586ed3627b51ff449c95c73be1b4\", \"net_unittests\": \"4b37bc912644a5349e749c60a4837c0dadce2d8a\", \"notification_helper_unittests\": \"d9120ab9d372c74c8a8f7e5ced4bf4cb72e69178\", \"pdf_unittests\": \"f43ba09ed412bc7c1d94bfaafb4e76e1dde558b0\", \"perfetto_unittests\": \"47af1bc0b438743525b11707d17a54006845b151\", \"ppapi_unittests\": \"ba2a0779be9c046ad6eb0d48acd5c822dbffdf4a\", \"printing_unittests\": \"b13f16ac68e3f01a3adfd3409196b5c508d13747\", \"remoting_unittests\": \"e3767863f56b016f654c052201e98d96bcf1cac9\", \"sbox_integration_tests\": \"2fde677e9f146c01ee3011d62d5f9e6891249c2b\", \"sbox_unittests\": \"78a9f77a8e70c9eb967417993918937cd9c45a55\", \"sbox_validation_tests\": \"ac0c1de2caebf2c9dd7a373f5336e3eddc6ee01d\", \"service_manager_unittests\": \"31e7e1cb32f8cf8cbc1f386c068eae94357d0fde\", \"services_unittests\": \"9253b8e8758110a34d69fd66b32dd0d24e16d288\", \"setup_unittests\": \"e7b4e96a23e358589454eaee0352bc3593230fab\", \"shell_dialogs_unittests\": \"b46ebbacbe4a2197ae5a56b73cfb36fbd2716a43\", \"skia_unittests\": \"6a01bc9d8e6db2214b7f0ffa3c6c2077e9302c4f\", \"snapshot_unittests\": \"71347ae40193880381b889c6d24af226473e0b3c\", \"sql_unittests\": \"c4f5b6978fcce50d760de3268470aa85c8dbd6d0\", \"storage_unittests\": \"994d97713560f84c290ca0e77b10d6c7f790994c\", \"sync_integration_tests\": \"fe6a994194c188b204e7c357d93f622708cd5ce0\", \"traffic_annotation_auditor_unittests\": \"10a538fea29e0f5f4fe471e20b2297553b135bd9\", \"ui_base_unittests\": \"89f4a178a7d0949d5b050cb7118953c4f0b7e65c\", \"ui_touch_selection_unittests\": \"6f63e14efba9ca82ac31a892f2619fbe5b6fa538\", \"unit_tests\": \"7493e677f02d269847221fb205f7308ef1f5817f\", \"updater_tests\": \"b0bd732d20477dccca5025effa8f311215c63dc8\", \"url_unittests\": \"79bf2d284a745570d0cacaea19f3c4133f11f5d3\", \"views_unittests\": \"c3e258a0f648dd8ba6cd0f7500845e0167d3c4f5\", \"viz_unittests\": \"b7d1be4abb40cf345b4b5fff916ee8e21c610256\", \"vr_common_unittests\": \"6e8ce78734e22974bdd681d069fd721743b888df\", \"vr_pixeltests\": \"c18f54f79620654ac52d4bb0da0652a828b88cdf\", \"weblayer_browsertests\": \"3fd88a7fc0af2875232c96a8807186831d0d3f33\", \"weblayer_unittests\": \"3e5e6e889df319492fce1ecf3dd5b7d4eea4a70f\", \"wm_unittests\": \"29bcdfb95453511640be3f4ae2972420a84cafa3\", \"wtf_unittests\": \"44bcc4d8500d444331cd139c63b630d2229c2973\", \"zlib_unittests\": \"e53142252d6b4c0daf3079a99e48c4cd7f40e98e\", \"zucchini_unittests\": \"d9bbf073c78346f6d2d2c214ee63e4ced706d80a\"}}, \"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-8-2xaw\"], \"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\": [\"5746-91ff3bd\"], \"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": "1615387040577779",
"status": "COMPLETED",
"status_changed_ts": "1615390534091403",
"tags": [
"build_address:luci.chromium.ci/win-asan/17740",
"builder:win-asan",
"buildset:commit/git/707d212b01c53966e9a8401764c8c668ac870171",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/707d212b01c53966e9a8401764c8c668ac870171",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018567699560674000",
"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/8853137637618893648/+/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:5235639aa6c16a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615390534097145",
"url": "https://ci.chromium.org/b/8853137637618893648",
"utcnow_ts": "1615390534805396"
}
}
[15:35:34.826] Invocation finished in 58m18.460630998s with status SUCCEEDED