[03:54:52.223] New invocation is queued and will start shortly
[03:54:53.334] Starting the invocation (attempt 1)
[03:54:53.369] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:54:53.369] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:54:53.369] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027396021782233888",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"db0551621053329d6a000b4a3695e92f0d955759\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027396021782233888",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/db0551621053329d6a000b4a3695e92f0d955759",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/db0551621053329d6a000b4a3695e92f0d955759",
"gitiles_ref:refs/heads/master"
]
}
[03:54:53.949] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606967693442736",
"id": "8861965959839830592",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"db0551621053329d6a000b4a3695e92f0d955759\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606967693833731",
"tags": [
"build_address:luci.chromium.ci/win-asan/15997",
"builder:win-asan",
"buildset:commit/git/db0551621053329d6a000b4a3695e92f0d955759",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/db0551621053329d6a000b4a3695e92f0d955759",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027396021782233888",
"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/8861965959839830592/+/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": "1606967693833971",
"url": "https://ci.chromium.org/b/8861965959839830592",
"utcnow_ts": "1606967693939494"
}
}
[03:54:53.949] Task URL: https://ci.chromium.org/b/8861965959839830592
[03:54:53.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:2:0) after 1m0s
[03:54:59.231] Received PubSub notification, asking Buildbucket for the build status
[03:54:59.298] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:54.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:2:0)
[03:55:54.200] Timer tick, asking Buildbucket for the build status
[03:55:54.377] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:54.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:4:0) after 1m0s
[03:56:54.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:4:0)
[03:56:54.531] Timer tick, asking Buildbucket for the build status
[03:56:58.814] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:58.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:5:0) after 1m0s
[03:57:59.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:5:0)
[03:57:59.475] Timer tick, asking Buildbucket for the build status
[03:57:59.595] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:59.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:6:0) after 1m0s
[03:59:00.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:6:0)
[03:59:00.102] Timer tick, asking Buildbucket for the build status
[03:59:00.317] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:00.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:7:0) after 1m0s
[04:00:00.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:7:0)
[04:00:00.396] Timer tick, asking Buildbucket for the build status
[04:00:00.601] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:00.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:8:0) after 1m0s
[04:01:00.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:8:0)
[04:01:00.747] Timer tick, asking Buildbucket for the build status
[04:01:00.846] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:00.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:9:0) after 1m0s
[04:02:01.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:9:0)
[04:02:01.978] Timer tick, asking Buildbucket for the build status
[04:02:02.045] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:02.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:10:0) after 1m0s
[04:03:02.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:10:0)
[04:03:02.955] Timer tick, asking Buildbucket for the build status
[04:03:03.036] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:03.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:11:0) after 1m0s
[04:04:03.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:11:0)
[04:04:03.108] Timer tick, asking Buildbucket for the build status
[04:04:03.207] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:03.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:12:0) after 1m0s
[04:05:03.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:12:0)
[04:05:03.324] Timer tick, asking Buildbucket for the build status
[04:05:03.540] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:03.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:13:0) after 1m0s
[04:06:03.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:13:0)
[04:06:03.647] Timer tick, asking Buildbucket for the build status
[04:06:03.745] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:03.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:14:0) after 1m0s
[04:07:03.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:14:0)
[04:07:03.825] Timer tick, asking Buildbucket for the build status
[04:07:03.915] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:03.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:15:0) after 1m0s
[04:08:04.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:15:0)
[04:08:04.014] Timer tick, asking Buildbucket for the build status
[04:08:04.242] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:04.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:16:0) after 1m0s
[04:09:04.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:16:0)
[04:09:04.282] Timer tick, asking Buildbucket for the build status
[04:09:04.348] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:04.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:17:0) after 1m0s
[04:10:04.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:17:0)
[04:10:04.444] Timer tick, asking Buildbucket for the build status
[04:10:04.728] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:04.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:18:0) after 1m0s
[04:11:04.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:18:0)
[04:11:04.965] Timer tick, asking Buildbucket for the build status
[04:11:05.407] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:05.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:19:0) after 1m0s
[04:12:05.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:19:0)
[04:12:05.518] Timer tick, asking Buildbucket for the build status
[04:12:05.621] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:05.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:20:0) after 1m0s
[04:13:05.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:20:0)
[04:13:05.804] Timer tick, asking Buildbucket for the build status
[04:13:08.734] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:08.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:21:0) after 1m0s
[04:14:08.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:21:0)
[04:14:08.774] Timer tick, asking Buildbucket for the build status
[04:14:08.912] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:08.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:22:0) after 1m0s
[04:15:09.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:22:0)
[04:15:09.092] Timer tick, asking Buildbucket for the build status
[04:15:09.480] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:09.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:23:0) after 1m0s
[04:16:09.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:23:0)
[04:16:09.534] Timer tick, asking Buildbucket for the build status
[04:16:10.416] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:10.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:24:0) after 1m0s
[04:17:10.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:24:0)
[04:17:10.532] Timer tick, asking Buildbucket for the build status
[04:17:10.657] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:10.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:25:0) after 1m0s
[04:18:10.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:25:0)
[04:18:10.948] Timer tick, asking Buildbucket for the build status
[04:18:11.109] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:11.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:26:0) after 1m0s
[04:19:11.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:26:0)
[04:19:11.310] Timer tick, asking Buildbucket for the build status
[04:19:11.658] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:11.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:27:0) after 1m0s
[04:20:11.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:27:0)
[04:20:11.638] Timer tick, asking Buildbucket for the build status
[04:20:11.769] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:11.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:28:0) after 1m0s
[04:21:11.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:28:0)
[04:21:11.804] Timer tick, asking Buildbucket for the build status
[04:21:11.911] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:11.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:29:0) after 1m0s
[04:22:11.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:29:0)
[04:22:11.967] Timer tick, asking Buildbucket for the build status
[04:22:12.078] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:12.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:30:0) after 1m0s
[04:23:12.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:30:0)
[04:23:12.143] Timer tick, asking Buildbucket for the build status
[04:23:12.321] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:12.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:31:0) after 1m0s
[04:24:12.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:31:0)
[04:24:12.393] Timer tick, asking Buildbucket for the build status
[04:24:14.216] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:14.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:32:0) after 1m0s
[04:25:14.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:32:0)
[04:25:14.303] Timer tick, asking Buildbucket for the build status
[04:25:14.439] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:14.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:33:0) after 1m0s
[04:26:14.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:33:0)
[04:26:14.662] Timer tick, asking Buildbucket for the build status
[04:26:14.723] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:14.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:34:0) after 1m0s
[04:27:14.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:34:0)
[04:27:14.822] Timer tick, asking Buildbucket for the build status
[04:27:14.900] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:14.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:35:0) after 1m0s
[04:28:14.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:35:0)
[04:28:14.947] Timer tick, asking Buildbucket for the build status
[04:28:15.054] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:15.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:36:0) after 1m0s
[04:29:15.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:36:0)
[04:29:15.130] Timer tick, asking Buildbucket for the build status
[04:29:15.248] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:15.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:37:0) after 1m0s
[04:30:15.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:37:0)
[04:30:15.287] Timer tick, asking Buildbucket for the build status
[04:30:15.384] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:15.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:38:0) after 1m0s
[04:31:15.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:38:0)
[04:31:15.453] Timer tick, asking Buildbucket for the build status
[04:31:15.588] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:15.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:39:0) after 1m0s
[04:32:15.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:39:0)
[04:32:15.662] Timer tick, asking Buildbucket for the build status
[04:32:15.745] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:15.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:40:0) after 1m0s
[04:33:15.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:40:0)
[04:33:15.882] Timer tick, asking Buildbucket for the build status
[04:33:16.060] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:16.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:41:0) after 1m0s
[04:34:16.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:41:0)
[04:34:16.153] Timer tick, asking Buildbucket for the build status
[04:34:16.557] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:16.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:42:0) after 1m0s
[04:35:16.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:42:0)
[04:35:16.605] Timer tick, asking Buildbucket for the build status
[04:35:16.951] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:16.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:43:0) after 1m0s
[04:36:17.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:43:0)
[04:36:17.106] Timer tick, asking Buildbucket for the build status
[04:36:17.198] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:17.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:44:0) after 1m0s
[04:37:17.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:44:0)
[04:37:17.296] Timer tick, asking Buildbucket for the build status
[04:37:17.798] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:17.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:45:0) after 1m0s
[04:38:17.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:45:0)
[04:38:17.845] Timer tick, asking Buildbucket for the build status
[04:38:18.069] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:18.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:46:0) after 1m0s
[04:39:18.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:46:0)
[04:39:18.132] Timer tick, asking Buildbucket for the build status
[04:39:18.265] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:18.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:47:0) after 1m0s
[04:40:18.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:47:0)
[04:40:18.287] Timer tick, asking Buildbucket for the build status
[04:40:18.701] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:18.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:48:0) after 1m0s
[04:41:18.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:48:0)
[04:41:18.884] Timer tick, asking Buildbucket for the build status
[04:41:19.735] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:19.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:49:0) after 1m0s
[04:42:19.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:49:0)
[04:42:19.945] Timer tick, asking Buildbucket for the build status
[04:42:20.043] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:20.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:50:0) after 1m0s
[04:43:20.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:50:0)
[04:43:20.143] Timer tick, asking Buildbucket for the build status
[04:43:20.236] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:20.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:51:0) after 1m0s
[04:44:20.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:51:0)
[04:44:20.273] Timer tick, asking Buildbucket for the build status
[04:44:20.637] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:20.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:52:0) after 1m0s
[04:45:20.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:52:0)
[04:45:20.694] Timer tick, asking Buildbucket for the build status
[04:45:20.890] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:20.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:53:0) after 1m0s
[04:46:20.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:53:0)
[04:46:20.915] Timer tick, asking Buildbucket for the build status
[04:46:21.869] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:21.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:54:0) after 1m0s
[04:47:21.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:54:0)
[04:47:21.902] Timer tick, asking Buildbucket for the build status
[04:47:21.995] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:21.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:55:0) after 1m0s
[04:48:22.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:55:0)
[04:48:22.075] Timer tick, asking Buildbucket for the build status
[04:48:22.156] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:22.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:56:0) after 1m0s
[04:49:22.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:56:0)
[04:49:22.269] Timer tick, asking Buildbucket for the build status
[04:49:22.337] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:22.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:57:0) after 1m0s
[04:50:22.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:57:0)
[04:50:22.417] Timer tick, asking Buildbucket for the build status
[04:50:22.544] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:22.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:58:0) after 1m0s
[04:51:22.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:58:0)
[04:51:22.623] Timer tick, asking Buildbucket for the build status
[04:51:22.703] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:22.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:59:0) after 1m0s
[04:52:22.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:59:0)
[04:52:22.976] Timer tick, asking Buildbucket for the build status
[04:52:23.130] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:23.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:60:0) after 1m0s
[04:53:23.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:60:0)
[04:53:23.331] Timer tick, asking Buildbucket for the build status
[04:53:23.427] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:23.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:61:0) after 1m0s
[04:54:23.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:61:0)
[04:54:23.579] Timer tick, asking Buildbucket for the build status
[04:54:23.648] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:23.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:62:0) after 1m0s
[04:55:23.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:62:0)
[04:55:23.747] Timer tick, asking Buildbucket for the build status
[04:55:23.870] Build 8861965959839830592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:23.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027396021782233888:63:0) after 1m0s
[04:55:41.379] Received PubSub notification, asking Buildbucket for the build status
[04:55:41.448] Build 8861965959839830592: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:55:41.448] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606971339638447",
"created_by": "project:chromium",
"created_ts": "1606967693442736",
"id": "8861965959839830592",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"db0551621053329d6a000b4a3695e92f0d955759\"}}",
"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\": \"db0551621053329d6a000b4a3695e92f0d955759\", \"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-9-v8md\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15997, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"abe9657839a4366d23477eb2d30146dad041d23d\", \"got_dawn_revision\": \"429a12542f160a36d87c1daa6c3102bce072207e\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"db0551621053329d6a000b4a3695e92f0d955759\", \"got_revision_cp\": \"refs/heads/master@{#833136}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bae138de13ac876cbca0e30a40b89dc808e2b29d\", \"got_v8_revision\": \"5b8d12f1c305ceabc39187991c8eb1c0cb0d4d3d\", \"got_v8_revision_cp\": \"refs/heads/8.9.83@{#1}\", \"got_webrtc_revision\": \"6e7167456b5eba36c7985d6a74f1d191958d4e0f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32749}\", \"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\": \"db0551621053329d6a000b4a3695e92f0d955759\", \"swarm_hashes_refs/heads/master(at){#833136}_without_patch\": {\"absl_hardening_tests\": \"64a4313aad04c27264e7fbe3001fe6a4e8ea6f62\", \"accessibility_unittests\": \"8ffc45f2bb10041962c6a77e3b915ab5faf1e37f\", \"angle_unittests\": \"4856fa758a908d1c3a49f2bcc1cf530240c442db\", \"app_shell_unittests\": \"2df16ced68fa4db9927e72772408ef6cf0f41a48\", \"aura_unittests\": \"13347020ad294d38003bf8734ceb99dc4fd85cae\", \"base_unittests\": \"feb1f4fc59fb19d5062596596ab492994d8eb715\", \"base_util_unittests\": \"f9da924ecf5091772aeed962a28d32dd3fc24ce5\", \"blink_common_unittests\": \"aac81709d9a436a0edb45f65881b8876d4fb7e33\", \"blink_fuzzer_unittests\": \"2d9983656dc475c8c37f9d1454930f7bbe038e88\", \"blink_heap_unittests\": \"cf9c834c05185078abb711bfeb4dfb6e28675d90\", \"blink_platform_unittests\": \"89b5c483e7726dea0ca508646a76cc76fcb0f1c2\", \"blink_unittests\": \"f8af758208c82dce5745f64adab256c838a14f74\", \"boringssl_crypto_tests\": \"9d6d035ba66f1042b3aa635371d05db41f5e0f30\", \"boringssl_ssl_tests\": \"5de903fc0e00adb75de8a22571cd301d6ac640f5\", \"browser_switcher_bho_unittests\": \"da1267778f952c3479d2d9d0e29cf3b1573f0c18\", \"browser_tests\": \"47e1c67ee13fcb7714ca6de0d1e17bcaa8ee83c0\", \"capture_unittests\": \"431dbb6faeafa01a93230a29d27c969c62415a9f\", \"cast_unittests\": \"259d88c51916eefe776ab6f3523a04b1e6cb47e5\", \"cc_unittests\": \"3e5368fda260e6ea36cab41a49af87bc3fc8df03\", \"chrome_app_unittests\": \"68fe8a916afa79f11981ef7994dd92c170e5de5b\", \"chrome_cleaner_unittests\": \"c67a39b7d1d4f89cbf961e29f293098f37915fe3\", \"chrome_elf_unittests\": \"774ae00edac5c8db3ca56dcb10199f9f8b1da94f\", \"chromedriver_unittests\": \"1c940672f2083eb829fb99f25f2b2c776a2d9a0f\", \"components_browsertests\": \"1f29077f570772f26c831988c6dc287c628a73fd\", \"components_unittests\": \"4d8eb9a09c835123bc89b76db066c90c26948d81\", \"compositor_unittests\": \"bbaf59ec47e26c9262390458386799ce7185c4da\", \"content_browsertests\": \"0c6b4dcfec7e30c2469b0ed744826c28b1762a79\", \"content_unittests\": \"e2b1878d61c10c915e61de3ada7babd7382ff2d1\", \"courgette_unittests\": \"f9d8445a30c72ded1af6aaf797f7dc8ba1581c18\", \"crashpad_tests\": \"03c58fc0ed4aebb212260c1c35a6bf681415e99b\", \"cronet_tests\": \"e23546b606acb0bbe8b9a228b6ac0c24bd062605\", \"cronet_unittests\": \"b860bf31d4355bc7cbf77af95a2f06e69637800f\", \"crypto_unittests\": \"d35efb91675b8301dd99a89b4ef7880110121f79\", \"delayloads_unittests\": \"6264998faac6d4e480e44f2cafc6fade6d8c851c\", \"device_unittests\": \"dca636857eed1b20bec0f329f2d4dd2426e50f08\", \"display_unittests\": \"75d6255083b49718c6b63f887ba96526dfd1dbd8\", \"elevation_service_unittests\": \"362d942ed122f7960e86c45f1888bef1288eff46\", \"events_unittests\": \"a3c76e9bd6cbc4a6593582412212c39dd377065a\", \"extensions_browsertests\": \"a48f3cab714b0c41f98f0c8c7b476ddca3ad8cd7\", \"extensions_unittests\": \"186818bbb2ee8c0dfbdec74cf06518d239c10958\", \"filesystem_service_unittests\": \"3ed34bf2f0d9c34d044d206da7fe8b718c01cd97\", \"gcm_unit_tests\": \"b6e24a583912ff68d8189de723dde37aa042e523\", \"gcp_unittests\": \"87eb86f9530985b89dca569383583eb7fbeacfd4\", \"gfx_unittests\": \"5a497fbe4780e14d119e69579e5608e2a22238fb\", \"gin_unittests\": \"f568674b49c2a8d4e58fbd4d9409d3f337a21602\", \"google_apis_unittests\": \"fdda96b8dc8a879ed21ebaa2a0100dc151a9ba80\", \"gpu_unittests\": \"91d53a0b56eb88acf145dbf15f49d06c857befd6\", \"gwp_asan_unittests\": \"c7c105583749823e5a8bec5fa7c872bd16c63f12\", \"headless_browsertests\": \"ae35cd4900b613fd755e5a8399690eb15f4cd4f2\", \"headless_unittests\": \"3b5c58c27e6ec00d597e3184e7db67b76208b923\", \"install_static_unittests\": \"dd27b40778ed72cb7aaa2e7095cd6bc18e0c168d\", \"installer_util_unittests\": \"0c1d938f114806523786467599cfa78b3c9d60c9\", \"interactive_ui_tests\": \"8daf480be3c46fae4c65a3df02558ae273f318fd\", \"ipc_tests\": \"edf7f674ba218fdc814bfeb5f017106df3c6407c\", \"jingle_unittests\": \"f526bfba0ce720a43af24adcf6fb1fc3c92683a8\", \"latency_unittests\": \"3fd897451ab0a5f8d8b75d92409c57521c4e352d\", \"libjingle_xmpp_unittests\": \"f74a3d0c6208af1c1ac0084d283014088d7ea382\", \"liburlpattern_unittests\": \"02721510f44bbf50401c4a55033e8df88adcddf9\", \"media_blink_unittests\": \"c75a412074825976b55dd3f7d6951f26be948086\", \"media_unittests\": \"ea338bc4ec1fdba46893e36f0c237f711a08a9eb\", \"message_center_unittests\": \"79b32fd173eaed1e6f379d174a607b35dee73dea\", \"midi_unittests\": \"36378f09f013f517d6162449f825d060429239cc\", \"mojo_core_unittests\": \"d24e90f86c3ada41ea568f2edf7f3910a6e9618d\", \"mojo_unittests\": \"13c923e9d5c6bb7b570ff541e8e24f1e5670709f\", \"nacl_loader_unittests\": \"134e2f25f48a9c7e2df59a6dc83cc036efa249a9\", \"native_theme_unittests\": \"037af1c0898fff1507ae09e28fc3a26c79ad030b\", \"net_unittests\": \"b362928c1d2db671d4ece4f69977e728e2ed37c1\", \"notification_helper_unittests\": \"8f1ebb07a8147db3f37332dd4e7d31cc2e862252\", \"pdf_unittests\": \"a5e4749d7fa8373b29269ac785297831e8df3fca\", \"perfetto_unittests\": \"ce9c348c85a1ef40aed4c6467679c945d82fd88d\", \"ppapi_unittests\": \"901b30621aae6b65f8fff8a258ffc18a05ff71b8\", \"printing_unittests\": \"d2b5232d50d9647de0edaaf7d4aab77078aa9839\", \"remoting_unittests\": \"8fb26cee04abd135bb5953b571d04d5715787592\", \"sbox_integration_tests\": \"65e18ef55ad34ac03f7f18760943664b167cad6c\", \"sbox_unittests\": \"2c003dbbd2f0ef68547bcfee156dc87e03ef43ad\", \"sbox_validation_tests\": \"5ecaa58169bf7a07e27c7241c70fdba3ded22b68\", \"service_manager_unittests\": \"ad6f9100a2a0759506e5edc063e09bbe9a5cdef3\", \"services_unittests\": \"c8e949a73aa8384935c1d35a466738f9d4886d1d\", \"setup_unittests\": \"d3e98f6125503c92a0549b48157add6ea5045b68\", \"shell_dialogs_unittests\": \"3694bf889601c73be88230736dcabc3c4df83e39\", \"skia_unittests\": \"5d36da635473214ef6dba0c0cf81d34820a0fc3b\", \"snapshot_unittests\": \"fe5d7853df9af36bc4a4d1395775166fcd2500a9\", \"sql_unittests\": \"ba371e4b744aed42e58045fb435ab72ddb9ff9bd\", \"storage_unittests\": \"865aa667f5ae58326846c8e62b7f2f7778c75f4a\", \"sync_integration_tests\": \"7c87d26b65d966bf463214ffe5f9d9c026f1de96\", \"traffic_annotation_auditor_unittests\": \"c96bf9ea3743cc06a95dfa0cea25f25bfa58dc35\", \"ui_base_unittests\": \"f77bafccee20bcb1d8685093bb8b1d5ad21f0deb\", \"ui_touch_selection_unittests\": \"a40dfa12cddc1a86dbe41f88eaab434d3c6b4e5c\", \"unit_tests\": \"2bae9b828aef301471c27f3ed65e8a3b2c3a2ea8\", \"updater_tests\": \"d5e5a7d7d998bb23d70c7df6aebc13497d3b5bff\", \"url_unittests\": \"143dd7798e75f3368d5f9f3010e35bfae6607be1\", \"views_unittests\": \"ef1a9f06e71c83c8e048f472d2020b34e3074924\", \"viz_unittests\": \"baf9679e226aecaae5cf641beeb482a3201b447a\", \"vr_common_unittests\": \"c83f4c9e4c77268807e3b28db81a8f006dce21c9\", \"vr_pixeltests\": \"56f84cae97e7a0791faf4f4937628bf163c28db3\", \"weblayer_browsertests\": \"83b1a6cbb29994d6b49ccca8eba0a899b02f06e0\", \"weblayer_unittests\": \"83ccc598451ec5899ec0a61d4bcb18d2fc55d179\", \"wm_unittests\": \"323ce98e98b38a4cf10e23d2111328fc47bb131d\", \"wtf_unittests\": \"9664bb6d9a5e23bb8a9496a4cb26177e1bbf0a5c\", \"zlib_unittests\": \"33b8646087a668bb83af16655b5b97b0526a0acd\", \"zucchini_unittests\": \"32cf9292646ea9403e6d7e4835f0ae6d496389f1\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-9-v8md\"], \"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\": [\"5640-d0eb544\"], \"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": "1606967698692637",
"status": "COMPLETED",
"status_changed_ts": "1606971340100082",
"tags": [
"build_address:luci.chromium.ci/win-asan/15997",
"builder:win-asan",
"buildset:commit/git/db0551621053329d6a000b4a3695e92f0d955759",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/db0551621053329d6a000b4a3695e92f0d955759",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027396021782233888",
"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/8861965959839830592/+/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:503f8e9ae3969e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606971340113729",
"url": "https://ci.chromium.org/b/8861965959839830592",
"utcnow_ts": "1606971341439481"
}
}
[04:55:41.448] Invocation finished in 1h0m49.241860521s with status SUCCEEDED