[04:03:25.692] New invocation is queued and will start shortly
[04:03:26.887] Starting the invocation (attempt 1)
[04:03:26.943] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:03:26.944] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:03:26.944] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021869068418126608",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"24c8af75a992523026279984102a742fc1fff90a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021869068418126608",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/24c8af75a992523026279984102a742fc1fff90a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/24c8af75a992523026279984102a742fc1fff90a",
"gitiles_ref:refs/heads/master"
]
}
[04:03:27.726] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612238607042813",
"id": "8856439006340955744",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"24c8af75a992523026279984102a742fc1fff90a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612238607526785",
"tags": [
"build_address:luci.chromium.ci/win-asan/17123",
"builder:win-asan",
"buildset:commit/git/24c8af75a992523026279984102a742fc1fff90a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/24c8af75a992523026279984102a742fc1fff90a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021869068418126608",
"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/8856439006340955744/+/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": "1612238607526890",
"url": "https://ci.chromium.org/b/8856439006340955744",
"utcnow_ts": "1612238607715091"
}
}
[04:03:27.726] Task URL: https://ci.chromium.org/b/8856439006340955744
[04:03:27.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:2:0) after 1m0s
[04:03:35.522] Received PubSub notification, asking Buildbucket for the build status
[04:03:35.617] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:27.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:2:0)
[04:04:27.727] Timer tick, asking Buildbucket for the build status
[04:04:27.854] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:27.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:4:0) after 1m0s
[04:05:27.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:4:0)
[04:05:27.952] Timer tick, asking Buildbucket for the build status
[04:05:28.258] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:28.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:5:0) after 1m0s
[04:06:28.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:5:0)
[04:06:28.256] Timer tick, asking Buildbucket for the build status
[04:06:28.355] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:28.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:6:0) after 1m0s
[04:07:28.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:6:0)
[04:07:28.367] Timer tick, asking Buildbucket for the build status
[04:07:28.807] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:28.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:7:0) after 1m0s
[04:08:28.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:7:0)
[04:08:28.901] Timer tick, asking Buildbucket for the build status
[04:08:29.009] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:29.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:8:0) after 1m0s
[04:09:29.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:8:0)
[04:09:29.109] Timer tick, asking Buildbucket for the build status
[04:09:29.235] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:29.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:9:0) after 1m0s
[04:10:29.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:9:0)
[04:10:29.258] Timer tick, asking Buildbucket for the build status
[04:10:29.337] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:29.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:10:0) after 1m0s
[04:11:29.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:10:0)
[04:11:29.382] Timer tick, asking Buildbucket for the build status
[04:11:29.504] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:29.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:11:0) after 1m0s
[04:12:29.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:11:0)
[04:12:29.522] Timer tick, asking Buildbucket for the build status
[04:12:29.581] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:29.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:12:0) after 1m0s
[04:13:29.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:12:0)
[04:13:29.621] Timer tick, asking Buildbucket for the build status
[04:13:29.697] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:29.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:13:0) after 1m0s
[04:14:29.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:13:0)
[04:14:29.692] Timer tick, asking Buildbucket for the build status
[04:14:30.037] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:30.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:14:0) after 1m0s
[04:15:30.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:14:0)
[04:15:30.095] Timer tick, asking Buildbucket for the build status
[04:15:30.196] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:30.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:15:0) after 1m0s
[04:16:30.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:15:0)
[04:16:30.509] Timer tick, asking Buildbucket for the build status
[04:16:30.566] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:30.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:16:0) after 1m0s
[04:17:30.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:16:0)
[04:17:30.701] Timer tick, asking Buildbucket for the build status
[04:17:30.896] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:30.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:17:0) after 1m0s
[04:18:31.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:17:0)
[04:18:31.910] Timer tick, asking Buildbucket for the build status
[04:18:32.126] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:32.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:18:0) after 1m0s
[04:19:32.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:18:0)
[04:19:32.198] Timer tick, asking Buildbucket for the build status
[04:19:32.330] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:32.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:19:0) after 1m0s
[04:20:32.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:19:0)
[04:20:32.418] Timer tick, asking Buildbucket for the build status
[04:20:32.540] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:32.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:20:0) after 1m0s
[04:21:32.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:20:0)
[04:21:32.655] Timer tick, asking Buildbucket for the build status
[04:21:32.739] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:32.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:21:0) after 1m0s
[04:22:32.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:21:0)
[04:22:32.879] Timer tick, asking Buildbucket for the build status
[04:22:33.034] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:33.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:22:0) after 1m0s
[04:23:33.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:22:0)
[04:23:33.175] Timer tick, asking Buildbucket for the build status
[04:23:33.701] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:33.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:23:0) after 1m0s
[04:24:33.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:23:0)
[04:24:33.722] Timer tick, asking Buildbucket for the build status
[04:24:33.906] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:33.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:24:0) after 1m0s
[04:25:33.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:24:0)
[04:25:33.931] Timer tick, asking Buildbucket for the build status
[04:25:34.645] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:34.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:25:0) after 1m0s
[04:26:34.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:25:0)
[04:26:34.718] Timer tick, asking Buildbucket for the build status
[04:26:34.940] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:34.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:26:0) after 1m0s
[04:27:34.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:26:0)
[04:27:34.962] Timer tick, asking Buildbucket for the build status
[04:27:35.299] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:35.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:27:0) after 1m0s
[04:28:35.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:27:0)
[04:28:35.401] Timer tick, asking Buildbucket for the build status
[04:28:35.626] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:35.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:28:0) after 1m0s
[04:29:35.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:28:0)
[04:29:35.767] Timer tick, asking Buildbucket for the build status
[04:29:35.825] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:35.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:29:0) after 1m0s
[04:30:36.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:29:0)
[04:30:36.022] Timer tick, asking Buildbucket for the build status
[04:30:36.607] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:36.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:30:0) after 1m0s
[04:31:36.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:30:0)
[04:31:36.656] Timer tick, asking Buildbucket for the build status
[04:31:37.123] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:37.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:31:0) after 1m0s
[04:32:37.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:31:0)
[04:32:37.209] Timer tick, asking Buildbucket for the build status
[04:32:37.338] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:37.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:32:0) after 1m0s
[04:33:37.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:32:0)
[04:33:37.472] Timer tick, asking Buildbucket for the build status
[04:33:38.032] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:38.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:33:0) after 1m0s
[04:34:38.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:33:0)
[04:34:38.058] Timer tick, asking Buildbucket for the build status
[04:34:38.549] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:38.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:34:0) after 1m0s
[04:35:38.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:34:0)
[04:35:38.758] Timer tick, asking Buildbucket for the build status
[04:35:38.933] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:38.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:35:0) after 1m0s
[04:36:39.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:35:0)
[04:36:39.050] Timer tick, asking Buildbucket for the build status
[04:36:39.225] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:39.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:36:0) after 1m0s
[04:37:39.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:36:0)
[04:37:39.332] Timer tick, asking Buildbucket for the build status
[04:37:39.433] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:39.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:37:0) after 1m0s
[04:38:39.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:37:0)
[04:38:39.494] Timer tick, asking Buildbucket for the build status
[04:38:39.842] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:39.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:38:0) after 1m0s
[04:39:39.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:38:0)
[04:39:39.894] Timer tick, asking Buildbucket for the build status
[04:39:40.036] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:40.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:39:0) after 1m0s
[04:40:40.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:39:0)
[04:40:40.065] Timer tick, asking Buildbucket for the build status
[04:40:40.198] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:40.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:40:0) after 1m0s
[04:41:40.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:40:0)
[04:41:40.281] Timer tick, asking Buildbucket for the build status
[04:41:40.398] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:40.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:41:0) after 1m0s
[04:42:40.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:41:0)
[04:42:40.487] Timer tick, asking Buildbucket for the build status
[04:42:40.613] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:40.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:42:0) after 1m0s
[04:43:40.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:42:0)
[04:43:40.718] Timer tick, asking Buildbucket for the build status
[04:43:40.821] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:40.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:43:0) after 1m0s
[04:44:40.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:43:0)
[04:44:40.949] Timer tick, asking Buildbucket for the build status
[04:44:41.013] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:41.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:44:0) after 1m0s
[04:45:41.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:44:0)
[04:45:41.195] Timer tick, asking Buildbucket for the build status
[04:45:41.270] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:41.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:45:0) after 1m0s
[04:46:41.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:45:0)
[04:46:41.364] Timer tick, asking Buildbucket for the build status
[04:46:41.442] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:41.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:46:0) after 1m0s
[04:47:41.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:46:0)
[04:47:41.550] Timer tick, asking Buildbucket for the build status
[04:47:41.670] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:41.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:47:0) after 1m0s
[04:48:41.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:47:0)
[04:48:41.745] Timer tick, asking Buildbucket for the build status
[04:48:41.925] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:41.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:48:0) after 1m0s
[04:49:42.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:48:0)
[04:49:42.024] Timer tick, asking Buildbucket for the build status
[04:49:42.410] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:42.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:49:0) after 1m0s
[04:50:42.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:49:0)
[04:50:42.671] Timer tick, asking Buildbucket for the build status
[04:50:42.895] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:42.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:50:0) after 1m0s
[04:51:43.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:50:0)
[04:51:43.056] Timer tick, asking Buildbucket for the build status
[04:51:43.153] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:43.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:51:0) after 1m0s
[04:52:43.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:51:0)
[04:52:43.197] Timer tick, asking Buildbucket for the build status
[04:52:43.312] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:43.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:52:0) after 1m0s
[04:53:43.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:52:0)
[04:53:43.439] Timer tick, asking Buildbucket for the build status
[04:53:43.593] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:43.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:53:0) after 1m0s
[04:54:43.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:53:0)
[04:54:43.738] Timer tick, asking Buildbucket for the build status
[04:54:44.714] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:44.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:54:0) after 1m0s
[04:55:44.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:54:0)
[04:55:44.774] Timer tick, asking Buildbucket for the build status
[04:55:45.113] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:45.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:55:0) after 1m0s
[04:56:45.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:55:0)
[04:56:45.131] Timer tick, asking Buildbucket for the build status
[04:56:45.382] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:45.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:56:0) after 1m0s
[04:57:45.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:56:0)
[04:57:45.491] Timer tick, asking Buildbucket for the build status
[04:57:45.717] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:45.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:57:0) after 1m0s
[04:58:45.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:57:0)
[04:58:45.841] Timer tick, asking Buildbucket for the build status
[04:58:45.984] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:45.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:58:0) after 1m0s
[04:59:46.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:58:0)
[04:59:46.160] Timer tick, asking Buildbucket for the build status
[04:59:46.340] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:46.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:59:0) after 1m0s
[05:00:46.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:59:0)
[05:00:46.431] Timer tick, asking Buildbucket for the build status
[05:00:46.689] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:46.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:60:0) after 1m0s
[05:01:46.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:60:0)
[05:01:46.916] Timer tick, asking Buildbucket for the build status
[05:01:47.330] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:47.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:61:0) after 1m0s
[05:02:47.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:61:0)
[05:02:47.427] Timer tick, asking Buildbucket for the build status
[05:02:47.509] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:47.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:62:0) after 1m0s
[05:03:47.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:62:0)
[05:03:47.610] Timer tick, asking Buildbucket for the build status
[05:03:47.837] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:47.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:63:0) after 1m0s
[05:04:47.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:63:0)
[05:04:47.935] Timer tick, asking Buildbucket for the build status
[05:04:48.003] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:48.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:64:0) after 1m0s
[05:05:48.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:64:0)
[05:05:48.041] Timer tick, asking Buildbucket for the build status
[05:05:48.311] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:48.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:65:0) after 1m0s
[05:06:48.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:65:0)
[05:06:48.342] Timer tick, asking Buildbucket for the build status
[05:06:48.430] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:48.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:66:0) after 1m0s
[05:07:48.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:66:0)
[05:07:48.454] Timer tick, asking Buildbucket for the build status
[05:07:48.536] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:48.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:67:0) after 1m0s
[05:08:48.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:67:0)
[05:08:48.551] Timer tick, asking Buildbucket for the build status
[05:08:48.684] Build 8856439006340955744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:48.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021869068418126608:68:0) after 1m0s
[05:09:19.033] Received PubSub notification, asking Buildbucket for the build status
[05:09:19.179] Build 8856439006340955744: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:09:19.179] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612242557435477",
"created_by": "project:chromium",
"created_ts": "1612238607042813",
"id": "8856439006340955744",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"24c8af75a992523026279984102a742fc1fff90a\"}}",
"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\": \"24c8af75a992523026279984102a742fc1fff90a\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-8-uuz6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17123, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"729139c9f0233dcbbdb50529ed3681b314250ba1\", \"got_dawn_revision\": \"71b811b4c9901d33f5e3d66002a5265908939b70\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"24c8af75a992523026279984102a742fc1fff90a\", \"got_revision_cp\": \"refs/heads/master@{#849426}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"5093aadcdb5014db8a3f73e6ac488f8272d48e38\", \"got_v8_revision_cp\": \"refs/heads/9.0.107@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"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\": \"24c8af75a992523026279984102a742fc1fff90a\", \"swarm_hashes_refs/heads/master(at){#849426}_without_patch\": {\"absl_hardening_tests\": \"1726408473e9e22f85bc6812521f41950f255d8d\", \"accessibility_unittests\": \"6ba21e2a121fb4c1173db229ea6467c58ad9b371\", \"angle_unittests\": \"94f56239e1705e48d196a36c42f0c1d0c821c618\", \"app_shell_unittests\": \"0c5b212b33580dcedd1a435dc6c1834386ba11f5\", \"aura_unittests\": \"4d02538074cee93eabc9e5af3f83c5700160c4bf\", \"base_unittests\": \"e6e8cfedfb0bac2977d7319a507012645e04eee4\", \"base_util_unittests\": \"d911f30504db7f149eec9de9565facfcfd7ad9fc\", \"blink_common_unittests\": \"a7f18def7d92ef8604aa417f447fec68de48307f\", \"blink_fuzzer_unittests\": \"093dec3fee4ebcaea3b49e1c84c4c821cc273397\", \"blink_heap_unittests\": \"3f620e368455520983eb9f5f159f444e89685207\", \"blink_platform_unittests\": \"525b1911ca8f3c6c8292257129f9af5cab54daea\", \"blink_unittests\": \"8f7c56b1e08fd66af9d555389664fee7cf7181f7\", \"boringssl_crypto_tests\": \"edf2e10614e4d7fbabb6e2b4e56a2630204c35a1\", \"boringssl_ssl_tests\": \"f061c32389acaf2905500266add5eb654ecd8ea7\", \"browser_switcher_bho_unittests\": \"88ab1df89fcd45ad1435f45abbc651a56ffdf7a8\", \"browser_tests\": \"8e3c1f2c2cfa92d4e2f46618f1b9530456d8e415\", \"capture_unittests\": \"0b32a9e336bb38eee78c62b692a0432a8e9d2470\", \"cast_unittests\": \"ce5291adbb6db1743a40e73f3d8c8b21a8e4ef91\", \"cc_unittests\": \"f6b9c7d7201bffb15dec869084e1429fa05e19c3\", \"chrome_app_unittests\": \"b0c90241f662a7620aabe280ca7e51453bb07ab3\", \"chrome_cleaner_unittests\": \"b6187b27542ecbbab797c76d611c44bedb7d6161\", \"chrome_elf_unittests\": \"5840a5451bddb9cd8e8806bbf56df6fa6f1b6afd\", \"chromedriver_unittests\": \"5feb90820c13de29ece2fcb8480d06ac453922fd\", \"components_browsertests\": \"1d10881a34e87005b0891846bb859ba20ea47864\", \"components_unittests\": \"e42817d02875a6ce6a86dc87b2c49782cf91b84e\", \"compositor_unittests\": \"e3ffddc41b02a8042a9a0b31b31d1b04568b02a4\", \"content_browsertests\": \"8781c268969bf3dfff9de1099a85462a187a0f0d\", \"content_unittests\": \"2922e32abf69a2c239011f4be20e7ced657a5086\", \"courgette_unittests\": \"9560636e129ef54e040b686330bc90577209603c\", \"crashpad_tests\": \"b66e6e1b90acab1eba13d89e7e792d7d638931f1\", \"cronet_tests\": \"64ec33e80a39e95d6c43f15ad95a908e85e4abb2\", \"cronet_unittests\": \"71ee6ca266e5c80e9e5ad7a150a5cc8187e9a931\", \"crypto_unittests\": \"be3d8d8ae68e229a11699aa2ce6ffff67d5a2891\", \"delayloads_unittests\": \"a800949787e62da21aaca34061ac32296fa81678\", \"device_unittests\": \"221cc4eeda79a5d6f1fdd35e721ef5242b803f22\", \"display_unittests\": \"d6ea2fbb38952c11d343b0936d7ca57153d6abc7\", \"elevation_service_unittests\": \"ffea402c2f2ab6be8b9aae3d8daba444853ad069\", \"events_unittests\": \"9b65a853ef9879148e541c36363b06f6b248af27\", \"extensions_browsertests\": \"aa183ffb28ec27db53bc4364ccaebb3eca24df03\", \"extensions_unittests\": \"039d20a8ea5b6b898dd0bb72fccc6645f47024d8\", \"filesystem_service_unittests\": \"2e6ba7b3c62cdf95cf585ada45e79dfc8e0923bb\", \"gcm_unit_tests\": \"b05de4b72d03b77182a3ffe20426f6c3c6a9d4e3\", \"gcp_unittests\": \"4d5309e6303d0202eab15c9015e2b4040a55cd95\", \"gfx_unittests\": \"33da4434402a329cda116ef3baac96a39f4a6ad0\", \"gin_unittests\": \"2aef903206f9a6f71772800a092967b5a002eefc\", \"google_apis_unittests\": \"756c07dfc14f6d0b79f743a3493da6438d328969\", \"gpu_unittests\": \"ab8e929e47374b1c8bb4124e1585ed4511e2dd46\", \"gwp_asan_unittests\": \"274019a9a50d17638738f5f56172318033a1766e\", \"headless_browsertests\": \"8694d0141a5632ee6a01a3cf9b431c9334618377\", \"headless_unittests\": \"4e513c5705da78153509ea85bfcb68039111d62f\", \"install_static_unittests\": \"9c795a9528c849f4f3eaa1be5d7a80a9dbd0c292\", \"installer_util_unittests\": \"0141d0b34be0e815b95825a22d51a6372ec6ffb8\", \"interactive_ui_tests\": \"4c092189f6003aaba66e2c830b323fc0e3af313b\", \"ipc_tests\": \"9852d5b0f2e767ef36c4dc32d9f51c0a13274adc\", \"jingle_unittests\": \"6bb43553ff55ca862322c07ef06f4efe4e48ae22\", \"latency_unittests\": \"59ee6f04c95df3755091cf1916f99a7b839fa968\", \"libjingle_xmpp_unittests\": \"fb81887492bc2124d09936f957193c29d24a0e47\", \"liburlpattern_unittests\": \"8a6519df76d7b835e16a0bb23c4b6aecb08fdf2c\", \"media_blink_unittests\": \"062d49d7a0c8badd44652600617bb38624e66ff5\", \"media_unittests\": \"6b50d192dceee63110da70c08b12282010d9e680\", \"message_center_unittests\": \"f1442114542d3e8e57138c8bc94a2e08fe600759\", \"midi_unittests\": \"2714bfa096b5e7094559a87927e96dc9bdf974ef\", \"mojo_core_unittests\": \"e981b3f2ed86ee1e0a459978c20571a19920bd68\", \"mojo_unittests\": \"d936f9d99ef9d5ee7409342c9844533cb846f18b\", \"nacl_loader_unittests\": \"3d156cece9285ee69f6ae2a63bb0bd200bb1cca4\", \"native_theme_unittests\": \"9d13997e28e8cfec1ecc9fd887e4fbc4e8efcd27\", \"net_unittests\": \"a953ccb7254aa97952ca7a1b8d6d8bb9e743b71e\", \"notification_helper_unittests\": \"72e12b47f6b838c6fedf2fdb10199f006771c290\", \"pdf_unittests\": \"f52af89340b5d55ba16b37ec487fd9595df99d6e\", \"perfetto_unittests\": \"8625b7542f05d72eaf2638b55a5d8e03b9758226\", \"ppapi_unittests\": \"ae5011ebbafbe6b73e6ba521795f92ea410da164\", \"printing_unittests\": \"a3ec6838375f51c188443b5e163280defdf51e88\", \"remoting_unittests\": \"a1beb52300602b432c4b5e6b433790396c167693\", \"sbox_integration_tests\": \"21ee6a037cb7da1ff0feeb741ff94595c613d372\", \"sbox_unittests\": \"0a42b578c4fa4815e44f943630a5b76b94a53141\", \"sbox_validation_tests\": \"03106d5ea08dfff43ceab6bd769ae52e28ac65a9\", \"service_manager_unittests\": \"615d711462f20901377cf8c2e80287ac6d731c87\", \"services_unittests\": \"4463a6934ef29a0abc558804cdbebc8385434384\", \"setup_unittests\": \"7656600301603af071e37cd6b87d107ebff35b0d\", \"shell_dialogs_unittests\": \"9e05862a852c747cbb125f0f5bcb725580cb67ac\", \"skia_unittests\": \"ec5aefa5e845c450ba0f9c4f277eaaecd2693407\", \"snapshot_unittests\": \"105fb69dfee487ff80be01d1e926b44cd49847c2\", \"sql_unittests\": \"f681d2cd85d2e0543860113ad252300499a2a9ef\", \"storage_unittests\": \"58e027ce6416b669eddf1dfafbe42e4ec2d113a9\", \"sync_integration_tests\": \"d35a9d3ca3088e749f996f2b946649f242704e67\", \"traffic_annotation_auditor_unittests\": \"739fd752a431dda95ccbbc430461901d2eed8824\", \"ui_base_unittests\": \"7e423a786ce99589693b540c1fb59a9ebb0aa8f9\", \"ui_touch_selection_unittests\": \"09b46a719b69d5a2905c452d4ae7a39531d17cd5\", \"unit_tests\": \"4d4bc5362f20a67bf1780ab972d6eb261b617a54\", \"updater_tests\": \"f7c1c6b06c40724b95f8c3726e830643d10463cc\", \"url_unittests\": \"de82eefbb696c36be48cf1e34ce5681b3042fa93\", \"views_unittests\": \"9d4445dc5b3353d9feb3e5a6e08b09f754aafa37\", \"viz_unittests\": \"2f760724bcd6ffea76ebb3ec9e34c7b2e27ca049\", \"vr_common_unittests\": \"dc09723f5c80c122ec9043aea3cddfd867117914\", \"vr_pixeltests\": \"3e027a1da579d2336e478949c4cc3cf5c5bd8e24\", \"weblayer_browsertests\": \"278182f75a27c2e7abebbd938cdfbd4e481be0fb\", \"weblayer_unittests\": \"41f3cce0cb90e0f38c786310d3802a8960cf6573\", \"wm_unittests\": \"5f3e9b54086b41e71a5ba40d290792e86996fc7a\", \"wtf_unittests\": \"61ddf8f834ab99086081316262d9f1334b299a89\", \"zlib_unittests\": \"9345de151e6e1e5571f055e4fb7eec6965f80741\", \"zucchini_unittests\": \"d571109f4423f275add2933062286100dfb7b720\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_a4ee364c4b5996d1649f16c77d7186222b75eb5b75762fbbe3baeec97aac12d9_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-8-uuz6\"], \"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\": [\"5673-2413400\"], \"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": "1612238614593567",
"status": "COMPLETED",
"status_changed_ts": "1612242557993422",
"tags": [
"build_address:luci.chromium.ci/win-asan/17123",
"builder:win-asan",
"buildset:commit/git/24c8af75a992523026279984102a742fc1fff90a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/24c8af75a992523026279984102a742fc1fff90a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021869068418126608",
"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/8856439006340955744/+/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:5179ba745ed42910",
"user_agent:luci-scheduler"
],
"updated_ts": "1612242558016124",
"url": "https://ci.chromium.org/b/8856439006340955744",
"utcnow_ts": "1612242559161227"
}
}
[05:09:19.179] Invocation finished in 1h5m53.502628786s with status SUCCEEDED