[03:45:23.228] New invocation is queued and will start shortly
[03:45:24.340] Starting the invocation (attempt 1)
[03:45:24.379] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:45:24.379] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:45:24.379] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021779606505823232",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"92b9493922798309e7a5e6c3b4b00f9a1b064070\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021779606505823232",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/92b9493922798309e7a5e6c3b4b00f9a1b064070",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/92b9493922798309e7a5e6c3b4b00f9a1b064070",
"gitiles_ref:refs/heads/master"
]
}
[03:45:25.443] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1612323924459805",
"id": "8856349544544950768",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"92b9493922798309e7a5e6c3b4b00f9a1b064070\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612323925195636",
"tags": [
"build_address:luci.chromium.ci/win-asan/17141",
"builder:win-asan",
"buildset:commit/git/92b9493922798309e7a5e6c3b4b00f9a1b064070",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/92b9493922798309e7a5e6c3b4b00f9a1b064070",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021779606505823232",
"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/8856349544544950768/+/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": "1612323925195876",
"url": "https://ci.chromium.org/b/8856349544544950768",
"utcnow_ts": "1612323925433106"
}
}
[03:45:25.443] Task URL: https://ci.chromium.org/b/8856349544544950768
[03:45:25.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:2:0) after 1m0s
[03:45:27.972] Received PubSub notification, asking Buildbucket for the build status
[03:45:28.123] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:25.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:2:0)
[03:46:25.499] Timer tick, asking Buildbucket for the build status
[03:46:25.749] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:25.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:4:0) after 1m0s
[03:47:25.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:4:0)
[03:47:25.771] Timer tick, asking Buildbucket for the build status
[03:47:26.547] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:26.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:5:0) after 1m0s
[03:48:26.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:5:0)
[03:48:26.590] Timer tick, asking Buildbucket for the build status
[03:48:26.685] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:26.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:6:0) after 1m0s
[03:49:26.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:6:0)
[03:49:26.725] Timer tick, asking Buildbucket for the build status
[03:49:26.860] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:26.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:7:0) after 1m0s
[03:50:26.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:7:0)
[03:50:26.931] Timer tick, asking Buildbucket for the build status
[03:50:27.356] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:27.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:8:0) after 1m0s
[03:51:27.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:8:0)
[03:51:27.480] Timer tick, asking Buildbucket for the build status
[03:51:27.556] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:27.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:9:0) after 1m0s
[03:52:27.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:9:0)
[03:52:27.589] Timer tick, asking Buildbucket for the build status
[03:52:27.731] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:27.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:10:0) after 1m0s
[03:53:27.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:10:0)
[03:53:27.798] Timer tick, asking Buildbucket for the build status
[03:53:27.929] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:27.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:11:0) after 1m0s
[03:54:27.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:11:0)
[03:54:27.958] Timer tick, asking Buildbucket for the build status
[03:54:28.043] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:28.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:12:0) after 1m0s
[03:55:28.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:12:0)
[03:55:28.198] Timer tick, asking Buildbucket for the build status
[03:55:28.496] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:28.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:13:0) after 1m0s
[03:56:28.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:13:0)
[03:56:28.636] Timer tick, asking Buildbucket for the build status
[03:56:29.044] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:29.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:14:0) after 1m0s
[03:57:29.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:14:0)
[03:57:29.113] Timer tick, asking Buildbucket for the build status
[03:57:29.928] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:29.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:15:0) after 1m0s
[03:58:29.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:15:0)
[03:58:29.988] Timer tick, asking Buildbucket for the build status
[03:58:30.109] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:30.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:16:0) after 1m0s
[03:59:30.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:16:0)
[03:59:30.117] Timer tick, asking Buildbucket for the build status
[03:59:30.214] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:30.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:17:0) after 1m0s
[04:00:30.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:17:0)
[04:00:30.329] Timer tick, asking Buildbucket for the build status
[04:00:30.488] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:30.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:18:0) after 1m0s
[04:01:30.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:18:0)
[04:01:30.563] Timer tick, asking Buildbucket for the build status
[04:01:30.655] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:30.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:19:0) after 1m0s
[04:02:30.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:19:0)
[04:02:30.739] Timer tick, asking Buildbucket for the build status
[04:02:30.801] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:30.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:20:0) after 1m0s
[04:03:30.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:20:0)
[04:03:30.921] Timer tick, asking Buildbucket for the build status
[04:03:31.504] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:31.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:21:0) after 1m0s
[04:04:31.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:21:0)
[04:04:31.580] Timer tick, asking Buildbucket for the build status
[04:04:31.942] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:31.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:22:0) after 1m0s
[04:05:31.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:22:0)
[04:05:31.977] Timer tick, asking Buildbucket for the build status
[04:05:32.081] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:32.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:23:0) after 1m0s
[04:06:32.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:23:0)
[04:06:32.188] Timer tick, asking Buildbucket for the build status
[04:06:32.292] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:32.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:24:0) after 1m0s
[04:07:32.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:24:0)
[04:07:32.364] Timer tick, asking Buildbucket for the build status
[04:07:32.527] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:32.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:25:0) after 1m0s
[04:08:32.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:25:0)
[04:08:32.574] Timer tick, asking Buildbucket for the build status
[04:08:32.704] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:32.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:26:0) after 1m0s
[04:09:32.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:26:0)
[04:09:32.787] Timer tick, asking Buildbucket for the build status
[04:09:33.103] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:33.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:27:0) after 1m0s
[04:10:33.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:27:0)
[04:10:33.153] Timer tick, asking Buildbucket for the build status
[04:10:33.274] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:33.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:28:0) after 1m0s
[04:11:33.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:28:0)
[04:11:33.313] Timer tick, asking Buildbucket for the build status
[04:11:33.608] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:33.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:29:0) after 1m0s
[04:12:33.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:29:0)
[04:12:33.634] Timer tick, asking Buildbucket for the build status
[04:12:33.722] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:33.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:30:0) after 1m0s
[04:13:33.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:30:0)
[04:13:33.751] Timer tick, asking Buildbucket for the build status
[04:13:33.887] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:33.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:31:0) after 1m0s
[04:14:33.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:31:0)
[04:14:33.933] Timer tick, asking Buildbucket for the build status
[04:14:34.077] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:34.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:32:0) after 1m0s
[04:15:34.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:32:0)
[04:15:34.128] Timer tick, asking Buildbucket for the build status
[04:15:34.233] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:34.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:33:0) after 1m0s
[04:16:34.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:33:0)
[04:16:34.257] Timer tick, asking Buildbucket for the build status
[04:16:34.368] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:34.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:34:0) after 1m0s
[04:17:34.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:34:0)
[04:17:34.435] Timer tick, asking Buildbucket for the build status
[04:17:34.495] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:34.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:35:0) after 1m0s
[04:18:34.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:35:0)
[04:18:34.573] Timer tick, asking Buildbucket for the build status
[04:18:34.746] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:34.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:36:0) after 1m0s
[04:19:34.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:36:0)
[04:19:34.874] Timer tick, asking Buildbucket for the build status
[04:19:34.964] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:34.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:37:0) after 1m0s
[04:20:35.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:37:0)
[04:20:35.146] Timer tick, asking Buildbucket for the build status
[04:20:35.287] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:35.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:38:0) after 1m0s
[04:21:35.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:38:0)
[04:21:35.516] Timer tick, asking Buildbucket for the build status
[04:21:35.609] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:35.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:39:0) after 1m0s
[04:22:35.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:39:0)
[04:22:35.706] Timer tick, asking Buildbucket for the build status
[04:22:35.821] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:35.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:40:0) after 1m0s
[04:23:35.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:40:0)
[04:23:35.856] Timer tick, asking Buildbucket for the build status
[04:23:35.968] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:35.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:41:0) after 1m0s
[04:24:35.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:41:0)
[04:24:35.998] Timer tick, asking Buildbucket for the build status
[04:24:36.504] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:36.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:42:0) after 1m0s
[04:25:36.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:42:0)
[04:25:36.528] Timer tick, asking Buildbucket for the build status
[04:25:36.640] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:36.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:43:0) after 1m0s
[04:26:36.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:43:0)
[04:26:36.648] Timer tick, asking Buildbucket for the build status
[04:26:37.909] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:37.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:44:0) after 1m0s
[04:27:37.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:44:0)
[04:27:37.918] Timer tick, asking Buildbucket for the build status
[04:27:38.108] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:38.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:45:0) after 1m0s
[04:28:38.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:45:0)
[04:28:38.207] Timer tick, asking Buildbucket for the build status
[04:28:38.289] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:38.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:46:0) after 1m0s
[04:29:38.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:46:0)
[04:29:38.372] Timer tick, asking Buildbucket for the build status
[04:29:38.487] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:38.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:47:0) after 1m0s
[04:30:38.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:47:0)
[04:30:38.583] Timer tick, asking Buildbucket for the build status
[04:30:38.705] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:38.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:48:0) after 1m0s
[04:31:38.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:48:0)
[04:31:38.756] Timer tick, asking Buildbucket for the build status
[04:31:39.811] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:39.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:49:0) after 1m0s
[04:32:39.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:49:0)
[04:32:39.856] Timer tick, asking Buildbucket for the build status
[04:32:39.984] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:39.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:50:0) after 1m0s
[04:33:40.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:50:0)
[04:33:40.050] Timer tick, asking Buildbucket for the build status
[04:33:40.161] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:40.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:51:0) after 1m0s
[04:34:40.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:51:0)
[04:34:40.159] Timer tick, asking Buildbucket for the build status
[04:34:40.294] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:40.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:52:0) after 1m0s
[04:35:40.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:52:0)
[04:35:40.392] Timer tick, asking Buildbucket for the build status
[04:35:40.576] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:40.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:53:0) after 1m0s
[04:36:40.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:53:0)
[04:36:40.681] Timer tick, asking Buildbucket for the build status
[04:36:41.440] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:41.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:54:0) after 1m0s
[04:37:41.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:54:0)
[04:37:41.507] Timer tick, asking Buildbucket for the build status
[04:37:41.839] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:41.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:55:0) after 1m0s
[04:38:41.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:55:0)
[04:38:41.913] Timer tick, asking Buildbucket for the build status
[04:38:41.995] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:41.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:56:0) after 1m0s
[04:39:42.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:56:0)
[04:39:42.150] Timer tick, asking Buildbucket for the build status
[04:39:42.213] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:42.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:57:0) after 1m0s
[04:40:42.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:57:0)
[04:40:42.291] Timer tick, asking Buildbucket for the build status
[04:40:42.395] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:42.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:58:0) after 1m0s
[04:41:42.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:58:0)
[04:41:42.446] Timer tick, asking Buildbucket for the build status
[04:41:42.558] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:42.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:59:0) after 1m0s
[04:42:42.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:59:0)
[04:42:42.654] Timer tick, asking Buildbucket for the build status
[04:42:42.780] Build 8856349544544950768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:42.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021779606505823232:60:0) after 1m0s
[04:42:59.031] Received PubSub notification, asking Buildbucket for the build status
[04:42:59.369] Build 8856349544544950768: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:42:59.369] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1612327378171147",
"created_by": "project:chromium",
"created_ts": "1612323924459805",
"id": "8856349544544950768",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"92b9493922798309e7a5e6c3b4b00f9a1b064070\"}}",
"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\": \"92b9493922798309e7a5e6c3b4b00f9a1b064070\", \"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-2-30ob\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17141, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"6cc845bb0a603587ac36b900558d483e05f02f8c\", \"got_dawn_revision\": \"fd5fd05db039710c797ee8bac5e6d999173e5fc3\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"92b9493922798309e7a5e6c3b4b00f9a1b064070\", \"got_revision_cp\": \"refs/heads/master@{#849941}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"76dbeeb707f339e33e033b6ef4929f50e00d6c65\", \"got_v8_revision_cp\": \"refs/heads/9.0.121@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"92b9493922798309e7a5e6c3b4b00f9a1b064070\", \"swarm_hashes_refs/heads/master(at){#849941}_without_patch\": {\"absl_hardening_tests\": \"ef0f0cae7e28001006a2443cec323a7d24a8a4e8\", \"accessibility_unittests\": \"d3ba41295fb102c7f2f590ad22481ed02fb5e6f7\", \"angle_unittests\": \"4c7bad0c9a39526c8ccf232a770032962fb6d98f\", \"app_shell_unittests\": \"2c8a5c76b644562216201b442d9dd739d34d1f8c\", \"aura_unittests\": \"8eb7a98d295355ff9bfac32e90efe7c9856fd3f1\", \"base_unittests\": \"866f87372b4caaddda4925715667b7c6d68c3ab8\", \"base_util_unittests\": \"d9c740eaf7e7c6048b3a0bf5a289f266fb26845d\", \"blink_common_unittests\": \"4a29898fec7acdde8c68d540ce121d9513e5579c\", \"blink_fuzzer_unittests\": \"5230cef7daccd34d0dce892b524c91187c483e4e\", \"blink_heap_unittests\": \"6fb18c3d932820a42fe4f24030dddbe1b1ba3390\", \"blink_platform_unittests\": \"9dac7d62c4164b93bd38b6ed105303cbc5e49ec0\", \"blink_unittests\": \"a863795a8f4c7dde2a8a4e4119fed5156aaf4cb7\", \"boringssl_crypto_tests\": \"cb4ab8d3aa503d1cf9849357cd9ba463daddb12c\", \"boringssl_ssl_tests\": \"bf53ed4d3dd905a6eec6811075e10c11b2c4bd0a\", \"browser_switcher_bho_unittests\": \"895382379f79d1b8bb4a82c67d1b006ed4cb183b\", \"browser_tests\": \"f7dc2ceeece1f14309945d5f1ee4cb6ad2ebb3c3\", \"capture_unittests\": \"05160d9f52993ac98df5156fecf84b217d97a292\", \"cast_unittests\": \"bf69a1883c198698b112b317b459c02326540790\", \"cc_unittests\": \"e7b5dce095bebfdc86b054c5bd0980cab6c3c130\", \"chrome_app_unittests\": \"c8c0a01ef7127acd128c8db2ab6c8fa2708b7103\", \"chrome_cleaner_unittests\": \"3619da2156d6e9492409b216c874ee1c582f3197\", \"chrome_elf_unittests\": \"8d11b5c7922b9df9ece84fa8a3e633663c70d4a1\", \"chromedriver_unittests\": \"ba75ed58e26506a29f503b0ffd3a09907889bd0d\", \"components_browsertests\": \"9cecb606b89dcb9425227ad2ff9da2b06ee28eca\", \"components_unittests\": \"ce5d43a30934d09155d2045376949fc6b1316552\", \"compositor_unittests\": \"5055143d12b448ed35a26cb4a067335eb9b2a556\", \"content_browsertests\": \"041160b42fe1d89cb1d3d6e08b701a09e453006c\", \"content_unittests\": \"52bd149860e4a014a54858b978efdb912267474d\", \"courgette_unittests\": \"06c99485e43463543152fc4491f30df936287b94\", \"crashpad_tests\": \"4c4a59fa5c52a6675a238ae6209f535b123acfc8\", \"cronet_tests\": \"ff4738376e8a58276842f1ad0197eed5fab53b0e\", \"cronet_unittests\": \"6dc0fe19363b17ba62ab6877f700da6b84c819aa\", \"crypto_unittests\": \"e5fc234e041d0cb32f779233b2c3e67e2f083c12\", \"delayloads_unittests\": \"84ac0fb003317e1f6b3b87efc783744d67d87b63\", \"device_unittests\": \"7bcf980b041bde48f81fb60e30a21a3af6414750\", \"display_unittests\": \"4e1a73035f835c7f165c4cb282e0c22942695326\", \"elevation_service_unittests\": \"8d829245ccbf56f35465c7108b46bb189604e8c8\", \"events_unittests\": \"3d2b2325b1482f03660860fd7c554f35942264d7\", \"extensions_browsertests\": \"b303b896379f58d27a8e2d22d5835c8ea4eb7c18\", \"extensions_unittests\": \"2cf7289c153480351fbb8f26e5dbfaa00472130c\", \"filesystem_service_unittests\": \"e02f2c5a22e9bab0ff14c98ec1edc5e324302c95\", \"gcm_unit_tests\": \"3e616ee988d965061fd215e6405c842632953029\", \"gcp_unittests\": \"4cc598eaa759030e55fb68b954f307b6962b4004\", \"gfx_unittests\": \"cef4745a9d26ddca391dff5ff5c2210c9c6bb1db\", \"gin_unittests\": \"762b3db9574364e6991c4b1d9d4f51e124e2dcc2\", \"google_apis_unittests\": \"614377f13d6d91ed7535ddf731724b00cc9b8927\", \"gpu_unittests\": \"94d3b2186b6b56bd74afffd4ed29e8d04a4f2647\", \"gwp_asan_unittests\": \"2b6651f2b5ea6163ef11e13d88fe428b0cbc29ee\", \"headless_browsertests\": \"425cbb7365fef05ff740a2bec8e5b6f9f0d238d1\", \"headless_unittests\": \"c55ee35a8e7a1429d1756dfad89ee62721ad54a4\", \"install_static_unittests\": \"d103d02843b5b9a9ba5cdc2f2cc50464151f5682\", \"installer_util_unittests\": \"3db303f4bdb429131baf1f709c3aa2b2d523e5fb\", \"interactive_ui_tests\": \"1d18864511184d781872dd5f62ca539a8725a901\", \"ipc_tests\": \"1b34ba46ec6a39ada9af9c5dc2e76c84ea555969\", \"jingle_unittests\": \"fcc1a1acd30fab050e09a792a749724d53125562\", \"latency_unittests\": \"9c674f590d181f29ac38d7c17957b54f2a3bceaf\", \"libjingle_xmpp_unittests\": \"90b8be5ee803631065db1ca1c9d16289ecf1a945\", \"liburlpattern_unittests\": \"4096642b1adba5dac7408bbf5a351d5b34fb255c\", \"media_blink_unittests\": \"bf2a3b192e130014e1efd11976594fc81daa2a78\", \"media_unittests\": \"646bd01257b5e147447c5f45f029051173ecd0ed\", \"message_center_unittests\": \"9cb2ecd2dcf053ba39b820d60532f6300968960a\", \"midi_unittests\": \"e6c624bd4ce3245823b46ae7453006d62f6019a4\", \"mojo_core_unittests\": \"d6f5fddefab74ef862c799a5fe287f873c0371da\", \"mojo_unittests\": \"18cd245ea0efb6dfcd4dda2fe6aa61018f166c73\", \"nacl_loader_unittests\": \"cb7fc977f97d273014aede7920114a9f5a37f2ad\", \"native_theme_unittests\": \"b024c2b749a6b8ef3a959d7a4a9d0f63cef2026d\", \"net_unittests\": \"1e68d1e3a0979eb6360d97b9f0d7103b8ed0dcb2\", \"notification_helper_unittests\": \"eb2c0313b5fc2473d6e852c1e89d7faa01e841e1\", \"pdf_unittests\": \"633470a6d1df3afbbb00903d3f33d42479c634cb\", \"perfetto_unittests\": \"964e9ec93e61bc86c4b2b62fff0b0712e188bec7\", \"ppapi_unittests\": \"45d69e096f9f23503d47c66b47a7255e541c840e\", \"printing_unittests\": \"732b9abc9ee8b7c825484d0a56a6753e092c2bef\", \"remoting_unittests\": \"1e7209b58b0dc9ef07a2013a03d85ed54967568a\", \"sbox_integration_tests\": \"f220ec67faab75e392c4fc6110f57b84a773ccb9\", \"sbox_unittests\": \"cef2d797f9e90ad27e522621bf68a22c2fb7db3b\", \"sbox_validation_tests\": \"f4587c8b5479278cfa3d0944391b5080c5ad3ea3\", \"service_manager_unittests\": \"07767a567045add3d363a18ec3a4da80aa4c3edf\", \"services_unittests\": \"528009e24595ce11cb555a4f11c6728a206179ae\", \"setup_unittests\": \"9ca3a338998f3d6f15576b65fbfd1b80adaf0afc\", \"shell_dialogs_unittests\": \"135d8ce5fa36d0426e36c1cdd38fd1bc439a8bff\", \"skia_unittests\": \"753c97f07f7e60db05982c533398c8d81fc0eddf\", \"snapshot_unittests\": \"26ec5e03bd1886978d3a61f59fca61533f811229\", \"sql_unittests\": \"9e1e10669dda097c5f6f126ad2a98998bd3b627c\", \"storage_unittests\": \"0dce00bd3dbb4116abdb0ba095bd7c72658bbcc7\", \"sync_integration_tests\": \"21c8cc3ed7ede36eae7652cfa0b46fd6421f94f4\", \"traffic_annotation_auditor_unittests\": \"be1b5a3f8dd1d99db2cf5faf3f190437afd9a3ad\", \"ui_base_unittests\": \"eb149d6295351c109dfc2d013e9f495276274e44\", \"ui_touch_selection_unittests\": \"be37dc4b9c31704103d9e3edb1080f9e290aee01\", \"unit_tests\": \"55affacee9039e2452095d1404cc9e00fa33cbc5\", \"updater_tests\": \"1b7e3e6ba1317f312c8bb77e49802f61d34f426a\", \"url_unittests\": \"40f3a9273e2dca6cb829461a1d1cf61f4c7cf775\", \"views_unittests\": \"40da7259f163f54c7cf004b61639375affc7e114\", \"viz_unittests\": \"06379bc603fed0896517153f984e96a0aa05be94\", \"vr_common_unittests\": \"d4f14bdf328028b0ed4461dfc682200220b2cfeb\", \"vr_pixeltests\": \"539876591201b413c94128afb451a570535d6ba5\", \"weblayer_browsertests\": \"bd15ba0d486334531c25a7a83c023279929a9335\", \"weblayer_unittests\": \"b1ffa9831df7b653127b415a2c553a0f6d35790b\", \"wm_unittests\": \"87972e54ae06b1a6fd20604e036c0156f8d3370f\", \"wtf_unittests\": \"34b4074d7893d3b34c01cfbdfe6986773771b0ff\", \"zlib_unittests\": \"9029971070ef5d8a2b031544a1208b8a3c525f65\", \"zucchini_unittests\": \"603e16d24f2aa87cb99e7c2e5c7f08a04de8d130\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_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-2-30ob\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5676-c76598c\"], \"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": "1612323927209779",
"status": "COMPLETED",
"status_changed_ts": "1612327378647069",
"tags": [
"build_address:luci.chromium.ci/win-asan/17141",
"builder:win-asan",
"buildset:commit/git/92b9493922798309e7a5e6c3b4b00f9a1b064070",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/92b9493922798309e7a5e6c3b4b00f9a1b064070",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021779606505823232",
"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/8856349544544950768/+/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:517ed0363dfe3810",
"user_agent:luci-scheduler"
],
"updated_ts": "1612327378652496",
"url": "https://ci.chromium.org/b/8856349544544950768",
"utcnow_ts": "1612327379342843"
}
}
[04:42:59.369] Invocation finished in 57m36.163420146s with status SUCCEEDED