[14:58:38.279] New invocation is queued and will start shortly
[14:58:39.327] Starting the invocation (attempt 1)
[14:58:39.363] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:58:39.363] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:58:39.363] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023820979448511184",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"193bb22c8c9dcc29bb05196f8cb0acf0284ca4fb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023820979448511184",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/193bb22c8c9dcc29bb05196f8cb0acf0284ca4fb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/193bb22c8c9dcc29bb05196f8cb0acf0284ca4fb",
"gitiles_ref:refs/heads/master"
]
}
[14:58:40.344] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610377119470828",
"id": "8858390917532480848",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"193bb22c8c9dcc29bb05196f8cb0acf0284ca4fb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610377120197835",
"tags": [
"build_address:luci.chromium.ci/win-asan/16757",
"builder:win-asan",
"buildset:commit/git/193bb22c8c9dcc29bb05196f8cb0acf0284ca4fb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/193bb22c8c9dcc29bb05196f8cb0acf0284ca4fb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023820979448511184",
"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/8858390917532480848/+/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": "1610377120197951",
"url": "https://ci.chromium.org/b/8858390917532480848",
"utcnow_ts": "1610377120337649"
}
}
[14:58:40.344] Task URL: https://ci.chromium.org/b/8858390917532480848
[14:58:40.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:2:0) after 1m0s
[14:58:45.016] Received PubSub notification, asking Buildbucket for the build status
[14:58:45.111] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:40.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:2:0)
[14:59:40.381] Timer tick, asking Buildbucket for the build status
[14:59:40.511] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:40.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:4:0) after 1m0s
[15:00:40.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:4:0)
[15:00:40.540] Timer tick, asking Buildbucket for the build status
[15:00:40.670] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:40.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:5:0) after 1m0s
[15:01:40.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:5:0)
[15:01:40.819] Timer tick, asking Buildbucket for the build status
[15:01:41.005] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:41.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:6:0) after 1m0s
[15:02:41.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:6:0)
[15:02:41.118] Timer tick, asking Buildbucket for the build status
[15:02:41.238] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:41.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:7:0) after 1m0s
[15:03:41.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:7:0)
[15:03:41.292] Timer tick, asking Buildbucket for the build status
[15:03:41.484] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:41.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:8:0) after 1m0s
[15:04:41.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:8:0)
[15:04:41.519] Timer tick, asking Buildbucket for the build status
[15:04:41.989] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:41.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:9:0) after 1m0s
[15:05:42.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:9:0)
[15:05:42.182] Timer tick, asking Buildbucket for the build status
[15:05:42.285] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:42.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:10:0) after 1m0s
[15:06:42.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:10:0)
[15:06:42.324] Timer tick, asking Buildbucket for the build status
[15:06:42.406] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:42.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:11:0) after 1m0s
[15:07:42.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:11:0)
[15:07:42.430] Timer tick, asking Buildbucket for the build status
[15:07:42.552] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:42.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:12:0) after 1m0s
[15:08:42.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:12:0)
[15:08:42.547] Timer tick, asking Buildbucket for the build status
[15:08:42.692] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:42.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:13:0) after 1m0s
[15:09:42.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:13:0)
[15:09:42.803] Timer tick, asking Buildbucket for the build status
[15:09:42.866] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:42.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:14:0) after 1m0s
[15:10:42.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:14:0)
[15:10:42.948] Timer tick, asking Buildbucket for the build status
[15:10:43.009] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:43.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:15:0) after 1m0s
[15:11:43.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:15:0)
[15:11:43.076] Timer tick, asking Buildbucket for the build status
[15:11:43.158] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:43.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:16:0) after 1m0s
[15:12:43.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:16:0)
[15:12:43.220] Timer tick, asking Buildbucket for the build status
[15:12:43.282] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:43.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:17:0) after 1m0s
[15:13:43.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:17:0)
[15:13:43.389] Timer tick, asking Buildbucket for the build status
[15:13:43.922] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:43.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:18:0) after 1m0s
[15:14:43.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:18:0)
[15:14:43.918] Timer tick, asking Buildbucket for the build status
[15:14:44.007] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:44.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:19:0) after 1m0s
[15:15:44.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:19:0)
[15:15:44.028] Timer tick, asking Buildbucket for the build status
[15:15:44.122] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:44.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:20:0) after 1m0s
[15:16:44.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:20:0)
[15:16:44.143] Timer tick, asking Buildbucket for the build status
[15:16:44.206] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:44.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:21:0) after 1m0s
[15:17:44.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:21:0)
[15:17:44.251] Timer tick, asking Buildbucket for the build status
[15:17:44.621] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:44.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:22:0) after 1m0s
[15:18:44.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:22:0)
[15:18:44.666] Timer tick, asking Buildbucket for the build status
[15:18:44.833] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:44.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:23:0) after 1m0s
[15:19:44.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:23:0)
[15:19:44.885] Timer tick, asking Buildbucket for the build status
[15:19:45.006] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:45.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:24:0) after 1m0s
[15:20:45.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:24:0)
[15:20:45.052] Timer tick, asking Buildbucket for the build status
[15:20:45.216] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:45.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:25:0) after 1m0s
[15:21:45.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:25:0)
[15:21:45.252] Timer tick, asking Buildbucket for the build status
[15:21:45.809] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:45.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:26:0) after 1m0s
[15:22:45.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:26:0)
[15:22:45.860] Timer tick, asking Buildbucket for the build status
[15:22:45.975] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:45.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:27:0) after 1m0s
[15:23:46.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:27:0)
[15:23:46.028] Timer tick, asking Buildbucket for the build status
[15:23:46.133] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:46.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:28:0) after 1m0s
[15:24:46.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:28:0)
[15:24:46.165] Timer tick, asking Buildbucket for the build status
[15:24:46.292] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:46.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:29:0) after 1m0s
[15:25:46.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:29:0)
[15:25:46.346] Timer tick, asking Buildbucket for the build status
[15:25:46.950] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:46.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:30:0) after 1m0s
[15:26:47.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:30:0)
[15:26:47.002] Timer tick, asking Buildbucket for the build status
[15:26:47.142] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:47.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:31:0) after 1m0s
[15:27:47.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:31:0)
[15:27:47.138] Timer tick, asking Buildbucket for the build status
[15:27:47.318] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:47.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:32:0) after 1m0s
[15:28:47.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:32:0)
[15:28:47.383] Timer tick, asking Buildbucket for the build status
[15:28:47.523] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:47.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:33:0) after 1m0s
[15:29:47.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:33:0)
[15:29:47.562] Timer tick, asking Buildbucket for the build status
[15:29:49.356] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:49.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:34:0) after 1m0s
[15:30:49.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:34:0)
[15:30:49.381] Timer tick, asking Buildbucket for the build status
[15:30:49.497] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:49.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:35:0) after 1m0s
[15:31:49.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:35:0)
[15:31:49.565] Timer tick, asking Buildbucket for the build status
[15:31:49.700] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:49.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:36:0) after 1m0s
[15:32:49.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:36:0)
[15:32:49.738] Timer tick, asking Buildbucket for the build status
[15:32:49.846] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:49.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:37:0) after 1m0s
[15:33:49.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:37:0)
[15:33:49.859] Timer tick, asking Buildbucket for the build status
[15:33:49.990] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:49.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:38:0) after 1m0s
[15:34:50.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:38:0)
[15:34:50.093] Timer tick, asking Buildbucket for the build status
[15:34:50.202] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:50.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:39:0) after 1m0s
[15:35:50.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:39:0)
[15:35:50.299] Timer tick, asking Buildbucket for the build status
[15:35:50.491] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:50.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:40:0) after 1m0s
[15:36:50.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:40:0)
[15:36:50.589] Timer tick, asking Buildbucket for the build status
[15:36:50.702] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:50.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:41:0) after 1m0s
[15:37:50.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:41:0)
[15:37:50.688] Timer tick, asking Buildbucket for the build status
[15:37:50.795] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:50.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:42:0) after 1m0s
[15:38:50.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:42:0)
[15:38:50.847] Timer tick, asking Buildbucket for the build status
[15:38:50.946] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:50.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:43:0) after 1m0s
[15:39:50.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:43:0)
[15:39:50.999] Timer tick, asking Buildbucket for the build status
[15:39:51.145] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:51.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:44:0) after 1m0s
[15:40:51.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:44:0)
[15:40:51.184] Timer tick, asking Buildbucket for the build status
[15:40:51.262] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:51.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:45:0) after 1m0s
[15:41:51.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:45:0)
[15:41:51.360] Timer tick, asking Buildbucket for the build status
[15:41:51.430] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:51.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:46:0) after 1m0s
[15:42:51.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:46:0)
[15:42:51.527] Timer tick, asking Buildbucket for the build status
[15:42:51.908] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:51.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:47:0) after 1m0s
[15:43:51.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:47:0)
[15:43:51.939] Timer tick, asking Buildbucket for the build status
[15:43:52.197] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:52.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:48:0) after 1m0s
[15:44:52.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:48:0)
[15:44:52.295] Timer tick, asking Buildbucket for the build status
[15:44:52.437] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:52.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:49:0) after 1m0s
[15:45:52.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:49:0)
[15:45:52.473] Timer tick, asking Buildbucket for the build status
[15:45:52.588] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:52.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:50:0) after 1m0s
[15:46:52.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:50:0)
[15:46:52.745] Timer tick, asking Buildbucket for the build status
[15:46:52.853] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:52.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:51:0) after 1m0s
[15:47:52.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:51:0)
[15:47:52.904] Timer tick, asking Buildbucket for the build status
[15:47:53.046] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:53.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:52:0) after 1m0s
[15:48:53.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:52:0)
[15:48:53.110] Timer tick, asking Buildbucket for the build status
[15:48:53.256] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:53.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:53:0) after 1m0s
[15:49:53.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:53:0)
[15:49:53.554] Timer tick, asking Buildbucket for the build status
[15:49:53.774] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:53.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:54:0) after 1m0s
[15:50:53.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:54:0)
[15:50:53.923] Timer tick, asking Buildbucket for the build status
[15:50:54.284] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:54.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:55:0) after 1m0s
[15:51:54.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:55:0)
[15:51:54.303] Timer tick, asking Buildbucket for the build status
[15:51:54.378] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:54.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:56:0) after 1m0s
[15:52:54.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:56:0)
[15:52:54.400] Timer tick, asking Buildbucket for the build status
[15:52:55.232] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:55.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:57:0) after 1m0s
[15:53:55.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:57:0)
[15:53:55.296] Timer tick, asking Buildbucket for the build status
[15:53:55.444] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:55.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:58:0) after 1m0s
[15:54:55.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:58:0)
[15:54:55.633] Timer tick, asking Buildbucket for the build status
[15:54:55.710] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:55.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:59:0) after 1m0s
[15:55:56.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:59:0)
[15:55:56.093] Timer tick, asking Buildbucket for the build status
[15:55:56.180] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:56.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:60:0) after 1m0s
[15:56:56.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:60:0)
[15:56:56.372] Timer tick, asking Buildbucket for the build status
[15:56:56.447] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:56.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:61:0) after 1m0s
[15:57:56.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:61:0)
[15:57:56.483] Timer tick, asking Buildbucket for the build status
[15:57:56.552] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:56.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:62:0) after 1m0s
[15:58:56.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:62:0)
[15:58:56.697] Timer tick, asking Buildbucket for the build status
[15:58:56.862] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:56.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:63:0) after 1m0s
[15:59:57.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:63:0)
[15:59:57.315] Timer tick, asking Buildbucket for the build status
[15:59:57.437] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:57.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:64:0) after 1m0s
[16:00:57.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:64:0)
[16:00:57.738] Timer tick, asking Buildbucket for the build status
[16:00:57.826] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:57.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:65:0) after 1m0s
[16:01:58.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:65:0)
[16:01:58.037] Timer tick, asking Buildbucket for the build status
[16:01:58.221] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:58.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:66:0) after 1m0s
[16:02:58.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:66:0)
[16:02:58.383] Timer tick, asking Buildbucket for the build status
[16:02:58.606] Build 8858390917532480848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:58.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023820979448511184:67:0) after 1m0s
[16:03:24.392] Received PubSub notification, asking Buildbucket for the build status
[16:03:24.489] Build 8858390917532480848: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:03:24.489] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610381000383183",
"created_by": "project:chromium",
"created_ts": "1610377119470828",
"id": "8858390917532480848",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"193bb22c8c9dcc29bb05196f8cb0acf0284ca4fb\"}}",
"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\": \"193bb22c8c9dcc29bb05196f8cb0acf0284ca4fb\", \"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-ervs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16757, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"ac05066a00a9c7fecbe3c0fe4bf71c3994c8c200\", \"got_dawn_revision\": \"1670c5184b9eca52aac58636723e13e4d20fda75\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"193bb22c8c9dcc29bb05196f8cb0acf0284ca4fb\", \"got_revision_cp\": \"refs/heads/master@{#842033}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"348ba200aa29d5b3a36d696d48d8f619e055219f\", \"got_v8_revision\": \"cfaba5184b58dddef979661ea5c17ac1a00d2a48\", \"got_v8_revision_cp\": \"refs/heads/8.9.232@{#1}\", \"got_webrtc_revision\": \"cffaf0aea477b2ec923d023ef4a8b1bf65921cab\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32933}\", \"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\": \"193bb22c8c9dcc29bb05196f8cb0acf0284ca4fb\", \"swarm_hashes_refs/heads/master(at){#842033}_without_patch\": {\"absl_hardening_tests\": \"8b687a59c48d92a5048a38bbf0b5eea30f73a97f\", \"accessibility_unittests\": \"ecbfc127c287ca2bfecf78987c9ba14c5afe0bb3\", \"angle_unittests\": \"37b777484edea26eb83bb1c847a1092dc7c836ed\", \"app_shell_unittests\": \"f72166779a28f27621c9684506150271250da245\", \"aura_unittests\": \"69f4cf272d91bf360eb6262b85f840e90279ba22\", \"base_unittests\": \"dbb42b8bd09aa9a47e93cf4d8c6b1cca700c1d33\", \"base_util_unittests\": \"2b8c2f812b541d1051d47ab4a83dcbc262141a9a\", \"blink_common_unittests\": \"c083dc8b86ce6f961b5b73e44be81e79e9634ec5\", \"blink_fuzzer_unittests\": \"bf346c6cc5dfbc528e7f7116b9a2a33ea44b1861\", \"blink_heap_unittests\": \"1b0a76feb058ca09b3dce8f3fcda877e7dbe319f\", \"blink_platform_unittests\": \"4ac471205e6336fbee84905df7dbc1cdcabf4150\", \"blink_unittests\": \"4d59090022f36ec05ae4982beab28d1d82ce1af9\", \"boringssl_crypto_tests\": \"d7d41a61ffdc0658ff9aca4d3ccfd9753a20fce1\", \"boringssl_ssl_tests\": \"488b4569d371a99853046ccbfb581f0b5ab27bd0\", \"browser_switcher_bho_unittests\": \"2b17b17bba2d7c35816fb486621750bd337ed790\", \"browser_tests\": \"1a67edd1f2f0b2a76004792f9d160aaefd58f219\", \"capture_unittests\": \"4cccc8b8008a845fd5b429dd37a5943bbf1b8fa8\", \"cast_unittests\": \"bbc5ad54a230ad6df6f2ed484789c43e92e075c8\", \"cc_unittests\": \"33c249093b4f8fd9bc05cf467c7a099d01e08fe1\", \"chrome_app_unittests\": \"9410c5df01a4a5b2e61019b3cfec368c1dc7dee0\", \"chrome_cleaner_unittests\": \"7dc2a8400d32648071eaf90aa86b70c4a75376db\", \"chrome_elf_unittests\": \"778664a81a9670ef62bfc3a9321bc2eccb5625a7\", \"chromedriver_unittests\": \"8e902a8c6c951b4cf352f231907b05edc954be9d\", \"components_browsertests\": \"b7e0be61cb6b08228feacd16501df4cb7ac85006\", \"components_unittests\": \"82e6eeff7101d2b9c0d2f678e117e2da31e6c9fe\", \"compositor_unittests\": \"8c68e08d8c2609981595dd7d09d8abb995dc73ca\", \"content_browsertests\": \"fd2287adfe7a7740a2c218e4abbf47a61b131e4a\", \"content_unittests\": \"88fd4b1706441e97965efa0f8bfe8b056c2708bc\", \"courgette_unittests\": \"24f4ca8f62997804a90ca15e30480341d8789e05\", \"crashpad_tests\": \"cbe40890b773c09847040dd7b729889e21857514\", \"cronet_tests\": \"f098e8faacd0b9e30369eba540279fa727611548\", \"cronet_unittests\": \"45b59b27d39e25e97388df6235dcd81f61e6b844\", \"crypto_unittests\": \"151ef1774c98da5732754dd82b40d2a3cd2a9cb0\", \"delayloads_unittests\": \"3c6391bb8071aecab3de5a22a17c9cd9a2403bb2\", \"device_unittests\": \"44e67182256c28f6a7cf9aae2b6cd5140488f460\", \"display_unittests\": \"ba3de37bce5a98e689642621952be6bed00fedf4\", \"elevation_service_unittests\": \"192622179bcc89eba771904f8ff5ec3acb61da31\", \"events_unittests\": \"b384ce2ba05eaadf64374ca2728a4758d7ce456c\", \"extensions_browsertests\": \"57599bc79b1ee522b0ad4920c8d62e1f1094183d\", \"extensions_unittests\": \"1451c511af0e72715fcf04a60241ea9758efd9c0\", \"filesystem_service_unittests\": \"bc009dff1902661166e9428b5fce638bb8a9daa1\", \"gcm_unit_tests\": \"3a495c3cf07df62e5123ae9351ab7df06a4647f0\", \"gcp_unittests\": \"f8ebaad029eefb97e294b4b16ff3e00d50504a5d\", \"gfx_unittests\": \"027cc2f89ee588f2d57cf01cb073ebb2964cbaa8\", \"gin_unittests\": \"a930ad4533e2c9886b8d3f3ce7bcc8e099c0aacf\", \"google_apis_unittests\": \"12a40e18035419f1fdec678bcfa634dc2f7d255e\", \"gpu_unittests\": \"f2fdfd03803813a6e76889518d49400f76c6c6fb\", \"gwp_asan_unittests\": \"b869311d6c0db5b9ed1b3d31b0b049ee5a5e83b4\", \"headless_browsertests\": \"e155a3c579cf86023d2259490a21d36fab08bbd3\", \"headless_unittests\": \"d1957fb3301ccede39bad35fd43486116510862c\", \"install_static_unittests\": \"241fad0fdd06bb8c36e23b8a8bbc3c18cbd62ba9\", \"installer_util_unittests\": \"c6fa248785bff58f61b5431d6829fc6dfb184323\", \"interactive_ui_tests\": \"75515c9efbb4c531cecafa34f9ca04ad06a4be4c\", \"ipc_tests\": \"880a2dcb80cece8daf4f3ba59f2cd5205f66e074\", \"jingle_unittests\": \"cbb236b33cebb7860c43025e3ec9299e1b22c3bb\", \"latency_unittests\": \"2409b0d851fb3e9d82443c5878851a82cdf63710\", \"libjingle_xmpp_unittests\": \"f16893961a78a16bfa4313e02c8fc8a32e9ff8f7\", \"liburlpattern_unittests\": \"62ac16d4df82394bf6b65717b529b4b2f55c083b\", \"media_blink_unittests\": \"f396c3ff99b0caf6b60dc3cd45fadf463b911dcb\", \"media_unittests\": \"44e4d13382e97c45cda9da5f63d321391a347d5b\", \"message_center_unittests\": \"51bd6ffece473f4eb6b8c718da1177b4898a36ce\", \"midi_unittests\": \"2ac0451cd0c15a2ac2696739694cf571a705c2c7\", \"mojo_core_unittests\": \"9b736c49d7620a23f359df712fd4564dc79ade7c\", \"mojo_unittests\": \"6eb6502b1cd3b0b4097012b0fdf06f201b503df5\", \"nacl_loader_unittests\": \"34d11c07b0fbf3c3e043b7cfcfc876d5d705e64e\", \"native_theme_unittests\": \"c2d66006dbca5fdf7a12a7d1c9b81e3adff02685\", \"net_unittests\": \"b8cef095ee2936e7a8d11e6b4b9d29f95616a390\", \"notification_helper_unittests\": \"3eb0404ba9fd31e2c34e98bdde8edbdb28544ead\", \"pdf_unittests\": \"290187bd8be80ea10c46622e0f3d00cdf5b0e511\", \"perfetto_unittests\": \"054f839af927c73afbc352ec67649037d704c79b\", \"ppapi_unittests\": \"003458673c17c844a07b489976bae523cad533f6\", \"printing_unittests\": \"e5e6c8a2c3ac2f54b30f23bcfcecdfdaa7602203\", \"remoting_unittests\": \"8b9b0a956c71c00d6c98bf082f788b9107de071a\", \"sbox_integration_tests\": \"b7768f438b62df765792ae5079bb10db2d91f0ac\", \"sbox_unittests\": \"75ff6620f508216ab34175a08006c232a6270c8c\", \"sbox_validation_tests\": \"d36b455b44f6644e154d83ab5587e56d7b6973e5\", \"service_manager_unittests\": \"71cc8398e94a84a6686380b6e147f4a4e8d20d5a\", \"services_unittests\": \"5b5be25ae47338673ddf68210d85e92d5734b046\", \"setup_unittests\": \"d6396016daf8a3c4f6357cc304d7c8f602615eca\", \"shell_dialogs_unittests\": \"99ec6c19b6ee0d9e3377c014e4de007f5a9af69d\", \"skia_unittests\": \"3ecfa94f0ae90947e012fbb845daf405d2c2b38d\", \"snapshot_unittests\": \"c6091866c8f3533fbd7dd1d5db4fc063a7c915fa\", \"sql_unittests\": \"04e7c3988c6f64373c2688eedd17e2d190c3e3d0\", \"storage_unittests\": \"74c662b7794f818016b91d1c27925dc8af2ad787\", \"sync_integration_tests\": \"bec02f59ec79f704382560d001ee9ae1763c1e8b\", \"traffic_annotation_auditor_unittests\": \"d3f043c7b12bf8848ea53ce48581053898fc0398\", \"ui_base_unittests\": \"d9d0281c3f522720869c3fa00e5dc15ea2904ef9\", \"ui_touch_selection_unittests\": \"8213474c7c9644118d41e9b1984bb421b88a22ad\", \"unit_tests\": \"ffc8792e5da3de0ca93d792b3df5a55a6fc92cff\", \"updater_tests\": \"b4664a020044b1fbe10be80b191a6e4bfb1d563e\", \"url_unittests\": \"1f4f8a1534b1575dcee28619c3243cb6bdbd1452\", \"views_unittests\": \"5e7e8aff97a31776bd7efe075b2ed49f771087af\", \"viz_unittests\": \"ae30c58e1e533efa4175c0264263d23c6692dbde\", \"vr_common_unittests\": \"74e1984d937d2f71fe327df5bd9c1ab223e075b5\", \"vr_pixeltests\": \"0df4d06e3584b896ac0402b9c6492a8bc0699f5b\", \"weblayer_browsertests\": \"94636703646cc4fbc757c4c3337a764ef613f261\", \"weblayer_unittests\": \"76433698d2917b666679f633f5383b14e42cdb0a\", \"wm_unittests\": \"2f4f108acd57e75ae5af122727145939da5a96c9\", \"wtf_unittests\": \"23978c89796f83e943c4ecdf107eca242c393399\", \"zlib_unittests\": \"91bbdc151ea49f6d0c3440d39f0cdf7f92abee48\", \"zucchini_unittests\": \"b6e0a5e6a10c772e5a900f3ba7534319b51b8ec3\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-ervs\"], \"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": "1610377123990610",
"status": "COMPLETED",
"status_changed_ts": "1610381002224915",
"tags": [
"build_address:luci.chromium.ci/win-asan/16757",
"builder:win-asan",
"buildset:commit/git/193bb22c8c9dcc29bb05196f8cb0acf0284ca4fb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/193bb22c8c9dcc29bb05196f8cb0acf0284ca4fb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023820979448511184",
"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/8858390917532480848/+/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:510ac653dc60d510",
"user_agent:luci-scheduler"
],
"updated_ts": "1610381002274640",
"url": "https://ci.chromium.org/b/8858390917532480848",
"utcnow_ts": "1610381004475911"
}
}
[16:03:24.489] Invocation finished in 1h4m46.228526141s with status SUCCEEDED