[16:03:30.587] New invocation is queued and will start shortly
[16:03:31.785] Starting the invocation (attempt 1)
[16:03:31.826] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:03:31.826] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:03:31.826] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019377646711755600",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f4fb3fde6e54f97202753ccc5fff3045cebda1e4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019377646711755600",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f4fb3fde6e54f97202753ccc5fff3045cebda1e4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f4fb3fde6e54f97202753ccc5fff3045cebda1e4",
"gitiles_ref:refs/heads/master"
]
}
[16:03:32.704] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614614611905403",
"id": "8853947584665177264",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f4fb3fde6e54f97202753ccc5fff3045cebda1e4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614614612487874",
"tags": [
"build_address:luci.chromium.ci/win-asan/17576",
"builder:win-asan",
"buildset:commit/git/f4fb3fde6e54f97202753ccc5fff3045cebda1e4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f4fb3fde6e54f97202753ccc5fff3045cebda1e4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019377646711755600",
"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/8853947584665177264/+/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": "1614614612488120",
"url": "https://ci.chromium.org/b/8853947584665177264",
"utcnow_ts": "1614614612652385"
}
}
[16:03:32.704] Task URL: https://ci.chromium.org/b/8853947584665177264
[16:03:32.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:2:0) after 1m0s
[16:03:36.705] Received PubSub notification, asking Buildbucket for the build status
[16:03:36.811] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:32.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:2:0)
[16:04:32.731] Timer tick, asking Buildbucket for the build status
[16:04:32.931] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:32.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:4:0) after 1m0s
[16:05:32.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:4:0)
[16:05:32.940] Timer tick, asking Buildbucket for the build status
[16:05:33.025] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:33.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:5:0) after 1m0s
[16:06:33.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:5:0)
[16:06:33.040] Timer tick, asking Buildbucket for the build status
[16:06:33.175] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:33.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:6:0) after 1m0s
[16:07:33.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:6:0)
[16:07:33.984] Timer tick, asking Buildbucket for the build status
[16:07:34.527] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:34.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:7:0) after 1m0s
[16:08:34.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:7:0)
[16:08:34.671] Timer tick, asking Buildbucket for the build status
[16:08:34.802] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:34.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:8:0) after 1m0s
[16:09:35.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:8:0)
[16:09:35.452] Timer tick, asking Buildbucket for the build status
[16:09:36.238] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:36.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:9:0) after 1m0s
[16:10:37.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:9:0)
[16:10:37.760] Timer tick, asking Buildbucket for the build status
[16:10:37.857] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:37.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:10:0) after 1m0s
[16:11:39.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:10:0)
[16:11:39.039] Timer tick, asking Buildbucket for the build status
[16:11:39.141] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:39.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:11:0) after 1m0s
[16:12:39.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:11:0)
[16:12:39.189] Timer tick, asking Buildbucket for the build status
[16:12:39.273] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:39.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:12:0) after 1m0s
[16:13:39.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:12:0)
[16:13:39.294] Timer tick, asking Buildbucket for the build status
[16:13:39.988] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:39.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:13:0) after 1m0s
[16:14:40.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:13:0)
[16:14:40.103] Timer tick, asking Buildbucket for the build status
[16:14:40.300] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:40.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:14:0) after 1m0s
[16:15:40.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:14:0)
[16:15:40.348] Timer tick, asking Buildbucket for the build status
[16:15:40.528] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:40.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:15:0) after 1m0s
[16:16:40.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:15:0)
[16:16:40.559] Timer tick, asking Buildbucket for the build status
[16:16:40.664] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:40.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:16:0) after 1m0s
[16:17:40.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:16:0)
[16:17:40.708] Timer tick, asking Buildbucket for the build status
[16:17:40.858] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:40.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:17:0) after 1m0s
[16:18:40.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:17:0)
[16:18:40.916] Timer tick, asking Buildbucket for the build status
[16:18:41.077] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:41.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:18:0) after 1m0s
[16:19:41.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:18:0)
[16:19:41.375] Timer tick, asking Buildbucket for the build status
[16:19:41.468] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:41.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:19:0) after 1m0s
[16:20:41.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:19:0)
[16:20:41.629] Timer tick, asking Buildbucket for the build status
[16:20:41.718] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:41.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:20:0) after 1m0s
[16:21:41.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:20:0)
[16:21:41.874] Timer tick, asking Buildbucket for the build status
[16:21:41.971] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:41.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:21:0) after 1m0s
[16:22:42.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:21:0)
[16:22:42.032] Timer tick, asking Buildbucket for the build status
[16:22:42.348] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:42.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:22:0) after 1m0s
[16:23:42.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:22:0)
[16:23:42.401] Timer tick, asking Buildbucket for the build status
[16:23:42.539] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:42.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:23:0) after 1m0s
[16:24:42.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:23:0)
[16:24:42.577] Timer tick, asking Buildbucket for the build status
[16:24:42.676] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:42.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:24:0) after 1m0s
[16:25:42.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:24:0)
[16:25:42.693] Timer tick, asking Buildbucket for the build status
[16:25:42.794] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:42.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:25:0) after 1m0s
[16:26:42.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:25:0)
[16:26:42.836] Timer tick, asking Buildbucket for the build status
[16:26:42.918] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:42.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:26:0) after 1m0s
[16:27:42.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:26:0)
[16:27:42.996] Timer tick, asking Buildbucket for the build status
[16:27:43.339] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:43.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:27:0) after 1m0s
[16:28:43.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:27:0)
[16:28:43.354] Timer tick, asking Buildbucket for the build status
[16:28:43.455] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:43.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:28:0) after 1m0s
[16:29:43.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:28:0)
[16:29:43.447] Timer tick, asking Buildbucket for the build status
[16:29:43.555] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:43.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:29:0) after 1m0s
[16:30:43.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:29:0)
[16:30:43.784] Timer tick, asking Buildbucket for the build status
[16:30:44.117] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:44.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:30:0) after 1m0s
[16:31:44.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:30:0)
[16:31:44.507] Timer tick, asking Buildbucket for the build status
[16:31:44.633] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:44.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:31:0) after 1m0s
[16:32:44.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:31:0)
[16:32:44.723] Timer tick, asking Buildbucket for the build status
[16:32:45.465] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:45.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:32:0) after 1m0s
[16:33:45.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:32:0)
[16:33:45.540] Timer tick, asking Buildbucket for the build status
[16:33:46.622] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:46.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:33:0) after 1m0s
[16:34:46.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:33:0)
[16:34:46.706] Timer tick, asking Buildbucket for the build status
[16:34:46.828] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:46.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:34:0) after 1m0s
[16:35:46.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:34:0)
[16:35:46.890] Timer tick, asking Buildbucket for the build status
[16:35:47.014] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:47.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:35:0) after 1m0s
[16:36:47.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:35:0)
[16:36:47.018] Timer tick, asking Buildbucket for the build status
[16:36:47.318] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:47.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:36:0) after 1m0s
[16:37:47.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:36:0)
[16:37:47.345] Timer tick, asking Buildbucket for the build status
[16:37:47.471] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:47.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:37:0) after 1m0s
[16:38:47.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:37:0)
[16:38:47.553] Timer tick, asking Buildbucket for the build status
[16:38:47.664] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:47.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:38:0) after 1m0s
[16:39:47.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:38:0)
[16:39:47.709] Timer tick, asking Buildbucket for the build status
[16:39:48.039] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:48.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:39:0) after 1m0s
[16:40:48.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:39:0)
[16:40:48.057] Timer tick, asking Buildbucket for the build status
[16:40:48.194] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:48.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:40:0) after 1m0s
[16:41:48.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:40:0)
[16:41:48.246] Timer tick, asking Buildbucket for the build status
[16:41:48.364] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:48.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:41:0) after 1m0s
[16:42:48.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:41:0)
[16:42:48.440] Timer tick, asking Buildbucket for the build status
[16:42:48.715] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:48.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:42:0) after 1m0s
[16:43:48.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:42:0)
[16:43:48.793] Timer tick, asking Buildbucket for the build status
[16:43:48.865] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:48.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:43:0) after 1m0s
[16:44:49.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:43:0)
[16:44:49.000] Timer tick, asking Buildbucket for the build status
[16:44:49.072] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:49.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:44:0) after 1m0s
[16:45:49.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:44:0)
[16:45:49.207] Timer tick, asking Buildbucket for the build status
[16:45:49.459] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:49.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:45:0) after 1m0s
[16:46:49.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:45:0)
[16:46:49.575] Timer tick, asking Buildbucket for the build status
[16:46:49.691] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:49.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:46:0) after 1m0s
[16:47:49.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:46:0)
[16:47:49.911] Timer tick, asking Buildbucket for the build status
[16:47:50.262] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:50.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:47:0) after 1m0s
[16:48:50.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:47:0)
[16:48:50.389] Timer tick, asking Buildbucket for the build status
[16:48:50.486] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:50.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:48:0) after 1m0s
[16:49:50.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:48:0)
[16:49:50.558] Timer tick, asking Buildbucket for the build status
[16:49:50.794] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:50.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:49:0) after 1m0s
[16:50:50.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:49:0)
[16:50:50.834] Timer tick, asking Buildbucket for the build status
[16:50:50.910] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:50.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:50:0) after 1m0s
[16:51:50.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:50:0)
[16:51:50.956] Timer tick, asking Buildbucket for the build status
[16:51:51.031] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:51.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:51:0) after 1m0s
[16:52:51.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:51:0)
[16:52:51.086] Timer tick, asking Buildbucket for the build status
[16:52:51.221] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:51.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:52:0) after 1m0s
[16:53:51.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:52:0)
[16:53:51.265] Timer tick, asking Buildbucket for the build status
[16:53:51.355] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:51.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:53:0) after 1m0s
[16:54:51.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:53:0)
[16:54:51.378] Timer tick, asking Buildbucket for the build status
[16:54:51.470] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:51.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:54:0) after 1m0s
[16:55:51.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:54:0)
[16:55:51.489] Timer tick, asking Buildbucket for the build status
[16:55:51.581] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:51.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:55:0) after 1m0s
[16:56:51.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:55:0)
[16:56:51.604] Timer tick, asking Buildbucket for the build status
[16:56:51.799] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:51.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:56:0) after 1m0s
[16:57:51.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:56:0)
[16:57:51.865] Timer tick, asking Buildbucket for the build status
[16:57:51.962] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:51.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:57:0) after 1m0s
[16:58:51.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:57:0)
[16:58:51.970] Timer tick, asking Buildbucket for the build status
[16:58:52.086] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:52.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:58:0) after 1m0s
[16:59:52.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:58:0)
[16:59:52.081] Timer tick, asking Buildbucket for the build status
[16:59:52.190] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:52.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:59:0) after 1m0s
[17:00:52.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:59:0)
[17:00:52.211] Timer tick, asking Buildbucket for the build status
[17:00:52.344] Build 8853947584665177264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:52.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019377646711755600:60:0) after 1m0s
[17:01:02.480] Received PubSub notification, asking Buildbucket for the build status
[17:01:02.649] Build 8853947584665177264: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:01:02.650] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614618057606389",
"created_by": "project:chromium",
"created_ts": "1614614611905403",
"id": "8853947584665177264",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f4fb3fde6e54f97202753ccc5fff3045cebda1e4\"}}",
"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\": \"f4fb3fde6e54f97202753ccc5fff3045cebda1e4\", \"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-2-2mmz\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17576, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"0e11e49d1937d44ffb6345b115d13cf8cee24695\", \"got_dawn_revision\": \"19f111f69c550f5cddec76557a0b7808adae9c66\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"f4fb3fde6e54f97202753ccc5fff3045cebda1e4\", \"got_revision_cp\": \"refs/heads/master@{#858564}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"45e415150419491d6d12428ba6ee98876a405ba4\", \"got_v8_revision_cp\": \"refs/heads/9.1.1@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f4fb3fde6e54f97202753ccc5fff3045cebda1e4\", \"swarm_hashes_refs/heads/master(at){#858564}_without_patch\": {\"absl_hardening_tests\": \"3748e530a9a28fbdafeb1765c4ed7cdbaef733fa\", \"accessibility_unittests\": \"205a3b22f7994249537b1014fc7e519cc0045898\", \"angle_unittests\": \"7125d320ef4b3e0604245844edd41cdbf7018cd1\", \"app_shell_unittests\": \"9d758d698151f854c59448840d19b24484f77235\", \"aura_unittests\": \"0ea1442d2c591f1c0fae2ddf533de6bf5fde7209\", \"base_unittests\": \"4df8f545ae061b3927964a0549b1a02fdc067b00\", \"base_util_unittests\": \"1dae3bb5255f3030cbd7ae2f0a8b7513a1ad2743\", \"blink_common_unittests\": \"72a97e52249fdb83e666dff82970e724871f6061\", \"blink_fuzzer_unittests\": \"412f830ca39c6390c34801dd4f9390d0eb908239\", \"blink_heap_unittests\": \"ca3c353a7e0dcd8f6e3dd3795214efe6a5470bac\", \"blink_platform_unittests\": \"d02595264e40ac9cd4763cc946143f125c15acb7\", \"blink_unittests\": \"ba699894bc821b913e5fcc34f59e303dfd00b7e0\", \"boringssl_crypto_tests\": \"2f9e00fafbf6eb707d6aed78d71c2c60c279913a\", \"boringssl_ssl_tests\": \"bf4dcb825400801c786be47307f8d1b17d972b25\", \"browser_switcher_bho_unittests\": \"7d3ddd29f2daa6808797021a5895b0b5df252ba6\", \"browser_tests\": \"b35517269851be0f7a44391d2af75c29aef38201\", \"capture_unittests\": \"48b8ee5347b4042293a9dba8a49cb2296dfadc55\", \"cast_unittests\": \"d1364584f61d18598c1e4fa8c294e1f726d37688\", \"cc_unittests\": \"d13425b43e2f1832aa2685138b43632e4a0bfb8e\", \"chrome_app_unittests\": \"41c77989354fe3745a12b61791c80a0348059d1c\", \"chrome_cleaner_unittests\": \"9c99b0bde8d6e755affb1776907e10542b413017\", \"chrome_elf_unittests\": \"7089da5d7c12e89d3286643d19d1da6dfa9b65f4\", \"chromedriver_unittests\": \"936b54cc6a9a1046cb72240f57d4658957576151\", \"color_unittests\": \"1ac1fe81ef486f38934b53c4ea6c3ffbf150f006\", \"components_browsertests\": \"21b0a10c8c68296d77cbe877b91f8737c8d779a8\", \"components_unittests\": \"12b19fe5499a88c4ca7be7ea66bafb61031e935d\", \"compositor_unittests\": \"467da011c9d46f919fbaea91d925c82d5dec1c9b\", \"content_browsertests\": \"46c74194c1a286aef2c66f01e10d553cf2620869\", \"content_unittests\": \"f50959549f7a17d90be616bd7141fd5689bdd635\", \"courgette_unittests\": \"144a1ebfabb870a27b48883f0c05d0d09ba38eff\", \"crashpad_tests\": \"b6026cd1685502f170b5515c69bc415410a8c5bd\", \"cronet_tests\": \"314dbf60e7136c3d7db85dc94113c80fed4614d9\", \"cronet_unittests\": \"c6c00d2855fc54994d988e0e3d59af923ef06785\", \"crypto_unittests\": \"afc98410281c94dd8c5b3f6da6785732a2ebdcc9\", \"delayloads_unittests\": \"09cad19b4cd59689786f64d7896d495591d46c07\", \"device_unittests\": \"97633e0c17af9233d345eda0ceac368252272d77\", \"display_unittests\": \"b2ed6631cffce654b7d6b754c8fd57080884b614\", \"elevation_service_unittests\": \"9c60ab5f5b304bfe5608c9806c34d22d23fbc59e\", \"events_unittests\": \"0f72a97c71e9510cd7f1f131480988832522746c\", \"extensions_browsertests\": \"6015356b5db2d2c2a309b701d59e7f942bc1b29d\", \"extensions_unittests\": \"8838f540594b0875ca3c749b8d30ffc4e8ee4329\", \"filesystem_service_unittests\": \"c673056f033183b70cce10fa74e10d889d49d2e0\", \"gcm_unit_tests\": \"9759d0e0eb6bd083f99473838ccc1e612e0db356\", \"gcp_unittests\": \"cdec4c5770bf85faee9acc94d00eadcd66ebdcd0\", \"gfx_unittests\": \"a3848753be27e556daa2ce4f1c00a8b968a8b9cc\", \"gin_unittests\": \"18dc660298f47c99c5daee13be2344968b6c35bb\", \"google_apis_unittests\": \"31e90a3693524e9238ac8d560ab9e23a53cb04fd\", \"gpu_unittests\": \"467f1905a4e3caea1707f4c1d79018373feae22c\", \"gwp_asan_unittests\": \"1ff962d30861576a942c17a99240a29bd14f15b4\", \"headless_browsertests\": \"e78f11f6a6c476ca6d4b28dbd48ab833649be7a3\", \"headless_unittests\": \"be3f9fb450061feb5e9a89035d4e8ab17df38ca1\", \"install_static_unittests\": \"5c83a1aefcfeb15a7fadab80a7fa3ab3254900c6\", \"installer_util_unittests\": \"6001e61a6a8ff42a38511d355b858897ae6a98eb\", \"interactive_ui_tests\": \"7c26c0cc04d0aa180067975905ef7b4afc8b2aeb\", \"ipc_tests\": \"a09ee0821a0a2424432db195a5ca477b8e85a977\", \"jingle_unittests\": \"f3d41615676004dfd66d8c134610c0d6d9b2cbbc\", \"latency_unittests\": \"c4afe44cb4d79c468f1d990c9b57ca8318ed9d34\", \"libjingle_xmpp_unittests\": \"6750ee7a7117c2246b52b34841e12857d693b3f4\", \"liburlpattern_unittests\": \"4942b1ac8dae595d8ee2330ab26cdfb453ca547c\", \"media_blink_unittests\": \"6bee3ab5c2570818a9e50fe1ad3ff4c1d2dfa5fe\", \"media_unittests\": \"0b09fb1978474fe1f8984c19a34f55b76e3eb1ef\", \"message_center_unittests\": \"0197ccd5771642a78488a054d284e4b5013a1413\", \"midi_unittests\": \"64501ad57a1888e6c4a2c4eefcdae8eb7e863efa\", \"mojo_core_unittests\": \"9f293de5b8f48ad63d798001b6c750ffe43fec92\", \"mojo_unittests\": \"bf6ed60b062e62391d87dc2bff593223d5ab8631\", \"nacl_loader_unittests\": \"59ab27df3f422236d8a9652624d612fb44682c61\", \"native_theme_unittests\": \"15cc02d1e068746c26d89b235aa278fec37f5981\", \"net_unittests\": \"9c59da53937ec0efa72e85b1e7fc17494955d4d4\", \"notification_helper_unittests\": \"f1b00f438c9c89c5962c86e176ecca1f040f4534\", \"pdf_unittests\": \"68e4c2dd5854e78f6c3e3c5e4ae892f27309ed36\", \"perfetto_unittests\": \"bd80926c78b0f787e8096b6d2e02d2e26e90b7c3\", \"ppapi_unittests\": \"48bce854ce6360779a6f661e586e4a31d5ec74e5\", \"printing_unittests\": \"592aff1841442fd9435e25d2eb0c3f6ddccccaf7\", \"remoting_unittests\": \"4915438050d025559ba15d952edad177f54e6868\", \"sbox_integration_tests\": \"f3090b94f4bc422bb1ef019a320a23e2f8bf6b2b\", \"sbox_unittests\": \"cd311bbeb8a98a6f62afc1d3f44608533359d023\", \"sbox_validation_tests\": \"0379f62ac5070f612301bc09d1dfe708684fddde\", \"service_manager_unittests\": \"caab37d8b9a47a6d95e147bbd45d3c80f6b42a4c\", \"services_unittests\": \"e1546931570ede6a1c4830d4bfeb592b1706179e\", \"setup_unittests\": \"fcdc6127d33bd43533f08d0b3dae287e38dd8150\", \"shell_dialogs_unittests\": \"c3ca699295464ebbd8dc3d2c1953397a46a07c4f\", \"skia_unittests\": \"45f950ce66b6c6ba148e4f10127515f1cc9ef9f9\", \"snapshot_unittests\": \"96533cdc9eb8ed638e3527c2800befaaa6ed3710\", \"sql_unittests\": \"36291e904617bbd031853c87e008d566645948bb\", \"storage_unittests\": \"98fbd65385348b2bf2de5b5e147deea589273361\", \"sync_integration_tests\": \"2e088b46acc23d0b08e934436c3463d2f1d52ee6\", \"traffic_annotation_auditor_unittests\": \"18f5faccdf4dda401da2cb0554c7c7f29e6b094b\", \"ui_base_unittests\": \"235f67e83cbcb9eacbfec70716d1789f3309e7f0\", \"ui_touch_selection_unittests\": \"3e79f6c8451fbb8b57aa592b42d2a98f5a03dffe\", \"unit_tests\": \"18debf6a2f2801b4f9199b412bc7428ce478b160\", \"updater_tests\": \"3716fe3ae9269d5e7f279377097598c26c39844c\", \"url_unittests\": \"d26435cacdc06452174d6e02c2a176aae2e2d5f6\", \"views_unittests\": \"68ecea270c14f97b0e8c6d9e8f1563c397325733\", \"viz_unittests\": \"1ebe5155db48b22180b45c3c722812b5772e6cd5\", \"vr_common_unittests\": \"c2af04f14401f7d049d591d7b8fff30aeefc35b1\", \"vr_pixeltests\": \"fb00fccf1c6f7eabf989413ae8d8cc1ab0b54ad4\", \"weblayer_browsertests\": \"f8b5b4ea01522c0f5404dd6ca7071a88bb0f91ee\", \"weblayer_unittests\": \"65f8d376c15df00b0d902482d619411393321681\", \"wm_unittests\": \"a60afa914b7efd703c2ba8adc3ae11fac9a33c8d\", \"wtf_unittests\": \"505203f95024bcfb89b8c9e350840b4b16bd414e\", \"zlib_unittests\": \"92ca985bef4487ca40a3a8c7be4b1b445d425453\", \"zucchini_unittests\": \"3b5711a1d2f10876590b556555e8b376ed3b8d99\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-2-2mmz\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5712-339372b\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614614615477052",
"status": "COMPLETED",
"status_changed_ts": "1614618058623613",
"tags": [
"build_address:luci.chromium.ci/win-asan/17576",
"builder:win-asan",
"buildset:commit/git/f4fb3fde6e54f97202753ccc5fff3045cebda1e4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f4fb3fde6e54f97202753ccc5fff3045cebda1e4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019377646711755600",
"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/8853947584665177264/+/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:520759533b03b510",
"user_agent:luci-scheduler"
],
"updated_ts": "1614618058656487",
"url": "https://ci.chromium.org/b/8853947584665177264",
"utcnow_ts": "1614618062637645"
}
}
[17:01:02.650] Invocation finished in 57m32.079384392s with status SUCCEEDED