[07:59:24.503] New invocation is queued and will start shortly
[07:59:25.698] Starting the invocation (attempt 1)
[07:59:25.734] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:59:25.734] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:59:25.735] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026202876218417584",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5d95bb8020aad58f325abbc095d7f56737b835df\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026202876218417584",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5d95bb8020aad58f325abbc095d7f56737b835df",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5d95bb8020aad58f325abbc095d7f56737b835df",
"gitiles_ref:refs/heads/master"
]
}
[07:59:26.361] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608105565811309",
"id": "8860772814182281008",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5d95bb8020aad58f325abbc095d7f56737b835df\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608105566145853",
"tags": [
"build_address:luci.chromium.ci/win-asan/16256",
"builder:win-asan",
"buildset:commit/git/5d95bb8020aad58f325abbc095d7f56737b835df",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5d95bb8020aad58f325abbc095d7f56737b835df",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026202876218417584",
"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/8860772814182281008/+/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": "1608105566145993",
"url": "https://ci.chromium.org/b/8860772814182281008",
"utcnow_ts": "1608105566354595"
}
}
[07:59:26.361] Task URL: https://ci.chromium.org/b/8860772814182281008
[07:59:26.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:2:0) after 1m0s
[07:59:30.791] Received PubSub notification, asking Buildbucket for the build status
[07:59:30.871] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:26.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:2:0)
[08:00:26.484] Timer tick, asking Buildbucket for the build status
[08:00:26.642] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:26.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:4:0) after 1m0s
[08:01:26.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:4:0)
[08:01:26.921] Timer tick, asking Buildbucket for the build status
[08:01:27.038] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:27.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:5:0) after 1m0s
[08:02:27.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:5:0)
[08:02:27.145] Timer tick, asking Buildbucket for the build status
[08:02:27.258] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:27.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:6:0) after 1m0s
[08:03:27.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:6:0)
[08:03:27.492] Timer tick, asking Buildbucket for the build status
[08:03:27.611] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:27.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:7:0) after 1m0s
[08:04:27.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:7:0)
[08:04:27.775] Timer tick, asking Buildbucket for the build status
[08:04:27.897] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:27.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:8:0) after 1m0s
[08:05:27.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:8:0)
[08:05:27.889] Timer tick, asking Buildbucket for the build status
[08:05:28.014] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:28.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:9:0) after 1m0s
[08:06:28.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:9:0)
[08:06:28.154] Timer tick, asking Buildbucket for the build status
[08:06:28.569] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:28.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:10:0) after 1m0s
[08:07:28.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:10:0)
[08:07:28.712] Timer tick, asking Buildbucket for the build status
[08:07:28.779] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:28.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:11:0) after 1m0s
[08:08:28.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:11:0)
[08:08:28.806] Timer tick, asking Buildbucket for the build status
[08:08:28.903] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:28.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:12:0) after 1m0s
[08:09:28.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:12:0)
[08:09:28.936] Timer tick, asking Buildbucket for the build status
[08:09:29.069] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:29.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:13:0) after 1m0s
[08:10:29.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:13:0)
[08:10:29.087] Timer tick, asking Buildbucket for the build status
[08:10:29.514] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:29.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:14:0) after 1m0s
[08:11:29.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:14:0)
[08:11:29.557] Timer tick, asking Buildbucket for the build status
[08:11:29.651] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:29.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:15:0) after 1m0s
[08:12:29.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:15:0)
[08:12:29.707] Timer tick, asking Buildbucket for the build status
[08:12:29.798] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:29.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:16:0) after 1m0s
[08:13:29.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:16:0)
[08:13:29.829] Timer tick, asking Buildbucket for the build status
[08:13:29.901] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:29.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:17:0) after 1m0s
[08:14:29.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:17:0)
[08:14:29.998] Timer tick, asking Buildbucket for the build status
[08:14:30.107] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:30.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:18:0) after 1m0s
[08:15:30.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:18:0)
[08:15:30.156] Timer tick, asking Buildbucket for the build status
[08:15:30.274] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:30.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:19:0) after 1m0s
[08:16:30.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:19:0)
[08:16:30.283] Timer tick, asking Buildbucket for the build status
[08:16:30.389] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:30.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:20:0) after 1m0s
[08:17:30.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:20:0)
[08:17:30.428] Timer tick, asking Buildbucket for the build status
[08:17:30.507] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:30.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:21:0) after 1m0s
[08:18:30.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:21:0)
[08:18:30.575] Timer tick, asking Buildbucket for the build status
[08:18:30.913] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:30.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:22:0) after 1m0s
[08:19:30.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:22:0)
[08:19:30.937] Timer tick, asking Buildbucket for the build status
[08:19:31.315] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:31.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:23:0) after 1m0s
[08:20:31.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:23:0)
[08:20:31.298] Timer tick, asking Buildbucket for the build status
[08:20:31.455] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:31.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:24:0) after 1m0s
[08:21:31.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:24:0)
[08:21:31.487] Timer tick, asking Buildbucket for the build status
[08:21:31.595] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:31.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:25:0) after 1m0s
[08:22:31.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:25:0)
[08:22:31.614] Timer tick, asking Buildbucket for the build status
[08:22:32.200] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:32.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:26:0) after 1m0s
[08:23:32.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:26:0)
[08:23:32.234] Timer tick, asking Buildbucket for the build status
[08:23:32.339] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:32.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:27:0) after 1m0s
[08:24:32.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:27:0)
[08:24:32.397] Timer tick, asking Buildbucket for the build status
[08:24:32.496] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:32.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:28:0) after 1m0s
[08:25:32.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:28:0)
[08:25:32.597] Timer tick, asking Buildbucket for the build status
[08:25:32.795] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:32.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:29:0) after 1m0s
[08:26:32.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:29:0)
[08:26:32.838] Timer tick, asking Buildbucket for the build status
[08:26:33.108] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:33.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:30:0) after 1m0s
[08:27:33.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:30:0)
[08:27:33.127] Timer tick, asking Buildbucket for the build status
[08:27:33.353] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:33.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:31:0) after 1m0s
[08:28:33.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:31:0)
[08:28:33.439] Timer tick, asking Buildbucket for the build status
[08:28:33.525] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:33.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:32:0) after 1m0s
[08:29:33.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:32:0)
[08:29:33.542] Timer tick, asking Buildbucket for the build status
[08:29:33.674] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:33.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:33:0) after 1m0s
[08:30:33.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:33:0)
[08:30:33.735] Timer tick, asking Buildbucket for the build status
[08:30:33.921] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:33.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:34:0) after 1m0s
[08:31:33.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:34:0)
[08:31:33.930] Timer tick, asking Buildbucket for the build status
[08:31:34.143] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:34.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:35:0) after 1m0s
[08:32:34.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:35:0)
[08:32:34.223] Timer tick, asking Buildbucket for the build status
[08:32:34.618] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:34.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:36:0) after 1m0s
[08:33:34.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:36:0)
[08:33:34.638] Timer tick, asking Buildbucket for the build status
[08:33:34.749] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:34.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:37:0) after 1m0s
[08:34:34.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:37:0)
[08:34:34.803] Timer tick, asking Buildbucket for the build status
[08:34:34.922] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:34.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:38:0) after 1m0s
[08:35:35.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:38:0)
[08:35:35.024] Timer tick, asking Buildbucket for the build status
[08:35:35.114] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:35.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:39:0) after 1m0s
[08:36:35.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:39:0)
[08:36:35.135] Timer tick, asking Buildbucket for the build status
[08:36:35.251] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:35.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:40:0) after 1m0s
[08:37:35.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:40:0)
[08:37:35.321] Timer tick, asking Buildbucket for the build status
[08:37:35.632] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:35.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:41:0) after 1m0s
[08:38:35.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:41:0)
[08:38:35.616] Timer tick, asking Buildbucket for the build status
[08:38:35.693] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:35.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:42:0) after 1m0s
[08:39:35.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:42:0)
[08:39:35.748] Timer tick, asking Buildbucket for the build status
[08:39:35.862] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:35.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:43:0) after 1m0s
[08:40:35.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:43:0)
[08:40:35.929] Timer tick, asking Buildbucket for the build status
[08:40:36.011] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:36.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:44:0) after 1m0s
[08:41:36.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:44:0)
[08:41:36.025] Timer tick, asking Buildbucket for the build status
[08:41:36.103] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:36.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:45:0) after 1m0s
[08:42:36.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:45:0)
[08:42:36.101] Timer tick, asking Buildbucket for the build status
[08:42:36.214] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:36.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:46:0) after 1m0s
[08:43:36.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:46:0)
[08:43:36.302] Timer tick, asking Buildbucket for the build status
[08:43:36.426] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:36.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:47:0) after 1m0s
[08:44:36.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:47:0)
[08:44:36.514] Timer tick, asking Buildbucket for the build status
[08:44:36.644] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:36.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:48:0) after 1m0s
[08:45:36.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:48:0)
[08:45:36.791] Timer tick, asking Buildbucket for the build status
[08:45:37.185] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:37.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:49:0) after 1m0s
[08:46:37.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:49:0)
[08:46:37.273] Timer tick, asking Buildbucket for the build status
[08:46:37.354] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:37.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:50:0) after 1m0s
[08:47:37.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:50:0)
[08:47:37.415] Timer tick, asking Buildbucket for the build status
[08:47:37.541] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:37.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:51:0) after 1m0s
[08:48:37.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:51:0)
[08:48:37.604] Timer tick, asking Buildbucket for the build status
[08:48:37.719] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:37.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:52:0) after 1m0s
[08:49:37.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:52:0)
[08:49:37.738] Timer tick, asking Buildbucket for the build status
[08:49:37.868] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:37.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:53:0) after 1m0s
[08:50:37.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:53:0)
[08:50:37.892] Timer tick, asking Buildbucket for the build status
[08:50:38.512] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:38.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:54:0) after 1m0s
[08:51:38.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:54:0)
[08:51:38.568] Timer tick, asking Buildbucket for the build status
[08:51:38.676] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:38.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:55:0) after 1m0s
[08:52:38.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:55:0)
[08:52:38.714] Timer tick, asking Buildbucket for the build status
[08:52:38.868] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:38.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:56:0) after 1m0s
[08:53:38.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:56:0)
[08:53:38.888] Timer tick, asking Buildbucket for the build status
[08:53:39.138] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:39.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:57:0) after 1m0s
[08:54:39.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:57:0)
[08:54:39.162] Timer tick, asking Buildbucket for the build status
[08:54:39.275] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:39.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:58:0) after 1m0s
[08:55:39.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:58:0)
[08:55:39.329] Timer tick, asking Buildbucket for the build status
[08:55:39.408] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:39.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:59:0) after 1m0s
[08:56:39.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:59:0)
[08:56:39.393] Timer tick, asking Buildbucket for the build status
[08:56:39.499] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:39.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:60:0) after 1m0s
[08:57:39.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:60:0)
[08:57:39.519] Timer tick, asking Buildbucket for the build status
[08:57:39.739] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:39.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:61:0) after 1m0s
[08:58:39.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:61:0)
[08:58:39.769] Timer tick, asking Buildbucket for the build status
[08:58:39.893] Build 8860772814182281008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:39.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026202876218417584:62:0) after 1m0s
[08:59:29.553] Received PubSub notification, asking Buildbucket for the build status
[08:59:29.630] Build 8860772814182281008: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:59:29.630] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608109166152767",
"created_by": "project:chromium",
"created_ts": "1608105565811309",
"id": "8860772814182281008",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5d95bb8020aad58f325abbc095d7f56737b835df\"}}",
"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\": \"5d95bb8020aad58f325abbc095d7f56737b835df\", \"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-us-central1-b-32-1-qsbv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16256, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"98b56e604256fa0ced29cfc1ee943e8a62241d5a\", \"got_dawn_revision\": \"fea4c14580b11322b8ab0ced991dd42141ab4e89\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"5d95bb8020aad58f325abbc095d7f56737b835df\", \"got_revision_cp\": \"refs/heads/master@{#837469}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1ca6504eb7bb9971d317cf8c8033b38b914036b7\", \"got_v8_revision\": \"24e738a6bc98c6971154c655e17f656297085c45\", \"got_v8_revision_cp\": \"refs/heads/8.9.157@{#1}\", \"got_webrtc_revision\": \"cf8ec1f6867651204d81b14a8c31a86e6eb97572\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32829}\", \"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\": \"5d95bb8020aad58f325abbc095d7f56737b835df\", \"swarm_hashes_refs/heads/master(at){#837469}_without_patch\": {\"absl_hardening_tests\": \"e59c7483ede3af39a1f66692a0a55eb4e34a4537\", \"accessibility_unittests\": \"796701044213c8d2f91311980d3d90c4a58d4d27\", \"angle_unittests\": \"9fdb61c56d25157d62d2182e4087b20a10fa4cf5\", \"app_shell_unittests\": \"a7d4dcb5d43ac5a1bf72a06a9e5943e29b95d491\", \"aura_unittests\": \"b966123531bf893509732cb0ea2f936ec47b8014\", \"base_unittests\": \"9c3219ce4e204f44b1bc8c2f6c5547f8df027957\", \"base_util_unittests\": \"c4199c23533d9b9a38693aa6f445f5ebccdd26fa\", \"blink_common_unittests\": \"bd83b9d031626733625c0d3b26c7a5e4faa2f794\", \"blink_fuzzer_unittests\": \"db2718832661cdef2dc99155acb924b83d5e8294\", \"blink_heap_unittests\": \"364b11f201e66386854a01edf5b87265dc0c00c4\", \"blink_platform_unittests\": \"ef49b99c08125b779cf6f063a398726185f78523\", \"blink_unittests\": \"6890d8effa6ff0d0a2ed68fe98485b272b52d870\", \"boringssl_crypto_tests\": \"27b0dcc868ea2be27f39503c3a5c17980668f933\", \"boringssl_ssl_tests\": \"d96446b43a1bb9053cc7a84a1b0176cee663bd5f\", \"browser_switcher_bho_unittests\": \"ae8abe59ef4461e1dc90f7a55e256380d6f437f5\", \"browser_tests\": \"00e39f1d331973b0aeaae35fd062af26305ca37d\", \"capture_unittests\": \"36669398e69f739412f5e0082f089eae8fa7136c\", \"cast_unittests\": \"a53a36364f9e8dc9e0b50c623e0269d851edce58\", \"cc_unittests\": \"520247632bf322016a07f6404f3406af89b16d08\", \"chrome_app_unittests\": \"67a66e8ebbe7ec52fbf02cf0ac98df88942f6cd7\", \"chrome_cleaner_unittests\": \"dd7c0caa52f82605b9825f4bfe5a78f05acfd84c\", \"chrome_elf_unittests\": \"9018808abe3263b981db9f2ea850b80be6eb22aa\", \"chromedriver_unittests\": \"d1974edf4bf6f49d767f123810599958d1964eeb\", \"components_browsertests\": \"6f77536ebeb2d377f604ea3cf619e0d8d1cf633e\", \"components_unittests\": \"c26261489a80a7e74f7a7decf6a9caa079faf9f8\", \"compositor_unittests\": \"18a2354d1d3bb8e51caaa70e62153a1114e746dc\", \"content_browsertests\": \"36b50c71168f5eb92f2e67f958b09da874889afa\", \"content_unittests\": \"f625e04ee732a8924f7748909aecd69b048d813c\", \"courgette_unittests\": \"d0e6907564354eff148a39e72fbbec4c7e8e8e6e\", \"crashpad_tests\": \"4347417517d97480161ec709c3e84d6e08dd19c9\", \"cronet_tests\": \"7c527e837bc6acd4e3d3dd024eebb6c7bc636f33\", \"cronet_unittests\": \"83e3aa0856cb14058c6e288ca6e0689d8180737f\", \"crypto_unittests\": \"1c5294ff991a9bb511c493886b90cb04ebfd9966\", \"delayloads_unittests\": \"6105e224912713cda4bbd7474b0e9cd365d00835\", \"device_unittests\": \"8e0a5f39e5deb484899d4fc29513612327cdf7df\", \"display_unittests\": \"d27911d00395d6c0c473e6e23e1be51ae91959e6\", \"elevation_service_unittests\": \"7cc72277d9153b3c9dcd19a5c73a80cc642f489b\", \"events_unittests\": \"b3f3618bd840dc56db0e5e65a5b27c51d6b43cf2\", \"extensions_browsertests\": \"1110862a5a91938bdfa1da957d80dd879721a99b\", \"extensions_unittests\": \"fb66b345db5cbcf970923ee3c3b48ee49529ac47\", \"filesystem_service_unittests\": \"05ba2464d181cf6b5282c3aca2867424ad75c880\", \"gcm_unit_tests\": \"717dcafa88fca467e001f9d7fae81962bfc4ade7\", \"gcp_unittests\": \"245982e1b00f604686594749cbaf5c987dd97bb3\", \"gfx_unittests\": \"1b42a1450d2af850be565eef541214ec1bed20bc\", \"gin_unittests\": \"797012a24e05d93f0f049712bc12bf88da6bc23a\", \"google_apis_unittests\": \"258189342a20b58d4bdfc44c21359da0db6121fd\", \"gpu_unittests\": \"7b5063e25c61f97d761db5e8cf06b8bb86471c3e\", \"gwp_asan_unittests\": \"33e7e011a3b1f991f44b156f492ad7f1a615ddf7\", \"headless_browsertests\": \"91d618910c3e467bebcb68880d807488e23f26be\", \"headless_unittests\": \"f7b67b8b88bea7c922a4ac036206742cd37a2bcb\", \"install_static_unittests\": \"56c4f64f2a8a4eb7f34e4afbd35678a1672b72bb\", \"installer_util_unittests\": \"42e987897ac2213b7d1e651121215c486f49fb44\", \"interactive_ui_tests\": \"30dd00c4748001aecef5dc63cde1aa3d6635f253\", \"ipc_tests\": \"82a61fdf643c06400ae9a0448c4360ece59d7b4b\", \"jingle_unittests\": \"cd0527d2768222eec0f3aba87d3bc0dacf02e519\", \"latency_unittests\": \"c4942b560fc8a2ece0f8243197f5654408718ba3\", \"libjingle_xmpp_unittests\": \"f349bc1015493991fa0c3647bacfc5270f2253ad\", \"liburlpattern_unittests\": \"c7c57cdbf859803ef8ec8230343ca6f028a5565a\", \"media_blink_unittests\": \"aecdc9abc1e7533d95d13271e5788eeeeccc0045\", \"media_unittests\": \"ad725686cfb788cbce9c61d8b3dc7a8f8fe6ec3b\", \"message_center_unittests\": \"1d14969785665aab1eaad73e228bf74654ee4cf1\", \"midi_unittests\": \"d56ea7e1ad6fd7ab68fa71f92f1cc44a9d72f516\", \"mojo_core_unittests\": \"88e3c5bfd9a07c6a1d131fd55b60b7315c2b262d\", \"mojo_unittests\": \"d36b3039b2f411452a402f2cbc73f2da93c0e839\", \"nacl_loader_unittests\": \"dd17f4fb9d1a365bca9ce8c92986f4e30913f8e8\", \"native_theme_unittests\": \"43b7a98f8cc75b90a7037cb63f622ef9f6649b4c\", \"net_unittests\": \"31687511c84b921489e2e7b059d798d68909fff2\", \"notification_helper_unittests\": \"9c740b572cb391327958b85656a9452c08d3bf46\", \"pdf_unittests\": \"3d4bc90b4ee555c184d5f82cd35fc02d914c812b\", \"perfetto_unittests\": \"5981ce096516e1c3aa4f79ce30e7cf1f654a6a59\", \"ppapi_unittests\": \"b2ee367752cf334df1cb498fac8f2619a4f46ebc\", \"printing_unittests\": \"56c4e047ee19e298ce6f20f2d93aa74523cdfcf4\", \"remoting_unittests\": \"4494ad17effe49d4f7ef204eed25f79daa808bb6\", \"sbox_integration_tests\": \"c062bfa86fb8b19748dd650138b92eacad3776b1\", \"sbox_unittests\": \"811c0cc04113a511d22675066c30650dd058d6a1\", \"sbox_validation_tests\": \"5f6b71e520ba11793a3654ee05e4a540bf11f4d3\", \"service_manager_unittests\": \"9cb3f2a76cceccf5e2ef2eaa33bdfd3398b4856b\", \"services_unittests\": \"082efae17d68b87a6ccb1e8525922c11409fef16\", \"setup_unittests\": \"89a54a73daa45b86a93f783c2c2572468028303d\", \"shell_dialogs_unittests\": \"c40c458d018b70c3ae45447f1e668086470a9972\", \"skia_unittests\": \"956481d28c534a215ec0407e287012ade6bf53eb\", \"snapshot_unittests\": \"0def9f520b0eb8258f6c56b5ca50b75969687406\", \"sql_unittests\": \"ae9a7cd04277de1b36e67a5a2b0a2551f04c48d9\", \"storage_unittests\": \"f3f89dba978e59a3fc9010a1088f92797947b5b6\", \"sync_integration_tests\": \"b13a322f3173665de36ee3ef6437db98a2d48cc9\", \"traffic_annotation_auditor_unittests\": \"3dbcabf1af255e549353670534b2868a2a1c3388\", \"ui_base_unittests\": \"e7ca69d98352fa46a3bf24427fb47a02407c2bb8\", \"ui_touch_selection_unittests\": \"476b89e00a77c78c8d8e956336f5f4a9e797b856\", \"unit_tests\": \"1ee48f69dff56cdd7a5d8d9aaca214e8967e0b3c\", \"updater_tests\": \"91504b1a1707852bcde116f5720359dcf03e09af\", \"url_unittests\": \"cdc133a5227fb0caf886cc6a3330e9a150db3813\", \"views_unittests\": \"b46be0fe73fcce2bdce5e256e86fb8af1da0f26a\", \"viz_unittests\": \"b58af2e76d274c0fa262c5adfcfee67782f3a900\", \"vr_common_unittests\": \"b1cfe5f69bce2790e45e1a60c9f04106df36ffa3\", \"vr_pixeltests\": \"085f327f8b1e1ddd803acbfede7a1ede896d7487\", \"weblayer_browsertests\": \"ac1186dfb2d2a86f178d99a033308cea3493e260\", \"weblayer_unittests\": \"d95aba00753987bbdf14d8c68d0cc5dbe2653d23\", \"wm_unittests\": \"53932776cc8edcbc995a7fbd5467c6389512e094\", \"wtf_unittests\": \"b64d76820a81fa99a4bd2252656d8a4ca86f8393\", \"zlib_unittests\": \"4ddb142a4d260cda68e9a1fc51f44d7c3f9a69ef\", \"zucchini_unittests\": \"466d8f4c62fafea967a91ed8d91c0b56467264fc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91c730c3e6ead475130e2ae78bc3f03e09cb9dcb91c3469d4450b29020110401_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-qsbv\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608105569494135",
"status": "COMPLETED",
"status_changed_ts": "1608109166992921",
"tags": [
"build_address:luci.chromium.ci/win-asan/16256",
"builder:win-asan",
"buildset:commit/git/5d95bb8020aad58f325abbc095d7f56737b835df",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5d95bb8020aad58f325abbc095d7f56737b835df",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026202876218417584",
"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/8860772814182281008/+/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:50836129ac624b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608109167068973",
"url": "https://ci.chromium.org/b/8860772814182281008",
"utcnow_ts": "1608109169621263"
}
}
[08:59:29.630] Invocation finished in 1h0m5.143912637s with status SUCCEEDED