[05:04:39.290] New invocation is queued and will start shortly
[05:04:40.369] Starting the invocation (attempt 1)
[05:04:40.408] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:04:40.409] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:04:40.409] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023677155699325136",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"201f15f151543d61bbe258957723526b34c06414\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023677155699325136",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/201f15f151543d61bbe258957723526b34c06414",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/201f15f151543d61bbe258957723526b34c06414",
"gitiles_ref:refs/heads/master"
]
}
[05:04:41.204] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610514280552201",
"id": "8858247093714501760",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"201f15f151543d61bbe258957723526b34c06414\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610514280962656",
"tags": [
"build_address:luci.chromium.ci/win-asan/16790",
"builder:win-asan",
"buildset:commit/git/201f15f151543d61bbe258957723526b34c06414",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/201f15f151543d61bbe258957723526b34c06414",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023677155699325136",
"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/8858247093714501760/+/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": "1610514280962765",
"url": "https://ci.chromium.org/b/8858247093714501760",
"utcnow_ts": "1610514281188637"
}
}
[05:04:41.204] Task URL: https://ci.chromium.org/b/8858247093714501760
[05:04:41.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:2:0) after 1m0s
[05:04:43.148] Received PubSub notification, asking Buildbucket for the build status
[05:04:43.354] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:41.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:2:0)
[05:05:41.350] Timer tick, asking Buildbucket for the build status
[05:05:41.442] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:41.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:4:0) after 1m0s
[05:06:41.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:4:0)
[05:06:41.476] Timer tick, asking Buildbucket for the build status
[05:06:41.582] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:41.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:5:0) after 1m0s
[05:07:41.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:5:0)
[05:07:41.651] Timer tick, asking Buildbucket for the build status
[05:07:41.769] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:41.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:6:0) after 1m0s
[05:08:41.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:6:0)
[05:08:41.819] Timer tick, asking Buildbucket for the build status
[05:08:42.107] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:42.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:7:0) after 1m0s
[05:09:42.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:7:0)
[05:09:42.129] Timer tick, asking Buildbucket for the build status
[05:09:42.258] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:42.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:8:0) after 1m0s
[05:10:42.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:8:0)
[05:10:42.342] Timer tick, asking Buildbucket for the build status
[05:10:42.767] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:42.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:9:0) after 1m0s
[05:11:42.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:9:0)
[05:11:42.842] Timer tick, asking Buildbucket for the build status
[05:11:42.902] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:42.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:10:0) after 1m0s
[05:12:42.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:10:0)
[05:12:42.996] Timer tick, asking Buildbucket for the build status
[05:12:43.049] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:43.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:11:0) after 1m0s
[05:13:43.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:11:0)
[05:13:43.082] Timer tick, asking Buildbucket for the build status
[05:13:44.408] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:44.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:12:0) after 1m0s
[05:14:44.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:12:0)
[05:14:44.430] Timer tick, asking Buildbucket for the build status
[05:14:44.535] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:44.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:13:0) after 1m0s
[05:15:44.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:13:0)
[05:15:44.561] Timer tick, asking Buildbucket for the build status
[05:15:44.615] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:44.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:14:0) after 1m0s
[05:16:44.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:14:0)
[05:16:44.654] Timer tick, asking Buildbucket for the build status
[05:16:44.783] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:44.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:15:0) after 1m0s
[05:17:44.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:15:0)
[05:17:44.836] Timer tick, asking Buildbucket for the build status
[05:17:44.996] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:44.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:16:0) after 1m0s
[05:18:45.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:16:0)
[05:18:45.052] Timer tick, asking Buildbucket for the build status
[05:18:45.193] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:45.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:17:0) after 1m0s
[05:19:45.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:17:0)
[05:19:45.254] Timer tick, asking Buildbucket for the build status
[05:19:45.401] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:45.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:18:0) after 1m0s
[05:20:45.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:18:0)
[05:20:45.431] Timer tick, asking Buildbucket for the build status
[05:20:45.652] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:45.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:19:0) after 1m0s
[05:21:45.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:19:0)
[05:21:45.703] Timer tick, asking Buildbucket for the build status
[05:21:46.158] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:46.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:20:0) after 1m0s
[05:22:46.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:20:0)
[05:22:46.185] Timer tick, asking Buildbucket for the build status
[05:22:46.422] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:46.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:21:0) after 1m0s
[05:23:46.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:21:0)
[05:23:46.393] Timer tick, asking Buildbucket for the build status
[05:23:46.813] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:46.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:22:0) after 1m0s
[05:24:46.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:22:0)
[05:24:46.853] Timer tick, asking Buildbucket for the build status
[05:24:47.300] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:47.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:23:0) after 1m0s
[05:25:47.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:23:0)
[05:25:47.354] Timer tick, asking Buildbucket for the build status
[05:25:47.495] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:47.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:24:0) after 1m0s
[05:26:47.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:24:0)
[05:26:47.513] Timer tick, asking Buildbucket for the build status
[05:26:47.644] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:47.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:25:0) after 1m0s
[05:27:47.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:25:0)
[05:27:47.771] Timer tick, asking Buildbucket for the build status
[05:27:47.876] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:47.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:26:0) after 1m0s
[05:28:47.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:26:0)
[05:28:47.897] Timer tick, asking Buildbucket for the build status
[05:28:47.979] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:47.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:27:0) after 1m0s
[05:29:49.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:27:0)
[05:29:49.617] Timer tick, asking Buildbucket for the build status
[05:29:49.728] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:49.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:28:0) after 1m0s
[05:30:49.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:28:0)
[05:30:49.878] Timer tick, asking Buildbucket for the build status
[05:30:49.943] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:49.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:29:0) after 1m0s
[05:31:50.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:29:0)
[05:31:50.493] Timer tick, asking Buildbucket for the build status
[05:31:50.570] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:50.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:30:0) after 1m0s
[05:32:50.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:30:0)
[05:32:50.966] Timer tick, asking Buildbucket for the build status
[05:32:51.062] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:51.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:31:0) after 1m0s
[05:33:51.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:31:0)
[05:33:51.255] Timer tick, asking Buildbucket for the build status
[05:33:51.372] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:51.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:32:0) after 1m0s
[05:34:51.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:32:0)
[05:34:51.796] Timer tick, asking Buildbucket for the build status
[05:34:51.939] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:51.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:33:0) after 1m0s
[05:35:51.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:33:0)
[05:35:51.935] Timer tick, asking Buildbucket for the build status
[05:35:52.000] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:52.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:34:0) after 1m0s
[05:36:51.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:34:0)
[05:36:51.978] Timer tick, asking Buildbucket for the build status
[05:36:52.126] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:52.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:35:0) after 1m0s
[05:37:52.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:35:0)
[05:37:52.145] Timer tick, asking Buildbucket for the build status
[05:37:52.287] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:52.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:36:0) after 1m0s
[05:38:52.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:36:0)
[05:38:52.403] Timer tick, asking Buildbucket for the build status
[05:38:52.644] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:52.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:37:0) after 1m0s
[05:39:52.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:37:0)
[05:39:52.645] Timer tick, asking Buildbucket for the build status
[05:39:52.766] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:52.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:38:0) after 1m0s
[05:40:52.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:38:0)
[05:40:52.811] Timer tick, asking Buildbucket for the build status
[05:40:52.946] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:52.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:39:0) after 1m0s
[05:41:52.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:39:0)
[05:41:52.928] Timer tick, asking Buildbucket for the build status
[05:41:52.989] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:52.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:40:0) after 1m0s
[05:42:53.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:40:0)
[05:42:53.064] Timer tick, asking Buildbucket for the build status
[05:42:53.188] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:53.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:41:0) after 1m0s
[05:43:53.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:41:0)
[05:43:53.209] Timer tick, asking Buildbucket for the build status
[05:43:53.408] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:53.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:42:0) after 1m0s
[05:44:53.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:42:0)
[05:44:53.464] Timer tick, asking Buildbucket for the build status
[05:44:53.621] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:53.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:43:0) after 1m0s
[05:45:55.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:43:0)
[05:45:55.260] Timer tick, asking Buildbucket for the build status
[05:45:55.389] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:55.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:44:0) after 1m0s
[05:46:55.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:44:0)
[05:46:55.421] Timer tick, asking Buildbucket for the build status
[05:46:55.504] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:55.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:45:0) after 1m0s
[05:47:55.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:45:0)
[05:47:55.555] Timer tick, asking Buildbucket for the build status
[05:47:55.905] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:55.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:46:0) after 1m0s
[05:48:55.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:46:0)
[05:48:55.993] Timer tick, asking Buildbucket for the build status
[05:48:56.096] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:56.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:47:0) after 1m0s
[05:49:56.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:47:0)
[05:49:56.075] Timer tick, asking Buildbucket for the build status
[05:49:56.173] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:56.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:48:0) after 1m0s
[05:50:56.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:48:0)
[05:50:56.206] Timer tick, asking Buildbucket for the build status
[05:50:56.334] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:56.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:49:0) after 1m0s
[05:51:56.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:49:0)
[05:51:56.395] Timer tick, asking Buildbucket for the build status
[05:51:56.529] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:56.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:50:0) after 1m0s
[05:52:56.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:50:0)
[05:52:56.611] Timer tick, asking Buildbucket for the build status
[05:52:56.682] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:56.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:51:0) after 1m0s
[05:53:56.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:51:0)
[05:53:56.710] Timer tick, asking Buildbucket for the build status
[05:53:56.783] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:56.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:52:0) after 1m0s
[05:54:56.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:52:0)
[05:54:56.850] Timer tick, asking Buildbucket for the build status
[05:54:56.941] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:56.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:53:0) after 1m0s
[05:55:57.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:53:0)
[05:55:57.001] Timer tick, asking Buildbucket for the build status
[05:55:57.075] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:57.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:54:0) after 1m0s
[05:56:57.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:54:0)
[05:56:57.148] Timer tick, asking Buildbucket for the build status
[05:56:57.262] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:57.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:55:0) after 1m0s
[05:57:57.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:55:0)
[05:57:57.326] Timer tick, asking Buildbucket for the build status
[05:57:57.502] Build 8858247093714501760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:57.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023677155699325136:56:0) after 1m0s
[05:58:31.128] Received PubSub notification, asking Buildbucket for the build status
[05:58:31.231] Build 8858247093714501760: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:58:31.231] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610517509372889",
"created_by": "project:chromium",
"created_ts": "1610514280552201",
"id": "8858247093714501760",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"201f15f151543d61bbe258957723526b34c06414\"}}",
"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\": \"201f15f151543d61bbe258957723526b34c06414\", \"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-8-wwgq\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16790, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"d38b9f86ff8c57378ed2b301ad1f1b9ba80837ed\", \"got_dawn_revision\": \"22eff1eb8c2ff44e1928121d93559fcf0b81f834\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"201f15f151543d61bbe258957723526b34c06414\", \"got_revision_cp\": \"refs/heads/master@{#842874}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"3d7f91cf38ba97e2f8c8ba88ddeb8e9cc51cfeaf\", \"got_v8_revision_cp\": \"refs/heads/8.9.256@{#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\": \"201f15f151543d61bbe258957723526b34c06414\", \"swarm_hashes_refs/heads/master(at){#842874}_without_patch\": {\"absl_hardening_tests\": \"1ef04d65afd764da8e896aeba26aada62e6909f3\", \"accessibility_unittests\": \"6d61d5fc37f42ee71236719fcfc8041ec20b27a8\", \"angle_unittests\": \"8cb344c6120ce20e33e1de6da414e44fc2341709\", \"app_shell_unittests\": \"8c5d20692d081316382322d6e0901a59217b65dc\", \"aura_unittests\": \"6f6ac137ed6fd16a63ee503ac3c7022d4bc1d17c\", \"base_unittests\": \"06ba49c045eab786cb7ae4a49961143d56402f3a\", \"base_util_unittests\": \"3737961d0c8907433d49c3c79757dfa42e8e044e\", \"blink_common_unittests\": \"d9db66021743819730931a8a71b1def5b19e0828\", \"blink_fuzzer_unittests\": \"7b88d2fc1dbaae4ba9048666ac97010f7623a95e\", \"blink_heap_unittests\": \"9820a2dfa7107a63a4169036fb81f058ca67bf04\", \"blink_platform_unittests\": \"e6345de6c40e90f52dffb69f55cf3dc6c86d113f\", \"blink_unittests\": \"475b770ffb0b4a1da2353474d732268f4aabc713\", \"boringssl_crypto_tests\": \"5ee30f498c0f5848954b33a14a1e19c67cec788d\", \"boringssl_ssl_tests\": \"ee8da5a346cba968462f7108f0783dfd697f9e1a\", \"browser_switcher_bho_unittests\": \"7b8bc711ffb6bead9b12b397a47bae67cb5f6cb3\", \"browser_tests\": \"4da7f91f77ebe5bdf19b4f84385e4533e31ec28f\", \"capture_unittests\": \"a7ac13d5b6727edb97176b91d2bab964ca04fa10\", \"cast_unittests\": \"28a33d08492314767acb258789dd4e4de61dbb5d\", \"cc_unittests\": \"f4d93598e9f49e2ee09374acd3d1220dcc334bd4\", \"chrome_app_unittests\": \"d2e47b92e02042eda2fdb567008dd2a01140e5e9\", \"chrome_cleaner_unittests\": \"b932aa16e65a0c6bdaded19eac718c66bd00bd75\", \"chrome_elf_unittests\": \"1449a6e70df16399dfe69df01a70775235a47290\", \"chromedriver_unittests\": \"450fafe234a9e42429eead3a431bcf6490d25fa1\", \"components_browsertests\": \"3dbc9aedc3176cd412818c232fdc3b81400abd5f\", \"components_unittests\": \"b425702a4ed80adc42c6e5e7418e2db92f9cd979\", \"compositor_unittests\": \"b5e630b9d596ba69df4f32dfea30eb63dad5c7a4\", \"content_browsertests\": \"53f5941722d31c15a1d1eb9dd7ade28e1cf71d09\", \"content_unittests\": \"53248ab4604c3fc06f2dfb0be6eda3eb44879a2c\", \"courgette_unittests\": \"098be4bf12255bab4f09da15b9478f91f5aa7040\", \"crashpad_tests\": \"361cc0081fa38be3532c6c97962a28b6e246ccea\", \"cronet_tests\": \"ea55d73aa0410d382b0be9c06df23cfa33e7def5\", \"cronet_unittests\": \"4132a5d142d76905ce9f4a42e0bc4692052c9871\", \"crypto_unittests\": \"adb7ab3f67626e0925d8d0be0410ea6582422342\", \"delayloads_unittests\": \"9e72dd525f4485833c557110564622f516c7d4e9\", \"device_unittests\": \"ebfef63238bcb4add63a9be50fe37c2943948175\", \"display_unittests\": \"b662eb253358c9d47b1a43a93dd5920d6826fac9\", \"elevation_service_unittests\": \"e8b58ad3c93edfa6ebf2e52754588e7728638b2e\", \"events_unittests\": \"6aaed52fb3cc1e19f370a1ae9b34d5ea2b2f53a4\", \"extensions_browsertests\": \"e5fcbbdb8551b0cac86d239997cab6ff781d8ace\", \"extensions_unittests\": \"5d024c4511cfca46f20a9cc48c6a0f03bdcc0101\", \"filesystem_service_unittests\": \"e520f364def20584f971b93ea66a5b33ec923c37\", \"gcm_unit_tests\": \"4bfd43c8a234ec3557cf438ea646999dcb425d12\", \"gcp_unittests\": \"70c48e3d02310b296b5c069522046b405d2d816d\", \"gfx_unittests\": \"d56a2ab65071604c5ee89f20c9754380dfef9e27\", \"gin_unittests\": \"177329b912f631dee6f245af01c607d4d9857e75\", \"google_apis_unittests\": \"14ea4fccd4857021b3dc8c485b50beca34c770b7\", \"gpu_unittests\": \"1f8b501548237739f7ff8933a841ec08531ac9a8\", \"gwp_asan_unittests\": \"15a1f0574f29e720be5b43fa47ee06d9e0434f18\", \"headless_browsertests\": \"6a54247a45ae8256fe75bab151e43c5d9c9e704a\", \"headless_unittests\": \"e2a7d808c0a9e46778c51226088c7009f5f130ae\", \"install_static_unittests\": \"665d747ee0c6b437221113486535f95d2f306097\", \"installer_util_unittests\": \"5646d2228a67d176ff7db58df8299fc16369a93a\", \"interactive_ui_tests\": \"e9fc2922ce348b35ccc0dedfd99f3210fe12cd3e\", \"ipc_tests\": \"e623ee383a4e525ebf2d8d9c6f6225f71841bfa1\", \"jingle_unittests\": \"04cd0f0437c62d37eaad672855179d39be8389ab\", \"latency_unittests\": \"a256a6601afa4b828d1ba676f9819b3617a3e128\", \"libjingle_xmpp_unittests\": \"93b6af5404d60342d6fafa3f75628cd13d783970\", \"liburlpattern_unittests\": \"62e961b1330c1644b0d3affbb61d654378d011af\", \"media_blink_unittests\": \"d7e053ffa7675eb3718b6250949c43821c1ca54d\", \"media_unittests\": \"4c189480030c4c2b24a0e8b4bf43245f44294f60\", \"message_center_unittests\": \"34a9a84cc8010484dabf6443c5ce4a62312e1ed3\", \"midi_unittests\": \"5b17e444f83f7d2aadeacf9f3f870e20c7cf736c\", \"mojo_core_unittests\": \"a00b4b89d31be2ef0e6e41c059f16e5c5d3bc3ac\", \"mojo_unittests\": \"d0b6f0eaf44a7784b0213edf707403b113b82286\", \"nacl_loader_unittests\": \"a3531185bb975f299ce8d0c5eb4688b02e6496f0\", \"native_theme_unittests\": \"b5d8e00df884e980414ccce3b6d6e5d63500b1ea\", \"net_unittests\": \"bbfd42d9d551678c58cf2b497f0ea92856be398a\", \"notification_helper_unittests\": \"523345c31f60a5b75c717eac5da26b6c8057a0aa\", \"pdf_unittests\": \"30086513cbbc5968179b5086da091ca6be4f8e47\", \"perfetto_unittests\": \"390f48362389f5bdccdb0cfb1480c57c5f2cab07\", \"ppapi_unittests\": \"b896c802ea51a185e030397511c32a285b7cd3b8\", \"printing_unittests\": \"fba75e51790603aedd7db0bf616f832a810fa83a\", \"remoting_unittests\": \"63af9b4dbf16fd9f56805f0e094d12577efcaca5\", \"sbox_integration_tests\": \"63f3f07972685db3e67474c11c9144490ba3d089\", \"sbox_unittests\": \"013a8f0f93a07a08be956ee47c608e8d190523b1\", \"sbox_validation_tests\": \"80bb28f3ae967686eab469f9cfb08f4c0a6ac8dc\", \"service_manager_unittests\": \"d8986e4bce0ab0a79925a5bf0b18354060b5a210\", \"services_unittests\": \"ae14d62765b64eaf096acd8ca9526c3ea412613f\", \"setup_unittests\": \"235edab778372b2d4df2c4913682c6b77598e759\", \"shell_dialogs_unittests\": \"8f92c976a95ebe90989db940cf7f47fbf515d74d\", \"skia_unittests\": \"82dbd940f1cff099de716446db4b72541a3d8281\", \"snapshot_unittests\": \"52981a176be8b97bd8ba95aab42bfd52891e1a4b\", \"sql_unittests\": \"6385b5245ef97c28b769d16b2019b293e544e5ff\", \"storage_unittests\": \"503914923291cfe7907b4b440ea456dcc03159bf\", \"sync_integration_tests\": \"3b627990ed0b4e8d98cebf99e83bdbedb9b8adef\", \"traffic_annotation_auditor_unittests\": \"627fa665b562c32cd1eb45d7cb6ce2dd50852826\", \"ui_base_unittests\": \"523cff7856572c168ae52940a3b4cac70bc2ccdd\", \"ui_touch_selection_unittests\": \"4e85dff53c4e4d864fd0691335f34371a71ac92f\", \"unit_tests\": \"86211f126410ee259285a9caaca8564a61ee355b\", \"updater_tests\": \"23c4e0f1059d19521e275863563aa58ebd611cec\", \"url_unittests\": \"9d1001babf56d179435c7af8624eb9a15bf9a6e1\", \"views_unittests\": \"30e3d05d438fefbcc9da5bbb3f2441e2b8f3b507\", \"viz_unittests\": \"ee41f77a62fe07e6232f55677f01b70eadc4f29d\", \"vr_common_unittests\": \"cc63d4370e99767332bd9c8e5fa10e3cdf9fcdf4\", \"vr_pixeltests\": \"cb45be56689f050675b4c460586336c400ecf0e1\", \"weblayer_browsertests\": \"65f6688a14596e4556c10c56138f8f775d44fc00\", \"weblayer_unittests\": \"eeb004e9c51f805bc64d6ec01d13c0b3af191f20\", \"wm_unittests\": \"92624f31e46f99eb0831c77e364de849fbb01322\", \"wtf_unittests\": \"b9a29874cc5bc53ddbac10b97ddc23a2c14dbf52\", \"zlib_unittests\": \"eac41890e5ef08112a0c18acda29c480652e0f6d\", \"zucchini_unittests\": \"feb6cc47956e67508e476dd2ec0bd9e27a1b3704\"}}, \"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-8-wwgq\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"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": "1610514282446935",
"status": "COMPLETED",
"status_changed_ts": "1610517509820968",
"tags": [
"build_address:luci.chromium.ci/win-asan/16790",
"builder:win-asan",
"buildset:commit/git/201f15f151543d61bbe258957723526b34c06414",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/201f15f151543d61bbe258957723526b34c06414",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023677155699325136",
"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/8858247093714501760/+/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:5112f33ab9bf0110",
"user_agent:luci-scheduler"
],
"updated_ts": "1610517509900421",
"url": "https://ci.chromium.org/b/8858247093714501760",
"utcnow_ts": "1610517511222240"
}
}
[05:58:31.231] Invocation finished in 53m51.956056559s with status SUCCEEDED