[20:17:17.171] New invocation is queued and will start shortly
[20:17:18.268] Starting the invocation (attempt 1)
[20:17:18.327] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:17:18.327] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:17:18.327] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022441977337839696",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f74debdff1b26ebd3dbc260233bf4ac3fbafabc2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022441977337839696",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f74debdff1b26ebd3dbc260233bf4ac3fbafabc2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f74debdff1b26ebd3dbc260233bf4ac3fbafabc2",
"gitiles_ref:refs/heads/master"
]
}
[20:17:20.077] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611692238461542",
"id": "8857011915321989856",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f74debdff1b26ebd3dbc260233bf4ac3fbafabc2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611692239647025",
"tags": [
"build_address:luci.chromium.ci/win-asan/17024",
"builder:win-asan",
"buildset:commit/git/f74debdff1b26ebd3dbc260233bf4ac3fbafabc2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f74debdff1b26ebd3dbc260233bf4ac3fbafabc2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022441977337839696",
"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/8857011915321989856/+/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": "1611692239647172",
"url": "https://ci.chromium.org/b/8857011915321989856",
"utcnow_ts": "1611692240018413"
}
}
[20:17:20.078] Task URL: https://ci.chromium.org/b/8857011915321989856
[20:17:20.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:2:0) after 1m0s
[20:17:26.395] Received PubSub notification, asking Buildbucket for the build status
[20:17:26.512] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:20.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:2:0)
[20:18:20.221] Timer tick, asking Buildbucket for the build status
[20:18:20.659] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:20.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:4:0) after 1m0s
[20:19:20.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:4:0)
[20:19:20.785] Timer tick, asking Buildbucket for the build status
[20:19:20.886] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:20.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:5:0) after 1m0s
[20:20:20.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:5:0)
[20:20:20.972] Timer tick, asking Buildbucket for the build status
[20:20:21.168] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:21.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:6:0) after 1m0s
[20:21:21.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:6:0)
[20:21:21.178] Timer tick, asking Buildbucket for the build status
[20:21:21.816] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:21.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:7:0) after 1m0s
[20:22:21.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:7:0)
[20:22:21.932] Timer tick, asking Buildbucket for the build status
[20:22:25.411] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:25.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:8:0) after 1m0s
[20:23:25.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:8:0)
[20:23:25.774] Timer tick, asking Buildbucket for the build status
[20:23:25.979] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:25.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:9:0) after 1m0s
[20:24:26.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:9:0)
[20:24:26.255] Timer tick, asking Buildbucket for the build status
[20:24:26.383] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:26.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:10:0) after 1m0s
[20:25:26.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:10:0)
[20:25:26.501] Timer tick, asking Buildbucket for the build status
[20:25:26.629] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:26.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:11:0) after 1m0s
[20:26:26.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:11:0)
[20:26:26.717] Timer tick, asking Buildbucket for the build status
[20:26:26.883] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:26.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:12:0) after 1m0s
[20:27:27.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:12:0)
[20:27:27.086] Timer tick, asking Buildbucket for the build status
[20:27:27.893] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:27.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:13:0) after 1m0s
[20:28:27.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:13:0)
[20:28:27.906] Timer tick, asking Buildbucket for the build status
[20:28:27.964] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:27.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:14:0) after 1m0s
[20:29:27.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:14:0)
[20:29:27.988] Timer tick, asking Buildbucket for the build status
[20:29:28.491] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:28.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:15:0) after 1m0s
[20:30:28.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:15:0)
[20:30:28.537] Timer tick, asking Buildbucket for the build status
[20:30:28.836] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:28.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:16:0) after 1m0s
[20:31:28.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:16:0)
[20:31:28.839] Timer tick, asking Buildbucket for the build status
[20:31:29.071] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:29.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:17:0) after 1m0s
[20:32:29.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:17:0)
[20:32:29.155] Timer tick, asking Buildbucket for the build status
[20:32:29.638] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:29.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:18:0) after 1m0s
[20:33:29.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:18:0)
[20:33:29.723] Timer tick, asking Buildbucket for the build status
[20:33:29.856] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:29.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:19:0) after 1m0s
[20:34:29.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:19:0)
[20:34:29.877] Timer tick, asking Buildbucket for the build status
[20:34:30.072] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:30.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:20:0) after 1m0s
[20:35:30.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:20:0)
[20:35:30.165] Timer tick, asking Buildbucket for the build status
[20:35:30.395] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:30.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:21:0) after 1m0s
[20:36:30.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:21:0)
[20:36:30.452] Timer tick, asking Buildbucket for the build status
[20:36:30.595] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:30.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:22:0) after 1m0s
[20:37:30.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:22:0)
[20:37:30.645] Timer tick, asking Buildbucket for the build status
[20:37:30.787] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:30.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:23:0) after 1m0s
[20:38:30.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:23:0)
[20:38:30.828] Timer tick, asking Buildbucket for the build status
[20:38:30.926] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:30.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:24:0) after 1m0s
[20:39:30.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:24:0)
[20:39:30.995] Timer tick, asking Buildbucket for the build status
[20:39:31.194] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:31.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:25:0) after 1m0s
[20:40:31.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:25:0)
[20:40:31.247] Timer tick, asking Buildbucket for the build status
[20:40:31.437] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:31.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:26:0) after 1m0s
[20:41:31.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:26:0)
[20:41:31.542] Timer tick, asking Buildbucket for the build status
[20:41:33.331] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:33.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:27:0) after 1m0s
[20:42:33.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:27:0)
[20:42:33.385] Timer tick, asking Buildbucket for the build status
[20:42:33.564] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:33.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:28:0) after 1m0s
[20:43:33.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:28:0)
[20:43:33.605] Timer tick, asking Buildbucket for the build status
[20:43:33.871] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:33.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:29:0) after 1m0s
[20:44:33.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:29:0)
[20:44:33.906] Timer tick, asking Buildbucket for the build status
[20:44:34.067] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:34.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:30:0) after 1m0s
[20:45:34.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:30:0)
[20:45:34.126] Timer tick, asking Buildbucket for the build status
[20:45:34.445] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:34.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:31:0) after 1m0s
[20:46:34.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:31:0)
[20:46:34.470] Timer tick, asking Buildbucket for the build status
[20:46:34.906] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:34.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:32:0) after 1m0s
[20:47:34.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:32:0)
[20:47:34.927] Timer tick, asking Buildbucket for the build status
[20:47:35.196] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:35.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:33:0) after 1m0s
[20:48:35.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:33:0)
[20:48:35.242] Timer tick, asking Buildbucket for the build status
[20:48:36.024] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:36.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:34:0) after 1m0s
[20:49:36.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:34:0)
[20:49:36.126] Timer tick, asking Buildbucket for the build status
[20:49:36.407] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:36.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:35:0) after 1m0s
[20:50:36.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:35:0)
[20:50:36.467] Timer tick, asking Buildbucket for the build status
[20:50:36.648] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:36.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:36:0) after 1m0s
[20:51:36.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:36:0)
[20:51:36.660] Timer tick, asking Buildbucket for the build status
[20:51:36.745] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:36.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:37:0) after 1m0s
[20:52:36.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:37:0)
[20:52:36.757] Timer tick, asking Buildbucket for the build status
[20:52:36.965] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:36.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:38:0) after 1m0s
[20:53:36.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:38:0)
[20:53:36.968] Timer tick, asking Buildbucket for the build status
[20:53:37.153] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:37.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:39:0) after 1m0s
[20:54:37.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:39:0)
[20:54:37.200] Timer tick, asking Buildbucket for the build status
[20:54:37.287] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:37.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:40:0) after 1m0s
[20:55:37.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:40:0)
[20:55:37.335] Timer tick, asking Buildbucket for the build status
[20:55:37.427] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:37.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:41:0) after 1m0s
[20:56:37.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:41:0)
[20:56:37.520] Timer tick, asking Buildbucket for the build status
[20:56:39.631] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:39.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:42:0) after 1m0s
[20:57:39.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:42:0)
[20:57:39.658] Timer tick, asking Buildbucket for the build status
[20:57:39.814] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:39.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:43:0) after 1m0s
[20:58:39.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:43:0)
[20:58:39.952] Timer tick, asking Buildbucket for the build status
[20:58:41.057] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:41.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:44:0) after 1m0s
[20:59:41.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:44:0)
[20:59:41.147] Timer tick, asking Buildbucket for the build status
[20:59:41.388] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:41.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:45:0) after 1m0s
[21:00:41.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:45:0)
[21:00:41.452] Timer tick, asking Buildbucket for the build status
[21:00:41.671] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:41.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:46:0) after 1m0s
[21:01:41.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:46:0)
[21:01:41.746] Timer tick, asking Buildbucket for the build status
[21:01:42.418] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:42.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:47:0) after 1m0s
[21:02:42.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:47:0)
[21:02:42.557] Timer tick, asking Buildbucket for the build status
[21:02:44.545] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:44.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:48:0) after 1m0s
[21:03:44.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:48:0)
[21:03:44.620] Timer tick, asking Buildbucket for the build status
[21:03:44.889] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:44.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:49:0) after 1m0s
[21:04:44.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:49:0)
[21:04:44.878] Timer tick, asking Buildbucket for the build status
[21:04:45.321] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:45.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:50:0) after 1m0s
[21:05:45.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:50:0)
[21:05:45.466] Timer tick, asking Buildbucket for the build status
[21:05:45.747] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:45.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:51:0) after 1m0s
[21:06:45.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:51:0)
[21:06:45.960] Timer tick, asking Buildbucket for the build status
[21:06:46.100] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:46.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:52:0) after 1m0s
[21:07:46.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:52:0)
[21:07:46.179] Timer tick, asking Buildbucket for the build status
[21:07:46.377] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:46.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:53:0) after 1m0s
[21:08:46.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:53:0)
[21:08:46.542] Timer tick, asking Buildbucket for the build status
[21:08:46.926] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:46.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:54:0) after 1m0s
[21:09:46.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:54:0)
[21:09:46.961] Timer tick, asking Buildbucket for the build status
[21:09:47.362] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:47.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:55:0) after 1m0s
[21:10:47.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:55:0)
[21:10:47.455] Timer tick, asking Buildbucket for the build status
[21:10:47.833] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:47.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:56:0) after 1m0s
[21:11:47.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:56:0)
[21:11:47.865] Timer tick, asking Buildbucket for the build status
[21:11:48.083] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:48.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:57:0) after 1m0s
[21:12:48.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:57:0)
[21:12:48.096] Timer tick, asking Buildbucket for the build status
[21:12:48.256] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:48.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:58:0) after 1m0s
[21:13:48.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:58:0)
[21:13:48.318] Timer tick, asking Buildbucket for the build status
[21:13:49.932] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:49.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:59:0) after 1m0s
[21:14:50.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:59:0)
[21:14:50.048] Timer tick, asking Buildbucket for the build status
[21:14:50.271] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:50.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:60:0) after 1m0s
[21:15:50.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:60:0)
[21:15:50.302] Timer tick, asking Buildbucket for the build status
[21:15:50.429] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:50.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:61:0) after 1m0s
[21:16:50.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:61:0)
[21:16:50.476] Timer tick, asking Buildbucket for the build status
[21:16:50.987] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:50.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:62:0) after 1m0s
[21:17:51.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:62:0)
[21:17:51.010] Timer tick, asking Buildbucket for the build status
[21:17:51.320] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:51.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:63:0) after 1m0s
[21:18:51.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:63:0)
[21:18:51.299] Timer tick, asking Buildbucket for the build status
[21:18:51.411] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:51.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:64:0) after 1m0s
[21:19:51.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:64:0)
[21:19:51.479] Timer tick, asking Buildbucket for the build status
[21:19:51.645] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:51.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:65:0) after 1m0s
[21:20:51.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:65:0)
[21:20:51.835] Timer tick, asking Buildbucket for the build status
[21:20:52.622] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:52.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:66:0) after 1m0s
[21:21:52.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:66:0)
[21:21:52.836] Timer tick, asking Buildbucket for the build status
[21:21:53.337] Build 8857011915321989856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:53.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022441977337839696:67:0) after 1m0s
[21:22:19.748] Received PubSub notification, asking Buildbucket for the build status
[21:22:19.861] Build 8857011915321989856: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:22:19.861] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611696135489733",
"created_by": "project:chromium",
"created_ts": "1611692238461542",
"id": "8857011915321989856",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f74debdff1b26ebd3dbc260233bf4ac3fbafabc2\"}}",
"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\": \"f74debdff1b26ebd3dbc260233bf4ac3fbafabc2\", \"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-1-o1ts\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17024, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"50f7d64610097f0a1eeef27e6e35612ad52b1023\", \"got_dawn_revision\": \"e0feccacafb0643fbaef8f807a9b76f1b9a97828\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"f74debdff1b26ebd3dbc260233bf4ac3fbafabc2\", \"got_revision_cp\": \"refs/heads/master@{#847288}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"ea1f7d10db1f22f3327f4c0bc690695bc1a73aa0\", \"got_v8_revision\": \"c316f1301cc02dea5fe5722364062578c071f4af\", \"got_v8_revision_cp\": \"refs/heads/9.0.71@{#1}\", \"got_webrtc_revision\": \"c57089a97a3df454f4356d882cc8df173e8b3ead\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33072}\", \"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\": \"f74debdff1b26ebd3dbc260233bf4ac3fbafabc2\", \"swarm_hashes_refs/heads/master(at){#847288}_without_patch\": {\"absl_hardening_tests\": \"f2b3d2369f1e57fb0dc2d63169a580efd7ea4cc8\", \"accessibility_unittests\": \"642655e2b0e6ab859ec1ace78e702656296dc088\", \"angle_unittests\": \"f4772a0b746f27ee421b27022628ea7e72ab428d\", \"app_shell_unittests\": \"f39d5b18de8045a44a87ed92144defbcb4305193\", \"aura_unittests\": \"c7d7f92e96491b742c7acc81f28c56a775812b01\", \"base_unittests\": \"1fa1cdd09e89e5a2598c47470fe505914e8368ed\", \"base_util_unittests\": \"b6111740d03c4cf13bbcc8e0e3878c38b081f14f\", \"blink_common_unittests\": \"5e4282b9a6d1b69a810a40cea070575545f19bed\", \"blink_fuzzer_unittests\": \"c1833fcdee569da4b6c931baf386c9f597c7b910\", \"blink_heap_unittests\": \"601b5e48dab4b2e20454bde5fd49b7a58029f8cf\", \"blink_platform_unittests\": \"025f288712536c3c7de37ea8e48f71b7a28a60fe\", \"blink_unittests\": \"8e61e8ccb655b674b1eafeb308767c13d73dce30\", \"boringssl_crypto_tests\": \"1fd97029472434ce9a7218837c9acaf5aa8f320e\", \"boringssl_ssl_tests\": \"c8f9ae0c6d9f34f503d438570a0954496d23c1af\", \"browser_switcher_bho_unittests\": \"332b0df9eb2db043197e0f18cc7ded1a231aab3e\", \"browser_tests\": \"25beb087ac3ea90fcf56b27d873fab06b803d9e1\", \"capture_unittests\": \"19659cf7d721206e8dd530cd0f6b4b778dc466b2\", \"cast_unittests\": \"e380ff038e5595153af51c2c598766be057559e5\", \"cc_unittests\": \"3d9788b2e0a5721a04b9604c29f7f43dd6bf5666\", \"chrome_app_unittests\": \"4432421a589e748d63bfe4702b418b67d845d570\", \"chrome_cleaner_unittests\": \"0784b2e6680b23275dd21e7286a02bd1662a4702\", \"chrome_elf_unittests\": \"14efe9a7368afbd32c05edeed439f963666707c0\", \"chromedriver_unittests\": \"73f0e87df0496f1e07bd57d746b40338ba9d1248\", \"components_browsertests\": \"6a402d29795c1afce487a9fab265731e276be76d\", \"components_unittests\": \"bf4eba0eb3e6cfe9afe6d5b862e2dc86cfd0bcd0\", \"compositor_unittests\": \"4a3359fbf0474b3b23394cf4c20aac0e53cd642a\", \"content_browsertests\": \"3c29d439c3e4d85d7d76f39929f129ec27727d8b\", \"content_unittests\": \"a8993e4cf96e8969c1afa6b7ffdf918b4cb2763d\", \"courgette_unittests\": \"abdc4f93464fdaa75f78846cd0fe895d1a361f84\", \"crashpad_tests\": \"4ec04a0a1976cd4d343e93c3c0d4d5d838ee82cc\", \"cronet_tests\": \"f525a24c54e96a9341087dac8cc610cb4afe72fd\", \"cronet_unittests\": \"11636f3b3a9a5cbd8ef02c8b52c02c4171ff452d\", \"crypto_unittests\": \"df59acb2ccf8e63fd9fdf37f2296a8cde334ca53\", \"delayloads_unittests\": \"e01b3d4373e0b8e9a18326b4941c2289e5027d98\", \"device_unittests\": \"cbd711828fae63f7dff2ca8e8d705d000179e237\", \"display_unittests\": \"ee4ef06ee1dbe56bde686d17e608eac1fdce3077\", \"elevation_service_unittests\": \"42b5df4127912ce1f42cad2062baadab71838b01\", \"events_unittests\": \"123ef921a04b62406242653fba237ba4299a805c\", \"extensions_browsertests\": \"463753ca4fcfbf5b5f1b6e5ccf034c68e8ec335b\", \"extensions_unittests\": \"fc0770a6c94eb9eda623099c7f8f5a8ddcb76237\", \"filesystem_service_unittests\": \"3b695bc36d74619b14cc5e3defc6d53e4b9675da\", \"gcm_unit_tests\": \"9931206d2694716889ec58371524eaf4becea314\", \"gcp_unittests\": \"0778d74a376e8ddb21c1f8003c40b3123413e278\", \"gfx_unittests\": \"56da5e98fdfae95acb280dd4526d46efac7fe17a\", \"gin_unittests\": \"a480fbd746e6bc3d6cd724fe57b9eb97ec398dbf\", \"google_apis_unittests\": \"7b38099b8e316a71839a1371d65eade4d7a717aa\", \"gpu_unittests\": \"773018c7937146c47fab327945fdb72fadda1fbf\", \"gwp_asan_unittests\": \"3acef4cb32a9c6bb7db19dfdbabb7743d4e6037c\", \"headless_browsertests\": \"c72bc451bad8078ad838230178a1c6c100c2aa6f\", \"headless_unittests\": \"350c40bfe2ece5de1f70fe97ee07fe8bf8c26d17\", \"install_static_unittests\": \"380fe8fbcd79bc50b36e4371e4cb889e43e9fa64\", \"installer_util_unittests\": \"d42a74f5742a08d39be0ea25798fa3187092dbd9\", \"interactive_ui_tests\": \"8b613ca8d347ab115650d15de39e07a0582f83c0\", \"ipc_tests\": \"7af8a043cc96907dcf9897a89e79388e7d46f788\", \"jingle_unittests\": \"4abc1efe156021d9d3812bdf4411100c2c1ad078\", \"latency_unittests\": \"3a099e0ca5e29e0dc23a946686b218886b8a6b40\", \"libjingle_xmpp_unittests\": \"1d69472781dbd334fbfdd83f463936cb12aa8b03\", \"liburlpattern_unittests\": \"13afe348bdd57e17f89426fbe362c04073e36d00\", \"media_blink_unittests\": \"c6d36303d1bf375e7d4b15f3220db01ebc8d390f\", \"media_unittests\": \"14d550de2294325d238062c6b569a331f5083106\", \"message_center_unittests\": \"ad9eabc687a30611569d0b1647d6db3da31545c4\", \"midi_unittests\": \"e593a349c46e169e25895cca2c1669acb8d5414f\", \"mojo_core_unittests\": \"b4a6cc4ec623c9615d7ca52097b90f9eaeae3370\", \"mojo_unittests\": \"9a672bbdd6188672bd1b5ab47130ab2657248ea6\", \"nacl_loader_unittests\": \"dbc684d2d6c32cb93c712d69817233a4dd33eee6\", \"native_theme_unittests\": \"d99785e5a4265412ceb658fee33544491897684a\", \"net_unittests\": \"964ad6233a07f502550618529ed3ccfd6264b14d\", \"notification_helper_unittests\": \"f20c2dd0f71739dd49013a890b30ed0b4f1173bd\", \"pdf_unittests\": \"1a67fede382704200b175f727852e5c957eac38a\", \"perfetto_unittests\": \"1ad8e40429697f9d42301eb0b1c10aa318ab1335\", \"ppapi_unittests\": \"209e8174901641629445e6ec3e0014b5bab764f9\", \"printing_unittests\": \"728201169260cdb8b969c46239ce90b2521cfdd2\", \"remoting_unittests\": \"ad49259775da110717c6a5fa62413838afdf4c94\", \"sbox_integration_tests\": \"c9fd488b8a2a2c249447e233e9c469138299d886\", \"sbox_unittests\": \"f0c3b891ba442b153db3ff83f4e4d391ecc0ebbb\", \"sbox_validation_tests\": \"3f549d94820a87e09c8905474672a2e97aa4b2af\", \"service_manager_unittests\": \"0b4d8d45dc14d8ea2f9375bc5c847931e2a1c77a\", \"services_unittests\": \"d496d7a2f3930cdec3b975d4c4a73fcf14a6d728\", \"setup_unittests\": \"b1d6a3d2fe5fca9728fef4570dfa124282f2302a\", \"shell_dialogs_unittests\": \"5f5ba7bb8f8a6e6d7eed6b6969f50a7c548425e3\", \"skia_unittests\": \"bae5644fb8b7aac203de43fe7cb9d0175348340a\", \"snapshot_unittests\": \"6b43d0866fc6c2b196bec0ef8df613ad98024f52\", \"sql_unittests\": \"ca56921939df7fd32d36548eb8d9a844962c15f3\", \"storage_unittests\": \"aa8ba00f2f9f2329938833980695aeff315cb056\", \"sync_integration_tests\": \"f29bfd1c50d95ac32ad664b5d9a4d998b39d7f29\", \"traffic_annotation_auditor_unittests\": \"4c34a37d3cf8e73db920bff70c18e8252b7ab338\", \"ui_base_unittests\": \"7714bc8ad2482fed9ea8e5d9c3f859763e14a105\", \"ui_touch_selection_unittests\": \"3742596b51bb83e9aec709480fbc6f93031d6247\", \"unit_tests\": \"42dcc3cc2d2755653f1e3c22ec60ba0cb3a7125d\", \"updater_tests\": \"a7ac0b435d4c3f5315bd074121aa43041bd92385\", \"url_unittests\": \"df3831c9d8058db9590e1cdaaadb5ca4e8b1fb8b\", \"views_unittests\": \"3d5017e370382ffb347e5f02bc19a4ae9a242849\", \"viz_unittests\": \"a6300c6fba1d878b02ef43f7bb5693f4dead2d4e\", \"vr_common_unittests\": \"7476d3fc46537e1c53dd93ea6753410a270860f9\", \"vr_pixeltests\": \"62ef5e97393480e058f95e674c767d01a845b737\", \"weblayer_browsertests\": \"cc8e8a1299997d7bb01853d854bd0db9c6440ed2\", \"weblayer_unittests\": \"174a083e89e5bae1f84e175cb007808c25b108c8\", \"wm_unittests\": \"5409f357ec6104fd3441aab2835954b0771f9ab0\", \"wtf_unittests\": \"226b8ec46ab4edcbd3d4aad5d83b0ae21714a82b\", \"zlib_unittests\": \"e15236d285e06b46d0337bc95dc10fc40d2e20b5\", \"zucchini_unittests\": \"ea203de4e3c3df844c3b3ed9d2929026afe63f4f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-1-o1ts\"], \"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\": [\"5672-4713de1\"], \"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": "1611692242016564",
"status": "COMPLETED",
"status_changed_ts": "1611696136481435",
"tags": [
"build_address:luci.chromium.ci/win-asan/17024",
"builder:win-asan",
"buildset:commit/git/f74debdff1b26ebd3dbc260233bf4ac3fbafabc2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f74debdff1b26ebd3dbc260233bf4ac3fbafabc2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022441977337839696",
"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/8857011915321989856/+/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:5159297616d53a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611696136614006",
"url": "https://ci.chromium.org/b/8857011915321989856",
"utcnow_ts": "1611696139847392"
}
}
[21:22:19.861] Invocation finished in 1h5m2.705706299s with status SUCCEEDED