[20:46:38.139] New invocation is queued and will start shortly
[20:46:39.257] Starting the invocation (attempt 1)
[20:46:39.294] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:46:39.295] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:46:39.295] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020899982401502464",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"af214ed5bba724bd27e40234450f52d6310f8b50\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020899982401502464",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/af214ed5bba724bd27e40234450f52d6310f8b50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/af214ed5bba724bd27e40234450f52d6310f8b50",
"gitiles_ref:refs/heads/master"
]
}
[20:46:40.149] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613162799390480",
"id": "8855469920424708816",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"af214ed5bba724bd27e40234450f52d6310f8b50\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613162800020127",
"tags": [
"build_address:luci.chromium.ci/win-asan/17306",
"builder:win-asan",
"buildset:commit/git/af214ed5bba724bd27e40234450f52d6310f8b50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/af214ed5bba724bd27e40234450f52d6310f8b50",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020899982401502464",
"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/8855469920424708816/+/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": "1613162800020267",
"url": "https://ci.chromium.org/b/8855469920424708816",
"utcnow_ts": "1613162800141948"
}
}
[20:46:40.149] Task URL: https://ci.chromium.org/b/8855469920424708816
[20:46:40.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:2:0) after 1m0s
[20:46:44.998] Received PubSub notification, asking Buildbucket for the build status
[20:46:45.114] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:40.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:2:0)
[20:47:40.252] Timer tick, asking Buildbucket for the build status
[20:47:40.354] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:40.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:4:0) after 1m0s
[20:48:40.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:4:0)
[20:48:40.583] Timer tick, asking Buildbucket for the build status
[20:48:40.669] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:40.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:5:0) after 1m0s
[20:49:40.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:5:0)
[20:49:40.754] Timer tick, asking Buildbucket for the build status
[20:49:40.988] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:40.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:6:0) after 1m0s
[20:50:41.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:6:0)
[20:50:41.234] Timer tick, asking Buildbucket for the build status
[20:50:41.397] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:41.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:7:0) after 1m0s
[20:51:41.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:7:0)
[20:51:41.567] Timer tick, asking Buildbucket for the build status
[20:51:41.666] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:41.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:8:0) after 1m0s
[20:52:41.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:8:0)
[20:52:41.747] Timer tick, asking Buildbucket for the build status
[20:52:42.010] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:42.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:9:0) after 1m0s
[20:53:42.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:9:0)
[20:53:42.042] Timer tick, asking Buildbucket for the build status
[20:53:42.123] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:42.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:10:0) after 1m0s
[20:54:42.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:10:0)
[20:54:42.142] Timer tick, asking Buildbucket for the build status
[20:54:42.395] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:42.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:11:0) after 1m0s
[20:55:42.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:11:0)
[20:55:42.516] Timer tick, asking Buildbucket for the build status
[20:55:42.617] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:42.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:12:0) after 1m0s
[20:56:42.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:12:0)
[20:56:42.716] Timer tick, asking Buildbucket for the build status
[20:56:43.108] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:43.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:13:0) after 1m0s
[20:57:43.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:13:0)
[20:57:43.252] Timer tick, asking Buildbucket for the build status
[20:57:43.326] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:43.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:14:0) after 1m0s
[20:58:43.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:14:0)
[20:58:43.394] Timer tick, asking Buildbucket for the build status
[20:58:43.749] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:43.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:15:0) after 1m0s
[20:59:43.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:15:0)
[20:59:43.741] Timer tick, asking Buildbucket for the build status
[20:59:43.833] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:43.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:16:0) after 1m0s
[21:00:43.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:16:0)
[21:00:43.906] Timer tick, asking Buildbucket for the build status
[21:00:44.446] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:44.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:17:0) after 1m0s
[21:01:44.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:17:0)
[21:01:44.511] Timer tick, asking Buildbucket for the build status
[21:01:44.701] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:44.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:18:0) after 1m0s
[21:02:44.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:18:0)
[21:02:44.750] Timer tick, asking Buildbucket for the build status
[21:02:45.069] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:45.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:19:0) after 1m0s
[21:03:45.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:19:0)
[21:03:45.142] Timer tick, asking Buildbucket for the build status
[21:03:45.355] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:45.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:20:0) after 1m0s
[21:04:45.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:20:0)
[21:04:45.374] Timer tick, asking Buildbucket for the build status
[21:04:45.638] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:45.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:21:0) after 1m0s
[21:05:45.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:21:0)
[21:05:45.658] Timer tick, asking Buildbucket for the build status
[21:05:45.731] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:45.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:22:0) after 1m0s
[21:06:45.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:22:0)
[21:06:45.825] Timer tick, asking Buildbucket for the build status
[21:06:46.238] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:46.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:23:0) after 1m0s
[21:07:46.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:23:0)
[21:07:46.294] Timer tick, asking Buildbucket for the build status
[21:07:46.379] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:46.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:24:0) after 1m0s
[21:08:46.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:24:0)
[21:08:46.472] Timer tick, asking Buildbucket for the build status
[21:08:46.570] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:46.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:25:0) after 1m0s
[21:09:46.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:25:0)
[21:09:46.602] Timer tick, asking Buildbucket for the build status
[21:09:46.854] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:46.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:26:0) after 1m0s
[21:10:46.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:26:0)
[21:10:46.884] Timer tick, asking Buildbucket for the build status
[21:10:47.416] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:47.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:27:0) after 1m0s
[21:11:47.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:27:0)
[21:11:47.458] Timer tick, asking Buildbucket for the build status
[21:11:47.794] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:47.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:28:0) after 1m0s
[21:12:47.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:28:0)
[21:12:47.843] Timer tick, asking Buildbucket for the build status
[21:12:47.930] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:47.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:29:0) after 1m0s
[21:13:47.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:29:0)
[21:13:47.934] Timer tick, asking Buildbucket for the build status
[21:13:48.018] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:48.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:30:0) after 1m0s
[21:14:48.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:30:0)
[21:14:48.123] Timer tick, asking Buildbucket for the build status
[21:14:48.217] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:48.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:31:0) after 1m0s
[21:15:48.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:31:0)
[21:15:48.261] Timer tick, asking Buildbucket for the build status
[21:15:48.392] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:48.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:32:0) after 1m0s
[21:16:48.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:32:0)
[21:16:48.426] Timer tick, asking Buildbucket for the build status
[21:16:48.580] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:48.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:33:0) after 1m0s
[21:17:48.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:33:0)
[21:17:48.785] Timer tick, asking Buildbucket for the build status
[21:17:48.929] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:48.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:34:0) after 1m0s
[21:18:49.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:34:0)
[21:18:49.022] Timer tick, asking Buildbucket for the build status
[21:18:49.474] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:49.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:35:0) after 1m0s
[21:19:49.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:35:0)
[21:19:49.633] Timer tick, asking Buildbucket for the build status
[21:19:50.330] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:50.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:36:0) after 1m0s
[21:20:50.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:36:0)
[21:20:50.422] Timer tick, asking Buildbucket for the build status
[21:20:52.534] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:52.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:37:0) after 1m0s
[21:21:52.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:37:0)
[21:21:52.605] Timer tick, asking Buildbucket for the build status
[21:21:52.710] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:52.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:38:0) after 1m0s
[21:22:52.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:38:0)
[21:22:52.736] Timer tick, asking Buildbucket for the build status
[21:22:52.857] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:52.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:39:0) after 1m0s
[21:23:53.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:39:0)
[21:23:53.080] Timer tick, asking Buildbucket for the build status
[21:23:53.183] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:53.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:40:0) after 1m0s
[21:24:53.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:40:0)
[21:24:53.337] Timer tick, asking Buildbucket for the build status
[21:24:53.492] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:53.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:41:0) after 1m0s
[21:25:53.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:41:0)
[21:25:53.700] Timer tick, asking Buildbucket for the build status
[21:25:53.780] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:53.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:42:0) after 1m0s
[21:26:53.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:42:0)
[21:26:53.909] Timer tick, asking Buildbucket for the build status
[21:26:54.584] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:54.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:43:0) after 1m0s
[21:27:54.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:43:0)
[21:27:54.747] Timer tick, asking Buildbucket for the build status
[21:27:54.855] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:54.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:44:0) after 1m0s
[21:28:54.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:44:0)
[21:28:54.900] Timer tick, asking Buildbucket for the build status
[21:28:55.082] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:55.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:45:0) after 1m0s
[21:29:55.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:45:0)
[21:29:55.107] Timer tick, asking Buildbucket for the build status
[21:29:55.212] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:55.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:46:0) after 1m0s
[21:30:55.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:46:0)
[21:30:55.243] Timer tick, asking Buildbucket for the build status
[21:30:55.666] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:55.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:47:0) after 1m0s
[21:31:55.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:47:0)
[21:31:55.695] Timer tick, asking Buildbucket for the build status
[21:31:55.830] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:55.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:48:0) after 1m0s
[21:32:55.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:48:0)
[21:32:55.864] Timer tick, asking Buildbucket for the build status
[21:32:56.101] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:56.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:49:0) after 1m0s
[21:33:56.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:49:0)
[21:33:56.106] Timer tick, asking Buildbucket for the build status
[21:33:56.201] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:56.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:50:0) after 1m0s
[21:34:56.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:50:0)
[21:34:56.242] Timer tick, asking Buildbucket for the build status
[21:34:56.581] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:56.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:51:0) after 1m0s
[21:35:56.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:51:0)
[21:35:56.619] Timer tick, asking Buildbucket for the build status
[21:35:56.810] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:56.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:52:0) after 1m0s
[21:36:56.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:52:0)
[21:36:56.817] Timer tick, asking Buildbucket for the build status
[21:36:56.943] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:56.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:53:0) after 1m0s
[21:37:57.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:53:0)
[21:37:57.009] Timer tick, asking Buildbucket for the build status
[21:37:57.180] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:57.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:54:0) after 1m0s
[21:38:57.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:54:0)
[21:38:57.203] Timer tick, asking Buildbucket for the build status
[21:38:57.290] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:57.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:55:0) after 1m0s
[21:39:57.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:55:0)
[21:39:57.375] Timer tick, asking Buildbucket for the build status
[21:39:57.480] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:57.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:56:0) after 1m0s
[21:40:57.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:56:0)
[21:40:57.524] Timer tick, asking Buildbucket for the build status
[21:40:57.617] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:57.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:57:0) after 1m0s
[21:41:57.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:57:0)
[21:41:57.644] Timer tick, asking Buildbucket for the build status
[21:41:57.788] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:57.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:58:0) after 1m0s
[21:42:57.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:58:0)
[21:42:57.814] Timer tick, asking Buildbucket for the build status
[21:42:57.934] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:57.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:59:0) after 1m0s
[21:43:57.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:59:0)
[21:43:57.977] Timer tick, asking Buildbucket for the build status
[21:43:58.120] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:58.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:60:0) after 1m0s
[21:44:58.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:60:0)
[21:44:58.203] Timer tick, asking Buildbucket for the build status
[21:44:58.322] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:58.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:61:0) after 1m0s
[21:45:58.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:61:0)
[21:45:58.429] Timer tick, asking Buildbucket for the build status
[21:45:58.511] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:58.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:62:0) after 1m0s
[21:46:58.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:62:0)
[21:46:58.595] Timer tick, asking Buildbucket for the build status
[21:46:58.839] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:58.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:63:0) after 1m0s
[21:47:58.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:63:0)
[21:47:58.895] Timer tick, asking Buildbucket for the build status
[21:47:59.004] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:59.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:64:0) after 1m0s
[21:48:59.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:64:0)
[21:48:59.046] Timer tick, asking Buildbucket for the build status
[21:48:59.410] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:59.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:65:0) after 1m0s
[21:49:59.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:65:0)
[21:49:59.530] Timer tick, asking Buildbucket for the build status
[21:50:00.089] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:00.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:66:0) after 1m0s
[21:51:00.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:66:0)
[21:51:00.193] Timer tick, asking Buildbucket for the build status
[21:51:00.382] Build 8855469920424708816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:00.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020899982401502464:67:0) after 1m0s
[21:51:03.100] Received PubSub notification, asking Buildbucket for the build status
[21:51:03.475] Build 8855469920424708816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:51:03.475] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613166662146997",
"created_by": "project:chromium",
"created_ts": "1613162799390480",
"id": "8855469920424708816",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"af214ed5bba724bd27e40234450f52d6310f8b50\"}}",
"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\": \"af214ed5bba724bd27e40234450f52d6310f8b50\", \"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-1-ckf5\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17306, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"d44159c23c104746dd12b35d251922d89d45f4e8\", \"got_nacl_revision\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\", \"got_revision\": \"af214ed5bba724bd27e40234450f52d6310f8b50\", \"got_revision_cp\": \"refs/heads/master@{#853652}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"0bcb71f9c7372af4a5a56c02cf23775606f789a6\", \"got_v8_revision\": \"260bcd21bdd03c9b69640107ae221492fd04c744\", \"got_v8_revision_cp\": \"refs/heads/9.0.169@{#1}\", \"got_webrtc_revision\": \"f28d7e89b7a3c985d83f59d16b9f3c3f45ef7997\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33224}\", \"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\": \"af214ed5bba724bd27e40234450f52d6310f8b50\", \"swarm_hashes_refs/heads/master(at){#853652}_without_patch\": {\"absl_hardening_tests\": \"5c13357da1276c949ecfc7dcb6c1454165cf6010\", \"accessibility_unittests\": \"35457f2a3454652c81c81c7acd61029165efde7e\", \"angle_unittests\": \"9cd5e80c61840e9a308cc52ebe9d183d360a4efe\", \"app_shell_unittests\": \"490a083dfbf109737f5a9ef1e647c00eea568d30\", \"aura_unittests\": \"afe010d7f50819297246c3aa336e882fb7bcf540\", \"base_unittests\": \"37ce2b37c553090605be4aa51bf511aea74d81cc\", \"base_util_unittests\": \"5cd1c555ba5ddc8a75639a202b681d9418ff560c\", \"blink_common_unittests\": \"b44955777afed2725b890d008163431405d33250\", \"blink_fuzzer_unittests\": \"90911ca65c0f52a32c80a1b2a70fbca4ad2f6aa5\", \"blink_heap_unittests\": \"67b457ee73bc4a434eaaeb0a952a41174ce98efc\", \"blink_platform_unittests\": \"e203cecd9498fc1638cfda46900ad0fb4a635750\", \"blink_unittests\": \"8f8d0db7db4f4302798a2eb3d0d2e90f7922960e\", \"boringssl_crypto_tests\": \"4058901f25e1590a9bdc215c64d88775b35470d6\", \"boringssl_ssl_tests\": \"f6d1885e4e3fbf7f1934ba206248e398daf73265\", \"browser_switcher_bho_unittests\": \"72931c15eed345ddd18fc738534131a4b81f27dc\", \"browser_tests\": \"a8172771d4593c27c149442d866cf24c2fd3a9f7\", \"capture_unittests\": \"9d27f6073d843a881493a4c47777cfa92e8e451b\", \"cast_unittests\": \"611518492eb98b67d7f6d58e162dc6ee8f2758f8\", \"cc_unittests\": \"36ffc8b37857ed47c403f03cc5cbb135a77d93fa\", \"chrome_app_unittests\": \"77636de01a4ea39e16290a22ac13dca4b0f26894\", \"chrome_cleaner_unittests\": \"8b9089ccdd5fa6f53c7a7fc569b7b80cc40d992e\", \"chrome_elf_unittests\": \"977cbfc3de6087869f78c9f58cf6d3387dda40df\", \"chromedriver_unittests\": \"cc35d7ffdccea569620a22f261df6ff666f52fa1\", \"components_browsertests\": \"cb7afc5fd3d855300245cfca5dfa527c9e36d1a5\", \"components_unittests\": \"6513cb81c69b693249ccff8ceeddb3924a5eef9e\", \"compositor_unittests\": \"9c30dc72862699cd273914532f8980c18e5ba194\", \"content_browsertests\": \"1806fdfc56e2bcd56ddd82d761b9af2c9e2f2d86\", \"content_unittests\": \"669d1df381c6d85bb1c2db9d1b90585c721c5d5a\", \"courgette_unittests\": \"226dc2e448dc2611477cb7eb0556cac509b27fc9\", \"crashpad_tests\": \"6141c179d515136b2984d84753df1b7a967ed776\", \"cronet_tests\": \"9cd399b5889e66376c794fd33d2b3b89694d1bba\", \"cronet_unittests\": \"287f28c568a708e0e392135835c75b3681ddde54\", \"crypto_unittests\": \"40724e4ec19626ba5a17b3537303f53e667feb95\", \"delayloads_unittests\": \"94a85bb2054fd21fc4ea7e6f10629dd29e69158b\", \"device_unittests\": \"5193693c7cf4243e519cc2980d29f04ace82b66b\", \"display_unittests\": \"7d0035127a5febe616aca72dcd21a8482945336f\", \"elevation_service_unittests\": \"c9854923098b8983c08b9007e97728bda3eaa59c\", \"events_unittests\": \"df621f8b132c30006b14ab67c4690220567df4d3\", \"extensions_browsertests\": \"aa31a03c09fad849781b745c1c9451b681330b83\", \"extensions_unittests\": \"3231c541064fcdb00b4a21959fb9f58e038c33f3\", \"filesystem_service_unittests\": \"29bfcd49ecb6a83a76e6bcd1ed2b78fd47bf34a0\", \"gcm_unit_tests\": \"0e42c322b3070911be6f627bf3da30c3e6657258\", \"gcp_unittests\": \"0ce5869ce343530d06317f992dc1ebb8a0f3d76c\", \"gfx_unittests\": \"67543f0586c6a51fec3a192e91d202d6ed01ccae\", \"gin_unittests\": \"a4fc06e025501be0c47e19a99c976ecbd0e199b9\", \"google_apis_unittests\": \"48323c71dd97e1eeae807df3d2fabb6d9af7ed8d\", \"gpu_unittests\": \"5467d208afa139b31485cf9ccd7a9a1d8aa92306\", \"gwp_asan_unittests\": \"e82bad58cbb3cd6095ae24c571e152e138421557\", \"headless_browsertests\": \"d8ce90e57598fa87cc5a1126e3156bdcec285d16\", \"headless_unittests\": \"ca343e366fbcf39d5b47ca77c1bbe084844bff49\", \"install_static_unittests\": \"98f0a2d08a83f1490785ca05fdfbe0a2b3f8048d\", \"installer_util_unittests\": \"fca312cd89c7688c781d17e1e738e8875099fe0b\", \"interactive_ui_tests\": \"73d8e8f87afaceba424d8b90e3a36b000b85f553\", \"ipc_tests\": \"46f86795d4956bc0a73e28775cde094ed8bfb8ad\", \"jingle_unittests\": \"66d41645cd585b1a0881c74f3b1fe10a0b7ae849\", \"latency_unittests\": \"b6dd37893f0d18bfce774e56d1f24131658f01dd\", \"libjingle_xmpp_unittests\": \"f9e77e263c87c514a71a40bbedb1757ef6d1e1ca\", \"liburlpattern_unittests\": \"3aec5bd53dd15d887eb866eccbb81e5c31c3762e\", \"media_blink_unittests\": \"cc3cf115ad4cbebd91e106442e9ca96e860ee240\", \"media_unittests\": \"82d66f6363e50105b891a2ff2c21b22bef2c5e06\", \"message_center_unittests\": \"1f79aee8d74c7b999645f275688c7c28cc734369\", \"midi_unittests\": \"546e3c1e205d2c824f6b4ba77c328587d5a50fd0\", \"mojo_core_unittests\": \"10bd7f9121b17be5687cfb806ef1652ae3dec3e5\", \"mojo_unittests\": \"a5b022091111ec966ae39d438eaea824f701a36c\", \"nacl_loader_unittests\": \"49b7918be02bdad8e5958b35619f729e7a465a37\", \"native_theme_unittests\": \"317d396f2eebe8333eb93c7a92efdcb905d200c0\", \"net_unittests\": \"da2140fdfde56d7e265d673c91d3cdb7b79b7784\", \"notification_helper_unittests\": \"0d07fc5c17e68875d1d15316b29e8e3003906d7f\", \"pdf_unittests\": \"b9ed9236db6e712078210aca4a988390c9dcb69e\", \"perfetto_unittests\": \"ee3916e4b9b45eaebf0468e9b746b2b378ab2928\", \"ppapi_unittests\": \"59c06530f9bf06ad3e4b0f64e5642f4a7353adf9\", \"printing_unittests\": \"571259e98228052580185ba44af71dd2866fb730\", \"remoting_unittests\": \"225fec0e63ba989c473f7e7debeb9b7c4b4015a6\", \"sbox_integration_tests\": \"2dd50f0236d8670e223c4a5c9c409f780c24a197\", \"sbox_unittests\": \"71f02c8734d4fdfb11a53aaf2f1bc997f32c26b4\", \"sbox_validation_tests\": \"532b62a176dcfdc8d23f9c950d47c6ccd541cffc\", \"service_manager_unittests\": \"06886a95e1fa4925fbf072bbe339a8179291489e\", \"services_unittests\": \"44571d26697af42ab218a3e119284c9ed30ff788\", \"setup_unittests\": \"d5a6c484e91a1da708475959df30d299a816c544\", \"shell_dialogs_unittests\": \"3574d0f3dff1c0f94c127dd1e18950a55d500afa\", \"skia_unittests\": \"01da5c177b1211eee9546de79b5a81c9c05934e3\", \"snapshot_unittests\": \"05ba58160953337046e601ea747956eedfd45a7d\", \"sql_unittests\": \"47a2195c11f75813aaf3ac49192a48df19f67447\", \"storage_unittests\": \"d2b0e14612bac2208d4a563f1898e69ad629323a\", \"sync_integration_tests\": \"5b8abaf54039ad879832829ca78b7fb5ce01eabf\", \"traffic_annotation_auditor_unittests\": \"f0da9bede95bb123e497b05ba0d22a573a54e788\", \"ui_base_unittests\": \"549f77ba90320385638252b89b40b28614e4f8bf\", \"ui_touch_selection_unittests\": \"3c2c0c5f8a1c7f53ab0440c262d9e77173bc82e3\", \"unit_tests\": \"3552845c762a5035eabcfbb2ec76954a7998b423\", \"updater_tests\": \"6e12ba874bc59de1830639a4917b6eab442e0bab\", \"url_unittests\": \"65501341b54acfe7895f004574a75720e6f3a47d\", \"views_unittests\": \"163c2bf064a2a1d362642f3c15ba0c97ab4c4433\", \"viz_unittests\": \"b1479a337ece537929764a1e6d2746efccfe1289\", \"vr_common_unittests\": \"833904894c267bae75a55a5e72f5e5be007f7f41\", \"vr_pixeltests\": \"4e23ea5f086d276f9d739418acce8a26b7e5cdb0\", \"weblayer_browsertests\": \"9fc9e00f520d5088eac27484c3cf491eb6f54446\", \"weblayer_unittests\": \"bc4c86c76f7cae7807dc63f8f8a68e29a6f6402f\", \"wm_unittests\": \"6cf7722340ac627c728bc2766b4a9a1f1a44d175\", \"wtf_unittests\": \"0bcddd2c2c62020bb87c5b713c49f4c7fea99ef6\", \"zlib_unittests\": \"453f0bb848c8e97769b6fa8aba8a5549bb86e2ed\", \"zucchini_unittests\": \"5d43ab4dcf7d880d74cb030cc30f09cd6e057013\"}}, \"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-1-ckf5\"], \"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\": [\"5690-40331dd\"], \"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": "1613162801766803",
"status": "COMPLETED",
"status_changed_ts": "1613166662484093",
"tags": [
"build_address:luci.chromium.ci/win-asan/17306",
"builder:win-asan",
"buildset:commit/git/af214ed5bba724bd27e40234450f52d6310f8b50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/af214ed5bba724bd27e40234450f52d6310f8b50",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020899982401502464",
"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/8855469920424708816/+/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:51b0d06e56b7d510",
"user_agent:luci-scheduler"
],
"updated_ts": "1613166662497351",
"url": "https://ci.chromium.org/b/8855469920424708816",
"utcnow_ts": "1613166663423011"
}
}
[21:51:03.475] Invocation finished in 1h4m25.353051531s with status SUCCEEDED