[03:24:17.089] New invocation is queued and will start shortly
[03:24:18.364] Starting the invocation (attempt 1)
[03:24:18.428] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:24:18.428] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:24:18.429] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027216752132861440",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bbc1b315811609fb633aeb4249e2fab63de895e2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027216752132861440",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/bbc1b315811609fb633aeb4249e2fab63de895e2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbc1b315811609fb633aeb4249e2fab63de895e2",
"gitiles_ref:refs/heads/master"
]
}
[03:24:19.279] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1607138658609644",
"id": "8861786689868677696",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bbc1b315811609fb633aeb4249e2fab63de895e2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607138659123230",
"tags": [
"build_address:luci.chromium.ci/win-asan/16035",
"builder:win-asan",
"buildset:commit/git/bbc1b315811609fb633aeb4249e2fab63de895e2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbc1b315811609fb633aeb4249e2fab63de895e2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027216752132861440",
"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/8861786689868677696/+/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": "1607138659123393",
"url": "https://ci.chromium.org/b/8861786689868677696",
"utcnow_ts": "1607138659269504"
}
}
[03:24:19.279] Task URL: https://ci.chromium.org/b/8861786689868677696
[03:24:19.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:2:0) after 1m0s
[03:24:24.973] Received PubSub notification, asking Buildbucket for the build status
[03:24:25.062] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:19.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:2:0)
[03:25:19.365] Timer tick, asking Buildbucket for the build status
[03:25:19.604] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:19.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:4:0) after 1m0s
[03:26:19.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:4:0)
[03:26:19.619] Timer tick, asking Buildbucket for the build status
[03:26:19.760] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:19.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:5:0) after 1m0s
[03:27:19.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:5:0)
[03:27:19.782] Timer tick, asking Buildbucket for the build status
[03:27:19.880] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:19.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:6:0) after 1m0s
[03:28:20.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:6:0)
[03:28:20.009] Timer tick, asking Buildbucket for the build status
[03:28:20.105] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:20.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:7:0) after 1m0s
[03:29:20.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:7:0)
[03:29:20.230] Timer tick, asking Buildbucket for the build status
[03:29:20.394] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:20.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:8:0) after 1m0s
[03:30:20.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:8:0)
[03:30:20.720] Timer tick, asking Buildbucket for the build status
[03:30:20.784] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:20.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:9:0) after 1m0s
[03:31:20.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:9:0)
[03:31:20.960] Timer tick, asking Buildbucket for the build status
[03:31:21.054] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:21.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:10:0) after 1m0s
[03:32:21.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:10:0)
[03:32:21.245] Timer tick, asking Buildbucket for the build status
[03:32:21.309] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:21.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:11:0) after 1m0s
[03:33:21.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:11:0)
[03:33:21.458] Timer tick, asking Buildbucket for the build status
[03:33:21.533] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:21.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:12:0) after 1m0s
[03:34:21.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:12:0)
[03:34:21.635] Timer tick, asking Buildbucket for the build status
[03:34:21.742] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:21.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:13:0) after 1m0s
[03:35:21.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:13:0)
[03:35:21.831] Timer tick, asking Buildbucket for the build status
[03:35:21.912] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:21.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:14:0) after 1m0s
[03:36:22.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:14:0)
[03:36:22.336] Timer tick, asking Buildbucket for the build status
[03:36:22.435] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:22.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:15:0) after 1m0s
[03:37:22.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:15:0)
[03:37:22.487] Timer tick, asking Buildbucket for the build status
[03:37:22.552] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:22.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:16:0) after 1m0s
[03:38:22.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:16:0)
[03:38:22.782] Timer tick, asking Buildbucket for the build status
[03:38:22.839] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:22.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:17:0) after 1m0s
[03:39:22.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:17:0)
[03:39:22.983] Timer tick, asking Buildbucket for the build status
[03:39:23.138] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:23.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:18:0) after 1m0s
[03:40:23.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:18:0)
[03:40:23.182] Timer tick, asking Buildbucket for the build status
[03:40:23.268] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:23.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:19:0) after 1m0s
[03:41:23.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:19:0)
[03:41:23.425] Timer tick, asking Buildbucket for the build status
[03:41:23.543] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:23.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:20:0) after 1m0s
[03:42:23.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:20:0)
[03:42:23.777] Timer tick, asking Buildbucket for the build status
[03:42:23.945] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:23.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:21:0) after 1m0s
[03:43:23.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:21:0)
[03:43:23.964] Timer tick, asking Buildbucket for the build status
[03:43:24.028] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:24.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:22:0) after 1m0s
[03:44:24.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:22:0)
[03:44:24.178] Timer tick, asking Buildbucket for the build status
[03:44:24.314] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:24.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:23:0) after 1m0s
[03:45:24.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:23:0)
[03:45:24.457] Timer tick, asking Buildbucket for the build status
[03:45:24.761] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:24.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:24:0) after 1m0s
[03:46:25.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:24:0)
[03:46:25.015] Timer tick, asking Buildbucket for the build status
[03:46:25.117] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:25.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:25:0) after 1m0s
[03:47:25.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:25:0)
[03:47:25.276] Timer tick, asking Buildbucket for the build status
[03:47:25.417] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:25.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:26:0) after 1m0s
[03:48:25.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:26:0)
[03:48:25.471] Timer tick, asking Buildbucket for the build status
[03:48:25.593] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:25.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:27:0) after 1m0s
[03:49:25.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:27:0)
[03:49:25.764] Timer tick, asking Buildbucket for the build status
[03:49:25.911] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:25.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:28:0) after 1m0s
[03:50:25.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:28:0)
[03:50:25.976] Timer tick, asking Buildbucket for the build status
[03:50:26.344] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:26.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:29:0) after 1m0s
[03:51:26.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:29:0)
[03:51:26.521] Timer tick, asking Buildbucket for the build status
[03:51:26.676] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:26.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:30:0) after 1m0s
[03:52:26.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:30:0)
[03:52:26.736] Timer tick, asking Buildbucket for the build status
[03:52:26.948] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:26.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:31:0) after 1m0s
[03:53:27.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:31:0)
[03:53:27.012] Timer tick, asking Buildbucket for the build status
[03:53:27.189] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:27.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:32:0) after 1m0s
[03:54:27.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:32:0)
[03:54:27.355] Timer tick, asking Buildbucket for the build status
[03:54:27.607] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:27.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:33:0) after 1m0s
[03:55:27.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:33:0)
[03:55:27.634] Timer tick, asking Buildbucket for the build status
[03:55:27.737] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:27.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:34:0) after 1m0s
[03:56:27.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:34:0)
[03:56:27.756] Timer tick, asking Buildbucket for the build status
[03:56:27.924] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:27.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:35:0) after 1m0s
[03:57:27.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:35:0)
[03:57:27.955] Timer tick, asking Buildbucket for the build status
[03:57:28.031] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:28.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:36:0) after 1m0s
[03:58:28.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:36:0)
[03:58:28.086] Timer tick, asking Buildbucket for the build status
[03:58:28.180] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:28.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:37:0) after 1m0s
[03:59:28.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:37:0)
[03:59:28.211] Timer tick, asking Buildbucket for the build status
[03:59:28.405] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:28.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:38:0) after 1m0s
[04:00:28.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:38:0)
[04:00:28.459] Timer tick, asking Buildbucket for the build status
[04:00:28.531] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:28.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:39:0) after 1m0s
[04:01:28.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:39:0)
[04:01:28.584] Timer tick, asking Buildbucket for the build status
[04:01:28.655] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:28.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:40:0) after 1m0s
[04:02:28.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:40:0)
[04:02:28.726] Timer tick, asking Buildbucket for the build status
[04:02:28.899] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:28.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:41:0) after 1m0s
[04:03:28.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:41:0)
[04:03:28.961] Timer tick, asking Buildbucket for the build status
[04:03:29.216] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:29.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:42:0) after 1m0s
[04:04:29.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:42:0)
[04:04:29.233] Timer tick, asking Buildbucket for the build status
[04:04:29.368] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:29.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:43:0) after 1m0s
[04:05:29.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:43:0)
[04:05:29.459] Timer tick, asking Buildbucket for the build status
[04:05:29.771] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:29.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:44:0) after 1m0s
[04:06:29.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:44:0)
[04:06:29.784] Timer tick, asking Buildbucket for the build status
[04:06:30.058] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:30.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:45:0) after 1m0s
[04:07:30.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:45:0)
[04:07:30.135] Timer tick, asking Buildbucket for the build status
[04:07:30.346] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:30.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:46:0) after 1m0s
[04:08:30.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:46:0)
[04:08:30.387] Timer tick, asking Buildbucket for the build status
[04:08:30.583] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:30.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:47:0) after 1m0s
[04:09:30.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:47:0)
[04:09:30.617] Timer tick, asking Buildbucket for the build status
[04:09:30.877] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:30.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:48:0) after 1m0s
[04:10:30.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:48:0)
[04:10:30.961] Timer tick, asking Buildbucket for the build status
[04:10:31.150] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:31.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:49:0) after 1m0s
[04:11:31.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:49:0)
[04:11:31.176] Timer tick, asking Buildbucket for the build status
[04:11:31.336] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:31.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:50:0) after 1m0s
[04:12:31.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:50:0)
[04:12:31.397] Timer tick, asking Buildbucket for the build status
[04:12:31.688] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:31.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:51:0) after 1m0s
[04:13:31.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:51:0)
[04:13:31.731] Timer tick, asking Buildbucket for the build status
[04:13:31.851] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:31.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:52:0) after 1m0s
[04:14:31.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:52:0)
[04:14:31.872] Timer tick, asking Buildbucket for the build status
[04:14:31.996] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:31.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:53:0) after 1m0s
[04:15:32.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:53:0)
[04:15:32.019] Timer tick, asking Buildbucket for the build status
[04:15:32.144] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:32.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:54:0) after 1m0s
[04:16:32.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:54:0)
[04:16:32.191] Timer tick, asking Buildbucket for the build status
[04:16:32.360] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:32.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:55:0) after 1m0s
[04:17:32.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:55:0)
[04:17:32.384] Timer tick, asking Buildbucket for the build status
[04:17:32.508] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:32.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:56:0) after 1m0s
[04:18:32.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:56:0)
[04:18:32.538] Timer tick, asking Buildbucket for the build status
[04:18:32.729] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:32.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:57:0) after 1m0s
[04:19:32.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:57:0)
[04:19:32.760] Timer tick, asking Buildbucket for the build status
[04:19:33.193] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:33.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:58:0) after 1m0s
[04:20:33.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:58:0)
[04:20:33.246] Timer tick, asking Buildbucket for the build status
[04:20:33.316] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:33.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:59:0) after 1m0s
[04:21:33.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:59:0)
[04:21:33.339] Timer tick, asking Buildbucket for the build status
[04:21:33.540] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:33.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:60:0) after 1m0s
[04:22:33.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:60:0)
[04:22:33.651] Timer tick, asking Buildbucket for the build status
[04:22:33.780] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:33.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:61:0) after 1m0s
[04:23:33.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:61:0)
[04:23:33.814] Timer tick, asking Buildbucket for the build status
[04:23:33.985] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:33.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:62:0) after 1m0s
[04:24:34.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:62:0)
[04:24:34.008] Timer tick, asking Buildbucket for the build status
[04:24:34.732] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:34.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:63:0) after 1m0s
[04:25:34.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:63:0)
[04:25:34.788] Timer tick, asking Buildbucket for the build status
[04:25:36.884] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:36.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:64:0) after 1m0s
[04:26:36.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:64:0)
[04:26:36.944] Timer tick, asking Buildbucket for the build status
[04:26:37.019] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:37.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:65:0) after 1m0s
[04:27:37.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:65:0)
[04:27:37.074] Timer tick, asking Buildbucket for the build status
[04:27:37.141] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:37.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:66:0) after 1m0s
[04:28:37.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:66:0)
[04:28:37.147] Timer tick, asking Buildbucket for the build status
[04:28:37.617] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:37.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:67:0) after 1m0s
[04:29:37.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:67:0)
[04:29:37.642] Timer tick, asking Buildbucket for the build status
[04:29:37.809] Build 8861786689868677696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:37.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027216752132861440:68:0) after 1m0s
[04:30:04.367] Received PubSub notification, asking Buildbucket for the build status
[04:30:04.864] Build 8861786689868677696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:30:04.864] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1607142600216202",
"created_by": "project:chromium",
"created_ts": "1607138658609644",
"id": "8861786689868677696",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bbc1b315811609fb633aeb4249e2fab63de895e2\"}}",
"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\": \"bbc1b315811609fb633aeb4249e2fab63de895e2\", \"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-10-eupo\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16035, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"c859c0ac81bbca2066fcaeb6efa48eece5c10103\", \"got_dawn_revision\": \"c532048062befdee8bb57d18f01d3a3c403d49de\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"bbc1b315811609fb633aeb4249e2fab63de895e2\", \"got_revision_cp\": \"refs/heads/master@{#833981}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bae138de13ac876cbca0e30a40b89dc808e2b29d\", \"got_v8_revision\": \"61f3e61408129afc0d9e1268d3354a283ea3c881\", \"got_v8_revision_cp\": \"refs/heads/8.9.100@{#1}\", \"got_webrtc_revision\": \"6c80aebd00360ee00c84c7b14422c5fe475d8916\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32774}\", \"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\": \"bbc1b315811609fb633aeb4249e2fab63de895e2\", \"swarm_hashes_refs/heads/master(at){#833981}_without_patch\": {\"absl_hardening_tests\": \"b7756871b76c5c8f76958c3b65646b96710a3dd1\", \"accessibility_unittests\": \"eccb2c0515b9f6356c19c8bab0d3a9d2e76ddabf\", \"angle_unittests\": \"10de2488b6f8ee0e80a8e42779359e73db923085\", \"app_shell_unittests\": \"1a2ef4e8ffa9edac16a32c44dd91010c03a2792f\", \"aura_unittests\": \"03775f6b46f44d19db6dbb72a4d37de29a8c4ef2\", \"base_unittests\": \"470147db8cd2774dc39c47a92a85a9f7ee7a99a1\", \"base_util_unittests\": \"1699349db27a792e99eb8fc6ebb11a12a3bf5e29\", \"blink_common_unittests\": \"c2c4359cf1a3307aa0d13886709df2d57cc75504\", \"blink_fuzzer_unittests\": \"aa2af030cb460ee9b661c7c7917350144c8a11e2\", \"blink_heap_unittests\": \"89d6a94c2e6858134d063b96b1cbb79d81b97abc\", \"blink_platform_unittests\": \"f8cc69412fe794e14c8eac45ec52edf6e7f63bb5\", \"blink_unittests\": \"045e82f2da280a8853a1e2699164b44598b159d4\", \"boringssl_crypto_tests\": \"2a99d76fb3aa71918e26b158a081fb316dc1fc01\", \"boringssl_ssl_tests\": \"31d5e8b91d72b678d61592480e3cf8833a9a50ff\", \"browser_switcher_bho_unittests\": \"87964c28a7f623db64c5a68c90ef1bcab6e3b070\", \"browser_tests\": \"49c0b4977bd05c32d14d90426308c567176d4334\", \"capture_unittests\": \"b845cfb57aa6dab3b5e0b014543038de7897a08c\", \"cast_unittests\": \"3c8e5fa24c387c93d3dd3b9ac91b2161f23c8952\", \"cc_unittests\": \"267c7b5ed578d8d209c0b0f79350dc7673baa0f9\", \"chrome_app_unittests\": \"e8de97b3f6c36571194b3d5180d464dbab10fad8\", \"chrome_cleaner_unittests\": \"13b665090c83a6534cccdf70c909d44abdec8673\", \"chrome_elf_unittests\": \"a1dbdd0836e779383ba4be5b8f42fab036432710\", \"chromedriver_unittests\": \"ed2939576c576a78a47bbf5801235bc2a466e265\", \"components_browsertests\": \"1806505131751ade5c33c3df74feca03c7ebdb8f\", \"components_unittests\": \"ed59a1d1b0c96f954c572c0aa57c0f6d720a1166\", \"compositor_unittests\": \"fcab5752f0c82894c2add7b2e0151a1f5bb7f761\", \"content_browsertests\": \"c4baef3021d414120f68f8fd383158834093df3b\", \"content_unittests\": \"0fdd699d584eeaf659dff99d6f7a86bc4799f859\", \"courgette_unittests\": \"ecd1a3d3372684f2322baa183813c64a0438d2f1\", \"crashpad_tests\": \"a6d764f38b76722e3e5170d9b26abbac3f0ff477\", \"cronet_tests\": \"7c6c64ac5382e5be692972baf1797ec88d55fe36\", \"cronet_unittests\": \"3ad211935eaf711f9e1cad3bf66a3e9b8a5db341\", \"crypto_unittests\": \"796e65fb3824b4989ad7e61b47c4fe26f4e2bdbd\", \"delayloads_unittests\": \"f2fcc6f659960775a7fba800be81142e47538a59\", \"device_unittests\": \"b46b9261589df3e508d5719fbbb2d5188da42e12\", \"display_unittests\": \"8922003174223181c7b260712fd5cc53b44e41d0\", \"elevation_service_unittests\": \"291d53224a1f0c7e35d533c42a30f5969e16db4e\", \"events_unittests\": \"d1f79b6fd62523a7e168a02ac8e61c4776abeb0f\", \"extensions_browsertests\": \"c99e2dc15725f8795218b4ebd00852d118c9abaa\", \"extensions_unittests\": \"991964f98edb369eefb462d40de975c8e3bddff1\", \"filesystem_service_unittests\": \"01cc936fde237aa4c8f1fb9978708b08be2f76a8\", \"gcm_unit_tests\": \"3d97c657617739b74ec66ceae88bd59bea1ddc5d\", \"gcp_unittests\": \"0f3233006848bfe536e58208440594e6a0d77ed4\", \"gfx_unittests\": \"a7b9420558275c518e88c386f2e07c71e696b920\", \"gin_unittests\": \"fbf3943c36bed4e1976df797b070566cc15a4047\", \"google_apis_unittests\": \"a2f74d959365ca4fe8748187ee057b5ee5025bd0\", \"gpu_unittests\": \"d3c4e269bdcbd3d4bafbd63a69de48880f566ccd\", \"gwp_asan_unittests\": \"93cac1d852cbcaa6e4223d3961c80836796dc6d3\", \"headless_browsertests\": \"ea9868cef91fb045bcdfaa70699aaa2c154a9deb\", \"headless_unittests\": \"0ce07637cb793d2a5809890435d8999493ab6941\", \"install_static_unittests\": \"ffc77995ca513d00b1cb6f5e1ac959b7644e9bc4\", \"installer_util_unittests\": \"7a8423fc7da7ff367b3e4177935611f28142394a\", \"interactive_ui_tests\": \"67a308722cf4464f2f822063a162a231e7f33654\", \"ipc_tests\": \"1db60d4f2ae6103578e51c4f08d4bcd65aee6931\", \"jingle_unittests\": \"2809d4a075e85e5d38d2c64d41b2bd6ecafe4f77\", \"latency_unittests\": \"11dcf94d33fef477f95893779d14cf74a01396af\", \"libjingle_xmpp_unittests\": \"855ecd4d8fbe212ec0aa2618e308d5a26a3bdf95\", \"liburlpattern_unittests\": \"21de48be7e51314cd70b29136416a837f3610650\", \"media_blink_unittests\": \"33984d83b2e0ec6aa3122df3f059aa23ed704617\", \"media_unittests\": \"9812f5157ab1b4d1ede63bc0475822c72a957fd6\", \"message_center_unittests\": \"bc4dd5410d6efe63fcdf91112d1b663185dab8e7\", \"midi_unittests\": \"a1aaf4435a7fbeda8e4e9d1586567124a7ddd45b\", \"mojo_core_unittests\": \"ae18bc36b3563c5abe80832c2005c84c63263c4f\", \"mojo_unittests\": \"32a9cf2a763cb342c74d4a14b40994ff2ff7fa2d\", \"nacl_loader_unittests\": \"d5cd6f9cf46a8b352890f2b5698fef682fc1f410\", \"native_theme_unittests\": \"90e60c4b72b979cfdf22c3064e5022f3753e65b8\", \"net_unittests\": \"7b6897edd6ef6e2ad7df8852194c8a3ed01080d7\", \"notification_helper_unittests\": \"d3967d2abf6dea8059fb7a5a8c4423dca4e52f21\", \"pdf_unittests\": \"f39dc744e5e1be7c07547eb9f5efebe140776cf3\", \"perfetto_unittests\": \"e56fdd95c28e1e1de8b96ec3f09d5e95dee8822c\", \"ppapi_unittests\": \"843824d0dffdf98136bc4a8921cb4b4883a815bb\", \"printing_unittests\": \"2bb2b80ef9fab56cb5b05f2e19f1d75f2464c919\", \"remoting_unittests\": \"440c76da12be3232cd384cbd8326f9d1c9b27554\", \"sbox_integration_tests\": \"d2fa8db8b4740eec10235bc7985ec56d3179986c\", \"sbox_unittests\": \"69a82b0364eb1dcb6957ef4e40f6d71b823ae847\", \"sbox_validation_tests\": \"9231cd0b5cc442d07029ac72db9f5a466ba4febf\", \"service_manager_unittests\": \"d1adadf7f7af3ef5f7b0435b636ac9186fd233e8\", \"services_unittests\": \"8d409bc87c574869a60f2b283097f622071de04d\", \"setup_unittests\": \"0b7c03fe7c73e70f5689decf68e4ef88126feea7\", \"shell_dialogs_unittests\": \"c4e26c010b0026d122d981f915fa4f9906669cef\", \"skia_unittests\": \"604cf5ae7d7a3bb76038953953ca8322f5c2d18e\", \"snapshot_unittests\": \"5044b0e553d0b11a516929e276e471d212f8a6b3\", \"sql_unittests\": \"96e9c602bb664c98bec84967bb23e9c28806b91b\", \"storage_unittests\": \"cc0fdebd1e1296ca2718a375e06bf1258c20fcda\", \"sync_integration_tests\": \"254a1e08a9d4c93beab7a59d7a90f7c20a1f0d67\", \"traffic_annotation_auditor_unittests\": \"2347955b217c34521a60e535ac60b6a946337a4c\", \"ui_base_unittests\": \"01ab0a91b941bc993621b8e8a93d1f821ef14a6c\", \"ui_touch_selection_unittests\": \"13a87d89dedf50760604a808768fcc63ade46c59\", \"unit_tests\": \"38b4480374fddb281616119167a9b65730eae3c1\", \"updater_tests\": \"01c53675219cb02fdc2cf9845aa5c4e23bdb6de9\", \"url_unittests\": \"923e9b67d2af85706269add562adc0aaaa00aa1e\", \"views_unittests\": \"39134d5e11aee3da2882b33bdb60feff2357ece8\", \"viz_unittests\": \"52d6841071c04c78396fe86f39c819df3cc04fc0\", \"vr_common_unittests\": \"1a2c74acac2b57632770327e6163c950c0eeba68\", \"vr_pixeltests\": \"e92cfc5962a8bd1e6f93b26bc30cc227e18f2761\", \"weblayer_browsertests\": \"944dc9fd93b69cb78bd70b6ba53ebc3925b9d609\", \"weblayer_unittests\": \"9cdb570499c2f1f0f25355f3156bc498f04e752f\", \"wm_unittests\": \"ac31a546e9f2cf89568ce9084991433068a15807\", \"wtf_unittests\": \"c0faeb8e5fcf17a96053b5731790983b8f9c4ee5\", \"zlib_unittests\": \"69307228fd0317f2dd0ffa2574e71f6e04b426a1\", \"zucchini_unittests\": \"2f7383a3755fc337882ae2fa8d2444c53c8a171b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91c730c3e6ead475130e2ae78bc3f03e09cb9dcb91c3469d4450b29020110401_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-10-eupo\"], \"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": "1607138662835724",
"status": "COMPLETED",
"status_changed_ts": "1607142600578026",
"tags": [
"build_address:luci.chromium.ci/win-asan/16035",
"builder:win-asan",
"buildset:commit/git/bbc1b315811609fb633aeb4249e2fab63de895e2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbc1b315811609fb633aeb4249e2fab63de895e2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027216752132861440",
"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/8861786689868677696/+/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:5049bf54d450c210",
"user_agent:luci-scheduler"
],
"updated_ts": "1607142600651454",
"url": "https://ci.chromium.org/b/8861786689868677696",
"utcnow_ts": "1607142604841381"
}
}
[04:30:04.864] Invocation finished in 1h5m47.798272117s with status SUCCEEDED