[12:01:56.911] New invocation is queued and will start shortly
[12:01:58.008] Starting the invocation (attempt 1)
[12:01:58.050] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:01:58.050] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:01:58.050] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028452541072767904",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"da6246d1752037fddcb027d5415bb25054202439\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028452541072767904",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/da6246d1752037fddcb027d5415bb25054202439",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/da6246d1752037fddcb027d5415bb25054202439",
"gitiles_ref:refs/heads/master"
]
}
[12:01:58.887] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605960118166919",
"id": "8863022479091989920",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"da6246d1752037fddcb027d5415bb25054202439\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605960118766930",
"tags": [
"build_address:luci.chromium.ci/win-asan/15758",
"builder:win-asan",
"buildset:commit/git/da6246d1752037fddcb027d5415bb25054202439",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/da6246d1752037fddcb027d5415bb25054202439",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028452541072767904",
"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/8863022479091989920/+/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": "1605960118767051",
"url": "https://ci.chromium.org/b/8863022479091989920",
"utcnow_ts": "1605960118880345"
}
}
[12:01:58.887] Task URL: https://ci.chromium.org/b/8863022479091989920
[12:01:58.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:2:0) after 1m0s
[12:02:38.411] Received PubSub notification, asking Buildbucket for the build status
[12:02:38.481] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:59.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:2:0)
[12:02:59.078] Timer tick, asking Buildbucket for the build status
[12:02:59.163] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:59.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:4:0) after 1m0s
[12:03:59.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:4:0)
[12:03:59.262] Timer tick, asking Buildbucket for the build status
[12:03:59.394] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:59.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:5:0) after 1m0s
[12:04:59.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:5:0)
[12:04:59.450] Timer tick, asking Buildbucket for the build status
[12:04:59.774] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:59.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:6:0) after 1m0s
[12:05:59.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:6:0)
[12:05:59.831] Timer tick, asking Buildbucket for the build status
[12:06:01.532] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:01.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:7:0) after 1m0s
[12:07:02.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:7:0)
[12:07:02.070] Timer tick, asking Buildbucket for the build status
[12:07:02.223] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:02.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:8:0) after 1m0s
[12:08:02.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:8:0)
[12:08:02.261] Timer tick, asking Buildbucket for the build status
[12:08:02.713] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:02.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:9:0) after 1m0s
[12:09:02.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:9:0)
[12:09:02.885] Timer tick, asking Buildbucket for the build status
[12:09:02.952] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:02.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:10:0) after 1m0s
[12:10:03.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:10:0)
[12:10:03.083] Timer tick, asking Buildbucket for the build status
[12:10:03.152] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:03.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:11:0) after 1m0s
[12:11:03.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:11:0)
[12:11:03.232] Timer tick, asking Buildbucket for the build status
[12:11:03.342] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:03.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:12:0) after 1m0s
[12:12:03.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:12:0)
[12:12:03.428] Timer tick, asking Buildbucket for the build status
[12:12:03.573] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:03.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:13:0) after 1m0s
[12:13:03.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:13:0)
[12:13:03.576] Timer tick, asking Buildbucket for the build status
[12:13:03.743] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:03.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:14:0) after 1m0s
[12:14:03.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:14:0)
[12:14:03.939] Timer tick, asking Buildbucket for the build status
[12:14:04.368] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:04.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:15:0) after 1m0s
[12:15:04.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:15:0)
[12:15:04.826] Timer tick, asking Buildbucket for the build status
[12:15:05.003] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:05.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:16:0) after 1m0s
[12:16:05.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:16:0)
[12:16:05.229] Timer tick, asking Buildbucket for the build status
[12:16:05.374] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:05.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:17:0) after 1m0s
[12:17:05.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:17:0)
[12:17:05.547] Timer tick, asking Buildbucket for the build status
[12:17:05.753] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:05.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:18:0) after 1m0s
[12:18:05.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:18:0)
[12:18:05.951] Timer tick, asking Buildbucket for the build status
[12:18:06.067] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:06.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:19:0) after 1m0s
[12:19:06.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:19:0)
[12:19:06.132] Timer tick, asking Buildbucket for the build status
[12:19:06.244] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:06.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:20:0) after 1m0s
[12:20:06.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:20:0)
[12:20:06.280] Timer tick, asking Buildbucket for the build status
[12:20:06.489] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:06.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:21:0) after 1m0s
[12:21:06.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:21:0)
[12:21:06.578] Timer tick, asking Buildbucket for the build status
[12:21:06.697] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:06.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:22:0) after 1m0s
[12:22:06.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:22:0)
[12:22:06.859] Timer tick, asking Buildbucket for the build status
[12:22:07.597] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:07.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:23:0) after 1m0s
[12:23:07.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:23:0)
[12:23:07.663] Timer tick, asking Buildbucket for the build status
[12:23:07.780] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:07.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:24:0) after 1m0s
[12:24:07.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:24:0)
[12:24:07.832] Timer tick, asking Buildbucket for the build status
[12:24:08.002] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:08.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:25:0) after 1m0s
[12:25:08.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:25:0)
[12:25:08.213] Timer tick, asking Buildbucket for the build status
[12:25:08.471] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:08.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:26:0) after 1m0s
[12:26:10.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:26:0)
[12:26:10.278] Timer tick, asking Buildbucket for the build status
[12:26:10.818] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:10.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:27:0) after 1m0s
[12:27:10.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:27:0)
[12:27:10.838] Timer tick, asking Buildbucket for the build status
[12:27:11.518] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:11.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:28:0) after 1m0s
[12:28:11.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:28:0)
[12:28:11.594] Timer tick, asking Buildbucket for the build status
[12:28:11.721] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:11.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:29:0) after 1m0s
[12:29:11.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:29:0)
[12:29:11.765] Timer tick, asking Buildbucket for the build status
[12:29:11.845] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:11.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:30:0) after 1m0s
[12:30:11.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:30:0)
[12:30:11.865] Timer tick, asking Buildbucket for the build status
[12:30:12.113] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:12.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:31:0) after 1m0s
[12:31:12.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:31:0)
[12:31:12.163] Timer tick, asking Buildbucket for the build status
[12:31:12.245] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:12.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:32:0) after 1m0s
[12:32:12.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:32:0)
[12:32:12.256] Timer tick, asking Buildbucket for the build status
[12:32:12.370] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:12.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:33:0) after 1m0s
[12:33:12.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:33:0)
[12:33:12.429] Timer tick, asking Buildbucket for the build status
[12:33:12.675] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:12.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:34:0) after 1m0s
[12:34:12.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:34:0)
[12:34:12.732] Timer tick, asking Buildbucket for the build status
[12:34:12.901] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:12.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:35:0) after 1m0s
[12:35:12.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:35:0)
[12:35:12.931] Timer tick, asking Buildbucket for the build status
[12:35:12.996] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:12.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:36:0) after 1m0s
[12:36:13.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:36:0)
[12:36:13.038] Timer tick, asking Buildbucket for the build status
[12:36:13.123] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:13.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:37:0) after 1m0s
[12:37:13.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:37:0)
[12:37:13.173] Timer tick, asking Buildbucket for the build status
[12:37:13.310] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:13.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:38:0) after 1m0s
[12:38:13.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:38:0)
[12:38:13.328] Timer tick, asking Buildbucket for the build status
[12:38:13.627] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:13.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:39:0) after 1m0s
[12:39:13.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:39:0)
[12:39:13.647] Timer tick, asking Buildbucket for the build status
[12:39:14.003] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:14.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:40:0) after 1m0s
[12:40:14.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:40:0)
[12:40:14.025] Timer tick, asking Buildbucket for the build status
[12:40:14.157] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:14.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:41:0) after 1m0s
[12:41:14.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:41:0)
[12:41:14.140] Timer tick, asking Buildbucket for the build status
[12:41:14.290] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:14.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:42:0) after 1m0s
[12:42:14.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:42:0)
[12:42:14.320] Timer tick, asking Buildbucket for the build status
[12:42:14.465] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:14.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:43:0) after 1m0s
[12:43:14.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:43:0)
[12:43:14.530] Timer tick, asking Buildbucket for the build status
[12:43:14.668] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:14.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:44:0) after 1m0s
[12:44:14.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:44:0)
[12:44:14.718] Timer tick, asking Buildbucket for the build status
[12:44:14.831] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:14.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:45:0) after 1m0s
[12:45:14.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:45:0)
[12:45:14.846] Timer tick, asking Buildbucket for the build status
[12:45:14.925] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:14.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:46:0) after 1m0s
[12:46:14.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:46:0)
[12:46:14.956] Timer tick, asking Buildbucket for the build status
[12:46:15.083] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:15.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:47:0) after 1m0s
[12:47:15.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:47:0)
[12:47:15.113] Timer tick, asking Buildbucket for the build status
[12:47:15.569] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:15.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:48:0) after 1m0s
[12:48:15.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:48:0)
[12:48:15.607] Timer tick, asking Buildbucket for the build status
[12:48:15.685] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:15.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:49:0) after 1m0s
[12:49:15.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:49:0)
[12:49:15.707] Timer tick, asking Buildbucket for the build status
[12:49:15.784] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:15.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:50:0) after 1m0s
[12:50:15.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:50:0)
[12:50:15.790] Timer tick, asking Buildbucket for the build status
[12:50:15.871] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:15.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:51:0) after 1m0s
[12:51:15.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:51:0)
[12:51:15.891] Timer tick, asking Buildbucket for the build status
[12:51:15.960] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:15.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:52:0) after 1m0s
[12:52:15.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:52:0)
[12:52:15.947] Timer tick, asking Buildbucket for the build status
[12:52:16.119] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:16.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:53:0) after 1m0s
[12:53:16.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:53:0)
[12:53:16.140] Timer tick, asking Buildbucket for the build status
[12:53:16.774] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:16.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:54:0) after 1m0s
[12:54:16.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:54:0)
[12:54:16.795] Timer tick, asking Buildbucket for the build status
[12:54:16.877] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:16.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:55:0) after 1m0s
[12:55:16.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:55:0)
[12:55:16.937] Timer tick, asking Buildbucket for the build status
[12:55:17.010] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:17.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:56:0) after 1m0s
[12:56:17.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:56:0)
[12:56:17.023] Timer tick, asking Buildbucket for the build status
[12:56:17.099] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:17.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:57:0) after 1m0s
[12:57:17.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:57:0)
[12:57:17.356] Timer tick, asking Buildbucket for the build status
[12:57:17.496] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:17.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:58:0) after 1m0s
[12:58:17.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:58:0)
[12:58:17.568] Timer tick, asking Buildbucket for the build status
[12:58:17.639] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:17.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:59:0) after 1m0s
[12:59:17.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:59:0)
[12:59:17.748] Timer tick, asking Buildbucket for the build status
[12:59:17.868] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:17.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:60:0) after 1m0s
[13:00:18.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:60:0)
[13:00:18.127] Timer tick, asking Buildbucket for the build status
[13:00:18.241] Build 8863022479091989920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:18.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028452541072767904:61:0) after 1m0s
[13:00:57.593] Received PubSub notification, asking Buildbucket for the build status
[13:00:57.746] Build 8863022479091989920: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:00:57.746] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605963653768641",
"created_by": "project:chromium",
"created_ts": "1605960118166919",
"id": "8863022479091989920",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"da6246d1752037fddcb027d5415bb25054202439\"}}",
"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\": \"da6246d1752037fddcb027d5415bb25054202439\", \"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-3-xoby\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15758, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"e51c9068a84f14e42157c097eb764ec3009e0e54\", \"got_dawn_revision\": \"41b3f9c1e4aaaa3d56d205d81e61deaebdd40d75\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"da6246d1752037fddcb027d5415bb25054202439\", \"got_revision_cp\": \"refs/heads/master@{#829984}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"6d612051c083238db89541be4fbb2d624a9baef4\", \"got_v8_revision\": \"22f2e5ef66ed5c820532f27b141096d3ddb1966e\", \"got_v8_revision_cp\": \"refs/heads/8.9.31@{#1}\", \"got_webrtc_revision\": \"7d75f2ca782f38006bb65bc67533e582190454e8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32659}\", \"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\": \"da6246d1752037fddcb027d5415bb25054202439\", \"swarm_hashes_refs/heads/master(at){#829984}_without_patch\": {\"absl_hardening_tests\": \"03f8cf3a2f4169627e0ed28ba69c5c84c44e9bca\", \"accessibility_unittests\": \"97c0704b4494051c1e6c227a3caa910daf19593d\", \"angle_unittests\": \"61d00a2ca7432bf2f24d1cfe2e38adfe0b5dada6\", \"app_shell_unittests\": \"9cf63121bf5d7dcc0d633a3bf92a81e90c628790\", \"aura_unittests\": \"968500870ab7f3f930dc3bbce8a4d944aaaa9253\", \"base_unittests\": \"b17ffdd03697a33f343c1c2a67807ddb161c7985\", \"base_util_unittests\": \"812836bc6dd6835f64320125857ead7b6fcb58da\", \"blink_common_unittests\": \"d3f1bec83452fe3982b22f17a74bd4b3e275e649\", \"blink_fuzzer_unittests\": \"0ee9fedd63ac9d8162441549c59a8b42f756b3a5\", \"blink_heap_unittests\": \"0589b4e547180fa23fa0c5c234f3f55f5be8903a\", \"blink_platform_unittests\": \"9c440aed699ef9b21570386ed6cb20be79b10334\", \"blink_unittests\": \"f2a0a6cf85ef4fae20ca638ef052ee67e2da9741\", \"boringssl_crypto_tests\": \"437c75175903f17674a9426a491631b4128312e4\", \"boringssl_ssl_tests\": \"f4ff432e1af43e5acb487de694833bb9c814729b\", \"browser_switcher_bho_unittests\": \"919516cdd26c2924eef5e801aba557510c19e98d\", \"browser_tests\": \"9c850a6c5ff48912f90c91a5b3afe1954658dbf0\", \"capture_unittests\": \"63992950517e3d3ddba196f40c48b6b7ed4eb61f\", \"cast_unittests\": \"73e8102ba34907fa6253124a1d41cefc774abb06\", \"cc_unittests\": \"4147b08ef10087d32c3fe3266bbb42a3b80be256\", \"chrome_app_unittests\": \"93410d947a430a73480082e5405efa04991f6231\", \"chrome_cleaner_unittests\": \"bf8274db752e05f8502d3d27f11d3c5c361f2f60\", \"chrome_elf_unittests\": \"7c51546960b6c73f3b393f776129507670c591c2\", \"chromedriver_unittests\": \"5f656cf776b8c6956f446657a8fc58e87544fc1d\", \"components_browsertests\": \"156f71327c61a64d23bfe35ab14352c3d5d15450\", \"components_unittests\": \"a0b2af0a8593336bc3db16cce33fd0c8165525f1\", \"compositor_unittests\": \"0c9372c18d1c26b1d4c39cd4abbc9a98f1504f80\", \"content_browsertests\": \"f6c51767e87b75819ba4ee3688a79659e51afabe\", \"content_unittests\": \"190736f09268e5b43d43b3b46b67663c64d25e5c\", \"courgette_unittests\": \"a17f5074c9c443370901ebbd313a73fad4f2232d\", \"crashpad_tests\": \"2fa8caccdc442fd1d58e8af08fc7595ed23482c8\", \"cronet_tests\": \"64a1a87b84d94ba28aa36b43e4349cb99bb90ceb\", \"cronet_unittests\": \"77db119592d5c1ef68e9c029cfddd9430a31f9f6\", \"crypto_unittests\": \"3da2dbc00740440fb318d4c7ff7565d884f2c7d4\", \"delayloads_unittests\": \"a72e96946707773a0fa621e4f0bc7eb6acfc2cbb\", \"device_unittests\": \"7f0649fad37fbe3261acc2bd42195ff856b4c5c9\", \"display_unittests\": \"f130d4305b32a01ba2d3a86969b6b9a32ebd5fcd\", \"elevation_service_unittests\": \"c8083af5f3ac165fdcd24b4c653242616d75af13\", \"events_unittests\": \"0bda69f104ba75a5b5e7c8d5b3e777aaacc10b09\", \"extensions_browsertests\": \"4135a7dc017c5d70698eed13b849817e7b82de34\", \"extensions_unittests\": \"f2e9b2f1992949f1fa2a8ba6d8486c47d4bf5ff1\", \"filesystem_service_unittests\": \"fdac573d1382394d2f151c6d3bfbeb63e15a0cc3\", \"gcm_unit_tests\": \"65594e4978db1eb2d0be82cac1245a8ee3bca796\", \"gcp_unittests\": \"6ac31451ed325404b1f3e819ca0727acf18acc18\", \"gfx_unittests\": \"d79f7e7395568dca43d47ac9c378debc187ef075\", \"gin_unittests\": \"f3c145ba478a44a5a280790d3f2f8f22aa41d9e1\", \"google_apis_unittests\": \"41113b35b4049c25ff1c639b2ea66e21e5e7b05f\", \"gpu_unittests\": \"d5238e004586f9f85fceb8a6c66bbd6fd98319bc\", \"gwp_asan_unittests\": \"d089adbe34f9eb3581284df92e7c9b9cae7bf80e\", \"headless_browsertests\": \"ff53e5437032aadb51d83b81fe67c87505b37ece\", \"headless_unittests\": \"48abe6a6234a6815aa965b92a0c67413eb3bf53d\", \"install_static_unittests\": \"2edf1b7d23afcb4022d3824355ed553468a3e500\", \"installer_util_unittests\": \"ed8e447f00040972a02f289db2039e3939a5e194\", \"interactive_ui_tests\": \"ffe1b4ca8169945421886e914e9d5b08351d2197\", \"ipc_tests\": \"8a91f2ffb2683809f4c32af4109fa87e6e4cee65\", \"jingle_unittests\": \"cfb21d9ebb28597793be794117dba0989b85a9ae\", \"latency_unittests\": \"4ce69b73e3a901d5d888b50430d6b8d41ad16157\", \"libjingle_xmpp_unittests\": \"572ece09f722d4c605e3b5329e22992e71ddf109\", \"liburlpattern_unittests\": \"4d9b160b32a730f6228271723d31ba3533ea8a90\", \"media_blink_unittests\": \"f31f401f2a6749ef89e54ae81cda7e232ca80f93\", \"media_unittests\": \"060096f9b032318520999c23e0ef5ac11e4c1088\", \"message_center_unittests\": \"edab0fefc4afa8c2d0e2afd704b2ce35965f2748\", \"midi_unittests\": \"d1c87dca65125232d5addd6c538d49aa18b2b6dc\", \"mojo_core_unittests\": \"475f21aeb586ace23eee0c18ba22b4aafbea0bc3\", \"mojo_unittests\": \"9e153544bcc0932d8eb2a0a99ae20eee12087872\", \"nacl_loader_unittests\": \"82391c67196310f01422aafd6ace01e848ddae08\", \"native_theme_unittests\": \"117b637313f6fe55db3b0c398acae23f6701fd4d\", \"net_unittests\": \"3cd506f18c18a86a4994dbfe009d8c251640b384\", \"notification_helper_unittests\": \"bc6656bd91026021135992e0d431dc4b3e2ab0b0\", \"pdf_unittests\": \"17962406c18eedb85dcf776dfca5587cabd4c49c\", \"perfetto_unittests\": \"82bd99ddee7719e34966774e38dc3b74958506d3\", \"ppapi_unittests\": \"8d25024bdd713042d6d01a3e607c49bcd0cb0666\", \"printing_unittests\": \"a57700a607b93b52093387cd7be85d88add16b2b\", \"remoting_unittests\": \"b6501e4f1e6e979fe30982f842dadc4aa3066db4\", \"sbox_integration_tests\": \"e6bd515c87268308824a2c219974ac6df2d152d1\", \"sbox_unittests\": \"fb25190ce879ae23856e8fa9ec2df388ea4a81f0\", \"sbox_validation_tests\": \"73711daf6e4bee6a49876d8236a80596d9026575\", \"service_manager_unittests\": \"481a7bfd90022efb76b7238b2baa2edf3bdf9204\", \"services_unittests\": \"c94c6542f0293d911aeae8eded284ed9676eb08d\", \"setup_unittests\": \"0c2a9d8e1807fd404b8a1c59659f1e62375e3b8d\", \"shell_dialogs_unittests\": \"d6376afd5fed0f5be684571639d72faad89f0f0b\", \"skia_unittests\": \"9c0dc336f73eb58b14b966dc7ddcdca215b85d0b\", \"snapshot_unittests\": \"cff283663998ae01a39ff6f9c56710b036b54496\", \"sql_unittests\": \"b758c138cfee371b392954b82b108423636c39bc\", \"storage_unittests\": \"ecfc70231b1a93e989f099ac5899cf8df719856b\", \"sync_integration_tests\": \"3e1172724a384efef41a0db1c6dd1f461ea7bade\", \"traffic_annotation_auditor_unittests\": \"f47a08f7c985d97872e41dcc546da1e45a12b3a7\", \"ui_base_unittests\": \"abc4e68a816b5927a05cd034a6258d57ad95c53e\", \"ui_touch_selection_unittests\": \"df8b5b97986608b9b2bd31572b99147ae8ebd650\", \"unit_tests\": \"26780ba4a3503f661b558808722277b0443b3a29\", \"updater_tests\": \"a25b0c3c767e1bea67653998e745992c6fbd2a33\", \"url_unittests\": \"6ad24ee46ed673531cf4d3fba2020f90e7f10499\", \"views_unittests\": \"edb032a9d7de3b120e81e30a7a013b4cd13c4b4c\", \"viz_unittests\": \"1b5e7647412cecfa076296b04e5e50da06759baf\", \"vr_common_unittests\": \"0182bfcebdc47cf1e6fd8732ef33a6930727170f\", \"vr_pixeltests\": \"2a5e9663e36690ce12c3759fd61b2cb5f961c8f8\", \"weblayer_browsertests\": \"95ddb1deb8d5dc35319f13aac5c9d3551c869c55\", \"weblayer_unittests\": \"25273754a664a1fb1a18f369e9038fdb17196176\", \"wm_unittests\": \"81dbd6bf121baf87f582413eede6618e4599c130\", \"wtf_unittests\": \"d3fe8f56d8096b6e7722872bc66b3fa7761ad12b\", \"zlib_unittests\": \"ba6da342d7ae880f9c35d222d5c5daa0c2a982a3\", \"zucchini_unittests\": \"75335a4920051e8a5388fc68fa6c32da0ae47dce\"}}, \"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-3-xoby\"], \"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\": [\"5637-4d8b832\"], \"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": "1605960145532971",
"status": "COMPLETED",
"status_changed_ts": "1605963654894520",
"tags": [
"build_address:luci.chromium.ci/win-asan/15758",
"builder:win-asan",
"buildset:commit/git/da6246d1752037fddcb027d5415bb25054202439",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/da6246d1752037fddcb027d5415bb25054202439",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028452541072767904",
"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/8863022479091989920/+/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:500380429d046d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605963655038040",
"url": "https://ci.chromium.org/b/8863022479091989920",
"utcnow_ts": "1605963657731802"
}
}
[13:00:57.746] Invocation finished in 59m0.852082467s with status SUCCEEDED