[13:58:00.974] New invocation is queued and will start shortly
[13:58:02.210] Starting the invocation (attempt 1)
[13:58:02.270] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:58:02.270] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:58:02.270] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023553002537260016",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0ec135c30c3a4cb19f35ff7da6c116bed2b3403d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023553002537260016",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/0ec135c30c3a4cb19f35ff7da6c116bed2b3403d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ec135c30c3a4cb19f35ff7da6c116bed2b3403d",
"gitiles_ref:refs/heads/master"
]
}
[13:58:04.089] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610632682496535",
"id": "8858122940277334800",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0ec135c30c3a4cb19f35ff7da6c116bed2b3403d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610632683792567",
"tags": [
"build_address:luci.chromium.ci/win-asan/16817",
"builder:win-asan",
"buildset:commit/git/0ec135c30c3a4cb19f35ff7da6c116bed2b3403d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ec135c30c3a4cb19f35ff7da6c116bed2b3403d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023553002537260016",
"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/8858122940277334800/+/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": "1610632683792981",
"url": "https://ci.chromium.org/b/8858122940277334800",
"utcnow_ts": "1610632684073906"
}
}
[13:58:04.089] Task URL: https://ci.chromium.org/b/8858122940277334800
[13:58:04.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:2:0) after 1m0s
[13:58:13.167] Received PubSub notification, asking Buildbucket for the build status
[13:58:13.249] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:04.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:2:0)
[13:59:04.131] Timer tick, asking Buildbucket for the build status
[13:59:04.275] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:04.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:4:0) after 1m0s
[14:00:04.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:4:0)
[14:00:04.341] Timer tick, asking Buildbucket for the build status
[14:00:04.665] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:04.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:5:0) after 1m0s
[14:01:04.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:5:0)
[14:01:04.720] Timer tick, asking Buildbucket for the build status
[14:01:05.006] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:05.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:6:0) after 1m0s
[14:02:05.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:6:0)
[14:02:05.142] Timer tick, asking Buildbucket for the build status
[14:02:05.508] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:05.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:7:0) after 1m0s
[14:03:05.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:7:0)
[14:03:05.541] Timer tick, asking Buildbucket for the build status
[14:03:05.692] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:05.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:8:0) after 1m0s
[14:04:05.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:8:0)
[14:04:05.900] Timer tick, asking Buildbucket for the build status
[14:04:06.079] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:06.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:9:0) after 1m0s
[14:05:06.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:9:0)
[14:05:06.142] Timer tick, asking Buildbucket for the build status
[14:05:07.679] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:07.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:10:0) after 1m0s
[14:06:07.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:10:0)
[14:06:07.702] Timer tick, asking Buildbucket for the build status
[14:06:07.765] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:07.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:11:0) after 1m0s
[14:07:07.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:11:0)
[14:07:07.862] Timer tick, asking Buildbucket for the build status
[14:07:07.935] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:07.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:12:0) after 1m0s
[14:08:07.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:12:0)
[14:08:07.969] Timer tick, asking Buildbucket for the build status
[14:08:08.167] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:08.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:13:0) after 1m0s
[14:09:08.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:13:0)
[14:09:08.281] Timer tick, asking Buildbucket for the build status
[14:09:08.629] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:08.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:14:0) after 1m0s
[14:10:08.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:14:0)
[14:10:08.651] Timer tick, asking Buildbucket for the build status
[14:10:08.723] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:08.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:15:0) after 1m0s
[14:11:08.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:15:0)
[14:11:08.774] Timer tick, asking Buildbucket for the build status
[14:11:08.849] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:08.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:16:0) after 1m0s
[14:12:08.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:16:0)
[14:12:08.873] Timer tick, asking Buildbucket for the build status
[14:12:08.942] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:08.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:17:0) after 1m0s
[14:13:09.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:17:0)
[14:13:09.013] Timer tick, asking Buildbucket for the build status
[14:13:09.074] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:09.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:18:0) after 1m0s
[14:14:09.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:18:0)
[14:14:09.176] Timer tick, asking Buildbucket for the build status
[14:14:09.592] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:09.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:19:0) after 1m0s
[14:15:09.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:19:0)
[14:15:09.811] Timer tick, asking Buildbucket for the build status
[14:15:09.896] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:09.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:20:0) after 1m0s
[14:16:10.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:20:0)
[14:16:10.085] Timer tick, asking Buildbucket for the build status
[14:16:10.241] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:10.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:21:0) after 1m0s
[14:17:10.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:21:0)
[14:17:10.279] Timer tick, asking Buildbucket for the build status
[14:17:10.455] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:10.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:22:0) after 1m0s
[14:18:10.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:22:0)
[14:18:10.516] Timer tick, asking Buildbucket for the build status
[14:18:10.610] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:10.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:23:0) after 1m0s
[14:19:10.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:23:0)
[14:19:10.664] Timer tick, asking Buildbucket for the build status
[14:19:11.132] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:11.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:24:0) after 1m0s
[14:20:11.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:24:0)
[14:20:11.286] Timer tick, asking Buildbucket for the build status
[14:20:11.925] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:11.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:25:0) after 1m0s
[14:21:11.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:25:0)
[14:21:11.979] Timer tick, asking Buildbucket for the build status
[14:21:12.126] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:12.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:26:0) after 1m0s
[14:22:12.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:26:0)
[14:22:12.271] Timer tick, asking Buildbucket for the build status
[14:22:12.610] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:12.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:27:0) after 1m0s
[14:23:12.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:27:0)
[14:23:12.763] Timer tick, asking Buildbucket for the build status
[14:23:13.149] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:13.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:28:0) after 1m0s
[14:24:13.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:28:0)
[14:24:13.269] Timer tick, asking Buildbucket for the build status
[14:24:13.904] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:13.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:29:0) after 1m0s
[14:25:13.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:29:0)
[14:25:13.935] Timer tick, asking Buildbucket for the build status
[14:25:14.290] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:14.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:30:0) after 1m0s
[14:26:14.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:30:0)
[14:26:14.400] Timer tick, asking Buildbucket for the build status
[14:26:14.524] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:14.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:31:0) after 1m0s
[14:27:14.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:31:0)
[14:27:14.647] Timer tick, asking Buildbucket for the build status
[14:27:15.062] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:15.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:32:0) after 1m0s
[14:28:15.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:32:0)
[14:28:15.178] Timer tick, asking Buildbucket for the build status
[14:28:15.285] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:15.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:33:0) after 1m0s
[14:29:15.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:33:0)
[14:29:15.374] Timer tick, asking Buildbucket for the build status
[14:29:15.615] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:15.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:34:0) after 1m0s
[14:30:15.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:34:0)
[14:30:15.728] Timer tick, asking Buildbucket for the build status
[14:30:16.062] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:16.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:35:0) after 1m0s
[14:31:16.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:35:0)
[14:31:16.124] Timer tick, asking Buildbucket for the build status
[14:31:16.249] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:16.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:36:0) after 1m0s
[14:32:16.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:36:0)
[14:32:16.349] Timer tick, asking Buildbucket for the build status
[14:32:16.702] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:16.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:37:0) after 1m0s
[14:33:16.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:37:0)
[14:33:16.747] Timer tick, asking Buildbucket for the build status
[14:33:17.348] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:17.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:38:0) after 1m0s
[14:34:17.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:38:0)
[14:34:17.494] Timer tick, asking Buildbucket for the build status
[14:34:17.839] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:17.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:39:0) after 1m0s
[14:35:17.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:39:0)
[14:35:17.839] Timer tick, asking Buildbucket for the build status
[14:35:18.586] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:18.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:40:0) after 1m0s
[14:36:18.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:40:0)
[14:36:18.697] Timer tick, asking Buildbucket for the build status
[14:36:18.815] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:18.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:41:0) after 1m0s
[14:37:19.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:41:0)
[14:37:19.404] Timer tick, asking Buildbucket for the build status
[14:37:19.500] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:19.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:42:0) after 1m0s
[14:38:19.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:42:0)
[14:38:19.593] Timer tick, asking Buildbucket for the build status
[14:38:19.708] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:19.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:43:0) after 1m0s
[14:39:19.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:43:0)
[14:39:19.968] Timer tick, asking Buildbucket for the build status
[14:39:20.135] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:20.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:44:0) after 1m0s
[14:40:20.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:44:0)
[14:40:20.286] Timer tick, asking Buildbucket for the build status
[14:40:20.394] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:20.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:45:0) after 1m0s
[14:41:20.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:45:0)
[14:41:20.628] Timer tick, asking Buildbucket for the build status
[14:41:20.758] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:20.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:46:0) after 1m0s
[14:42:20.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:46:0)
[14:42:20.778] Timer tick, asking Buildbucket for the build status
[14:42:21.066] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:21.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:47:0) after 1m0s
[14:43:21.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:47:0)
[14:43:21.145] Timer tick, asking Buildbucket for the build status
[14:43:21.574] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:21.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:48:0) after 1m0s
[14:44:21.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:48:0)
[14:44:21.868] Timer tick, asking Buildbucket for the build status
[14:44:22.068] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:22.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:49:0) after 1m0s
[14:45:22.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:49:0)
[14:45:22.212] Timer tick, asking Buildbucket for the build status
[14:45:22.739] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:22.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:50:0) after 1m0s
[14:46:22.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:50:0)
[14:46:22.793] Timer tick, asking Buildbucket for the build status
[14:46:23.389] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:23.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:51:0) after 1m0s
[14:47:23.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:51:0)
[14:47:23.511] Timer tick, asking Buildbucket for the build status
[14:47:23.605] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:23.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:52:0) after 1m0s
[14:48:23.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:52:0)
[14:48:23.694] Timer tick, asking Buildbucket for the build status
[14:48:23.840] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:23.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:53:0) after 1m0s
[14:49:23.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:53:0)
[14:49:23.821] Timer tick, asking Buildbucket for the build status
[14:49:23.895] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:23.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:54:0) after 1m0s
[14:50:23.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:54:0)
[14:50:23.928] Timer tick, asking Buildbucket for the build status
[14:50:24.033] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:24.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:55:0) after 1m0s
[14:51:24.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:55:0)
[14:51:24.113] Timer tick, asking Buildbucket for the build status
[14:51:26.139] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:26.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:56:0) after 1m0s
[14:52:26.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:56:0)
[14:52:26.352] Timer tick, asking Buildbucket for the build status
[14:52:26.890] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:26.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:57:0) after 1m0s
[14:53:27.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:57:0)
[14:53:27.007] Timer tick, asking Buildbucket for the build status
[14:53:27.247] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:27.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:58:0) after 1m0s
[14:54:27.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:58:0)
[14:54:27.449] Timer tick, asking Buildbucket for the build status
[14:54:27.542] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:27.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:59:0) after 1m0s
[14:55:27.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:59:0)
[14:55:27.657] Timer tick, asking Buildbucket for the build status
[14:55:27.717] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:27.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:60:0) after 1m0s
[14:56:27.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:60:0)
[14:56:27.952] Timer tick, asking Buildbucket for the build status
[14:56:28.212] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:28.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:61:0) after 1m0s
[14:57:28.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:61:0)
[14:57:28.287] Timer tick, asking Buildbucket for the build status
[14:57:28.622] Build 8858122940277334800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:28.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023553002537260016:62:0) after 1m0s
[14:58:13.609] Received PubSub notification, asking Buildbucket for the build status
[14:58:13.970] Build 8858122940277334800: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:58:13.970] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610636291413685",
"created_by": "project:chromium",
"created_ts": "1610632682496535",
"id": "8858122940277334800",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0ec135c30c3a4cb19f35ff7da6c116bed2b3403d\"}}",
"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\": \"0ec135c30c3a4cb19f35ff7da6c116bed2b3403d\", \"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-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16817, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"b421774ae9c3a78ca833a506ce1ea3baa01c3bbe\", \"got_dawn_revision\": \"247f511f198f901ce3eb0344f355f53bf9ffe59d\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"0ec135c30c3a4cb19f35ff7da6c116bed2b3403d\", \"got_revision_cp\": \"refs/heads/master@{#843512}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"5255c465bbbab020b2fa98f818a705e764865869\", \"got_v8_revision\": \"c80a50a3c4de747d61e044cea6f94223016110d2\", \"got_v8_revision_cp\": \"refs/heads/8.9.273@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"0ec135c30c3a4cb19f35ff7da6c116bed2b3403d\", \"swarm_hashes_refs/heads/master(at){#843512}_without_patch\": {\"absl_hardening_tests\": \"e5d200530756ee63183975eb05cece6b271f0d95\", \"accessibility_unittests\": \"b438b08af2e5659edddd4edc297d9a7774b6d219\", \"angle_unittests\": \"fa9cee359bb98e6df1e537fe9b48a8295a3f30af\", \"app_shell_unittests\": \"ee0a04fc0d9847d9719b106a2d1a32c74dda3357\", \"aura_unittests\": \"3b2af61501be05a3825a0ddc52fccb736d5c2c6a\", \"base_unittests\": \"518489998ecf849e371215691510c2a413e17054\", \"base_util_unittests\": \"262151d1d7b6f11e1333516b147d64315a589055\", \"blink_common_unittests\": \"9483fde062f20dfead44808933fa48398b307faa\", \"blink_fuzzer_unittests\": \"524e5c9c85896aed642965770b6af714ec01c0e1\", \"blink_heap_unittests\": \"14100eecbc2c38aebaa7af900ff467e69e1d9ae5\", \"blink_platform_unittests\": \"1d7c92765f98e5fcc51899e1a7658f21605d664c\", \"blink_unittests\": \"c97001eeee65fb9391e66ba381b3db85fa2ae593\", \"boringssl_crypto_tests\": \"4d55d3433385cd277560b7b757b8856f144c9052\", \"boringssl_ssl_tests\": \"f4a74d0719257cd8a75f0fc3203113baa17c1708\", \"browser_switcher_bho_unittests\": \"2140e8c5d7e45c625b12f9c245eda90eb093d4e9\", \"browser_tests\": \"efe9c4dd828f0313f39eb4df3f7668d413764679\", \"capture_unittests\": \"105f58d162bfab4f745b4849ae622416438bfe10\", \"cast_unittests\": \"c3a235835a89a40c2c173816aab0ff845fa3bd34\", \"cc_unittests\": \"f485e36ae4bb60a7db2c49a5ebbd7a8c61892a4b\", \"chrome_app_unittests\": \"2e033b4621a8206e1cd129277281c3b2fccecccf\", \"chrome_cleaner_unittests\": \"92cf8b0122e7b319c0eb41e5a782fedb88af5223\", \"chrome_elf_unittests\": \"7265e3e55fc4cea756984142c0a3dd5d816478c7\", \"chromedriver_unittests\": \"a1c7b25e2df72a37f9c294819a9f00b316a9a108\", \"components_browsertests\": \"89bf2cba31dfc43c29ed66f2384ee50c570b30c7\", \"components_unittests\": \"01ed9076659d102ed1d36c95a18098225c629f21\", \"compositor_unittests\": \"e5833570d32937a36076acb48603cde58182fbd4\", \"content_browsertests\": \"c81d243b3baf63b1262c552b48b4fc2bba4146a7\", \"content_unittests\": \"0fa2559e3234c02273faa8765bd27b9932576710\", \"courgette_unittests\": \"5b36c05fbf6b6a0daaeb02ba86575ec3a7f6186e\", \"crashpad_tests\": \"274aaa96e55b178c72ba7f6ea60b2494a08f27b7\", \"cronet_tests\": \"3d72c0300c84e8036ae3b3c75f7151a2b94bd4f7\", \"cronet_unittests\": \"5a5cc0454f2cd62813f22fad29baa50e4c30f6bc\", \"crypto_unittests\": \"13e3b845582a26f4f7e63923560370dc444e7ff9\", \"delayloads_unittests\": \"25dee505003da6bc7b0614974e161382575bfbd6\", \"device_unittests\": \"22f1671462ae714ca1eea4dccc7448e3f56abda6\", \"display_unittests\": \"d2624e2e698c932aaacad6e7b86e0ee666b9a0d2\", \"elevation_service_unittests\": \"8cac4b6fcb7a6fb351e80287b2e90b37a1876778\", \"events_unittests\": \"8d39e1c08d8d9c5f8fcf63b61464e17c6d009f21\", \"extensions_browsertests\": \"9af8ebbe2ec86ce5a7a3265b9d245f04805fdf8a\", \"extensions_unittests\": \"7eb797c10409c23c6c3d010a748c818d0dd69f07\", \"filesystem_service_unittests\": \"519f37d0a276c7c759a96edb1d035087db6e484f\", \"gcm_unit_tests\": \"9ba89fc161466703b3dad2eb16d8109a74ec2603\", \"gcp_unittests\": \"b328fbad9b15452a0de513158d0cea31300b57e3\", \"gfx_unittests\": \"55b51df9af7717bc75c38c7bfc24e2af51e6c65a\", \"gin_unittests\": \"db00921617e1fd9e2ec4a2b8cca1d56f3c9cdb2b\", \"google_apis_unittests\": \"6c40703f8ecb3401b0becae81aa3b94d257946d6\", \"gpu_unittests\": \"bb9f3460bd6d1006ea5509519bcd6a634b506552\", \"gwp_asan_unittests\": \"0edb5e62ab3bf0fd55d1879597645b04f8f920c5\", \"headless_browsertests\": \"97835eb9c57294e39b787e4be9f746ace71fa139\", \"headless_unittests\": \"5140a318e84aff7d0161fe04080b489641d06d0a\", \"install_static_unittests\": \"b34655fa879dd19a7c852488e9794b5945dff31c\", \"installer_util_unittests\": \"a8600068500ae2afd129fe40b424bea7f59bca7d\", \"interactive_ui_tests\": \"f8894c284cbe499e6136364e01ba77aab8197328\", \"ipc_tests\": \"b60cd4e6dbfbc7ff6d3f338130a4696741c18712\", \"jingle_unittests\": \"46bc3dcb2a6c2d6e1a484b130408e82a2812272b\", \"latency_unittests\": \"94faecce237c0c379aaeccef36b09a1d19434f5b\", \"libjingle_xmpp_unittests\": \"28d9f75b640c56f0722f94da46fdeda8bac30f37\", \"liburlpattern_unittests\": \"6d7aa038f5e23f9350f241bfca4beaaea063e019\", \"media_blink_unittests\": \"fb36c684ce19b74e3019dbba74f6075e07468502\", \"media_unittests\": \"d8d45966c325c2f3fae646807e82ed3323e460ad\", \"message_center_unittests\": \"4676e06ca5ab11598f2c244b104ec0c7d41794bf\", \"midi_unittests\": \"92cb49e04ad7f2c8f57717ca582fe552b8040360\", \"mojo_core_unittests\": \"cc2d1f8753fe339755fda5bd8ca0c0aa3b658051\", \"mojo_unittests\": \"ea829425eef53a7f16783f897cb88f2e99c217d2\", \"nacl_loader_unittests\": \"be9de91f33b8e2cde2f7fe4469f9ed6b5314e84a\", \"native_theme_unittests\": \"3c92077c59581b98c2dcd4e622130e91a61200e2\", \"net_unittests\": \"e090bf6e981640cd7c47d3d4bf75fd4df0035a50\", \"notification_helper_unittests\": \"ad0e7026ef4de7db0290facd8f47287a54e5a96f\", \"pdf_unittests\": \"bbc48064c0c500fa023db72b4ed4ff80f7fb5b1e\", \"perfetto_unittests\": \"d232aaa00814b97103f49b55419728a7ea8b169f\", \"ppapi_unittests\": \"33009a2b6baf269d6baa3c045c489adfc9be3327\", \"printing_unittests\": \"f8389c7c38a4c22710a603145ddcc65b6956b2bd\", \"remoting_unittests\": \"c75b021c8458dba2a88282f64d92e4570e4d5500\", \"sbox_integration_tests\": \"918c44c0dba0a7476ac0403e32c1ab20a0c3b4fa\", \"sbox_unittests\": \"3e90ce37a98cfc8214fa7bf36f691328e5fe1340\", \"sbox_validation_tests\": \"8e927bdb66d094882b16bd9dd2885175f1d53b0e\", \"service_manager_unittests\": \"8873e33e7f606fe5f5acdeb63312c214c0853480\", \"services_unittests\": \"7a566a23c992d18080027908e8a89ba22ddca738\", \"setup_unittests\": \"87f9af6f06a3d98bb66a4f7c15cdccbc53c18a20\", \"shell_dialogs_unittests\": \"21286863a59c0daf5d129c16be69325587d6e1bc\", \"skia_unittests\": \"cd4170b304af356fd7bc7050a281610073383fff\", \"snapshot_unittests\": \"d38a3d17cb534ee753f183bbcd75c885c6cd7dea\", \"sql_unittests\": \"47c132da9e8b679a7f214ffce49b52ec01323bda\", \"storage_unittests\": \"fabdf27751a393269aa287bd92067c77b79c00aa\", \"sync_integration_tests\": \"df72223e901731b6220e6e9cce3b218b3941a978\", \"traffic_annotation_auditor_unittests\": \"2f4a7eed60d8f9bc58273b19706aca6df832f51d\", \"ui_base_unittests\": \"c83049c04da0001c0020632421c0e338a6747b80\", \"ui_touch_selection_unittests\": \"0b09bce87c20fab73088126b22e38a53144d0d08\", \"unit_tests\": \"8bcd42630f89dd828fe7b11285ea6072f3d9edda\", \"updater_tests\": \"01cc41b5e8b17f9b9afba0b3e10e76073ea76a4b\", \"url_unittests\": \"2cd8594fcc2595a70a3651cad0e65e288c3ad289\", \"views_unittests\": \"184392c17dfc00030ed9303841fc8a2335dadad6\", \"viz_unittests\": \"1f60df319261626480de69f254c910ee85db5d6c\", \"vr_common_unittests\": \"d367f6c67181563a9f3f9049d9899cb9d3da3ca3\", \"vr_pixeltests\": \"85f8a2778f634a3b131571d5ac701c731ba8af7d\", \"weblayer_browsertests\": \"a820c34005c9e6c5a0c6eb5c32a07290df9104ca\", \"weblayer_unittests\": \"b3961595ad36d6e2b500d1b3dab530e12d5e5f8c\", \"wm_unittests\": \"c009890b12e75f68cbbf588928780a41136cb0b9\", \"wtf_unittests\": \"7de64d91d939d6aab7255d47ff0eea87a445457f\", \"zlib_unittests\": \"a210b2dc73ef9bd9735550fd6e75ca91eed09365\", \"zucchini_unittests\": \"b306dc77361d37da49650fb7266fa492967c7b40\"}}, \"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-nb2x\"], \"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\": [\"5661-c6f6250\"], \"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": "1610632688784804",
"status": "COMPLETED",
"status_changed_ts": "1610636291665185",
"tags": [
"build_address:luci.chromium.ci/win-asan/16817",
"builder:win-asan",
"buildset:commit/git/0ec135c30c3a4cb19f35ff7da6c116bed2b3403d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ec135c30c3a4cb19f35ff7da6c116bed2b3403d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023553002537260016",
"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/8858122940277334800/+/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:511a01f9e8425210",
"user_agent:luci-scheduler"
],
"updated_ts": "1610636291669495",
"url": "https://ci.chromium.org/b/8858122940277334800",
"utcnow_ts": "1610636293947476"
}
}
[14:58:13.971] Invocation finished in 1h0m13.013948914s with status SUCCEEDED