[08:12:32.096] New invocation is queued and will start shortly
[08:12:33.198] Starting the invocation (attempt 1)
[08:12:33.296] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:12:33.296] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:12:33.296] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020494441485291088",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ddb697122fdc1361080330285ff5d93f40f3f4ac\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020494441485291088",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/ddb697122fdc1361080330285ff5d93f40f3f4ac",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ddb697122fdc1361080330285ff5d93f40f3f4ac",
"gitiles_ref:refs/heads/master"
]
}
[08:12:34.116] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613549553422702",
"id": "8855064379429094576",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ddb697122fdc1361080330285ff5d93f40f3f4ac\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613549553929150",
"tags": [
"build_address:luci.chromium.ci/win-asan/17388",
"builder:win-asan",
"buildset:commit/git/ddb697122fdc1361080330285ff5d93f40f3f4ac",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ddb697122fdc1361080330285ff5d93f40f3f4ac",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020494441485291088",
"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/8855064379429094576/+/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": "1613549553929254",
"url": "https://ci.chromium.org/b/8855064379429094576",
"utcnow_ts": "1613549554105674"
}
}
[08:12:34.116] Task URL: https://ci.chromium.org/b/8855064379429094576
[08:12:34.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:2:0) after 1m0s
[08:12:38.481] Received PubSub notification, asking Buildbucket for the build status
[08:12:38.697] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:34.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:2:0)
[08:13:34.255] Timer tick, asking Buildbucket for the build status
[08:13:34.422] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:34.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:4:0) after 1m0s
[08:14:34.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:4:0)
[08:14:34.491] Timer tick, asking Buildbucket for the build status
[08:14:34.597] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:34.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:5:0) after 1m0s
[08:15:34.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:5:0)
[08:15:34.628] Timer tick, asking Buildbucket for the build status
[08:15:34.712] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:34.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:6:0) after 1m0s
[08:16:34.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:6:0)
[08:16:34.730] Timer tick, asking Buildbucket for the build status
[08:16:34.802] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:34.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:7:0) after 1m0s
[08:17:34.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:7:0)
[08:17:34.822] Timer tick, asking Buildbucket for the build status
[08:17:35.089] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:35.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:8:0) after 1m0s
[08:18:35.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:8:0)
[08:18:35.154] Timer tick, asking Buildbucket for the build status
[08:18:35.243] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:35.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:9:0) after 1m0s
[08:19:35.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:9:0)
[08:19:35.290] Timer tick, asking Buildbucket for the build status
[08:19:35.393] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:35.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:10:0) after 1m0s
[08:20:35.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:10:0)
[08:20:35.404] Timer tick, asking Buildbucket for the build status
[08:20:35.487] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:35.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:11:0) after 1m0s
[08:21:35.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:11:0)
[08:21:35.504] Timer tick, asking Buildbucket for the build status
[08:21:35.578] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:35.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:12:0) after 1m0s
[08:22:35.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:12:0)
[08:22:35.601] Timer tick, asking Buildbucket for the build status
[08:22:35.681] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:35.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:13:0) after 1m0s
[08:23:35.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:13:0)
[08:23:35.693] Timer tick, asking Buildbucket for the build status
[08:23:35.813] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:35.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:14:0) after 1m0s
[08:24:35.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:14:0)
[08:24:35.834] Timer tick, asking Buildbucket for the build status
[08:24:35.935] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:35.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:15:0) after 1m0s
[08:25:35.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:15:0)
[08:25:35.957] Timer tick, asking Buildbucket for the build status
[08:25:36.045] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:36.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:16:0) after 1m0s
[08:26:36.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:16:0)
[08:26:36.069] Timer tick, asking Buildbucket for the build status
[08:26:36.564] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:36.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:17:0) after 1m0s
[08:27:36.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:17:0)
[08:27:36.583] Timer tick, asking Buildbucket for the build status
[08:27:36.765] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:36.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:18:0) after 1m0s
[08:28:36.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:18:0)
[08:28:36.787] Timer tick, asking Buildbucket for the build status
[08:28:36.898] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:36.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:19:0) after 1m0s
[08:29:36.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:19:0)
[08:29:36.923] Timer tick, asking Buildbucket for the build status
[08:29:37.061] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:37.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:20:0) after 1m0s
[08:30:37.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:20:0)
[08:30:37.080] Timer tick, asking Buildbucket for the build status
[08:30:37.301] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:37.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:21:0) after 1m0s
[08:31:37.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:21:0)
[08:31:37.351] Timer tick, asking Buildbucket for the build status
[08:31:37.440] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:37.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:22:0) after 1m0s
[08:32:37.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:22:0)
[08:32:37.492] Timer tick, asking Buildbucket for the build status
[08:32:37.713] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:37.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:23:0) after 1m0s
[08:33:37.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:23:0)
[08:33:37.708] Timer tick, asking Buildbucket for the build status
[08:33:37.800] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:37.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:24:0) after 1m0s
[08:34:37.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:24:0)
[08:34:37.798] Timer tick, asking Buildbucket for the build status
[08:34:37.889] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:37.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:25:0) after 1m0s
[08:35:37.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:25:0)
[08:35:37.920] Timer tick, asking Buildbucket for the build status
[08:35:38.012] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:38.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:26:0) after 1m0s
[08:36:38.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:26:0)
[08:36:38.036] Timer tick, asking Buildbucket for the build status
[08:36:38.179] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:38.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:27:0) after 1m0s
[08:37:38.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:27:0)
[08:37:38.196] Timer tick, asking Buildbucket for the build status
[08:37:38.610] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:38.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:28:0) after 1m0s
[08:38:38.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:28:0)
[08:38:38.663] Timer tick, asking Buildbucket for the build status
[08:38:38.786] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:38.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:29:0) after 1m0s
[08:39:38.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:29:0)
[08:39:38.813] Timer tick, asking Buildbucket for the build status
[08:39:39.002] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:39.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:30:0) after 1m0s
[08:40:39.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:30:0)
[08:40:39.006] Timer tick, asking Buildbucket for the build status
[08:40:39.111] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:39.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:31:0) after 1m0s
[08:41:39.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:31:0)
[08:41:39.167] Timer tick, asking Buildbucket for the build status
[08:41:39.319] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:39.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:32:0) after 1m0s
[08:42:39.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:32:0)
[08:42:39.353] Timer tick, asking Buildbucket for the build status
[08:42:39.674] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:39.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:33:0) after 1m0s
[08:43:39.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:33:0)
[08:43:39.725] Timer tick, asking Buildbucket for the build status
[08:43:39.919] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:39.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:34:0) after 1m0s
[08:44:39.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:34:0)
[08:44:39.951] Timer tick, asking Buildbucket for the build status
[08:44:40.046] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:40.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:35:0) after 1m0s
[08:45:40.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:35:0)
[08:45:40.088] Timer tick, asking Buildbucket for the build status
[08:45:40.211] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:40.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:36:0) after 1m0s
[08:46:40.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:36:0)
[08:46:40.210] Timer tick, asking Buildbucket for the build status
[08:46:40.338] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:40.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:37:0) after 1m0s
[08:47:40.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:37:0)
[08:47:40.380] Timer tick, asking Buildbucket for the build status
[08:47:40.862] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:40.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:38:0) after 1m0s
[08:48:40.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:38:0)
[08:48:40.972] Timer tick, asking Buildbucket for the build status
[08:48:41.062] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:41.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:39:0) after 1m0s
[08:49:41.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:39:0)
[08:49:41.142] Timer tick, asking Buildbucket for the build status
[08:49:41.244] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:41.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:40:0) after 1m0s
[08:50:41.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:40:0)
[08:50:41.268] Timer tick, asking Buildbucket for the build status
[08:50:41.374] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:41.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:41:0) after 1m0s
[08:51:41.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:41:0)
[08:51:41.411] Timer tick, asking Buildbucket for the build status
[08:51:41.731] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:41.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:42:0) after 1m0s
[08:52:41.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:42:0)
[08:52:41.814] Timer tick, asking Buildbucket for the build status
[08:52:41.962] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:41.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:43:0) after 1m0s
[08:53:42.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:43:0)
[08:53:42.084] Timer tick, asking Buildbucket for the build status
[08:53:42.186] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:42.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:44:0) after 1m0s
[08:54:42.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:44:0)
[08:54:42.219] Timer tick, asking Buildbucket for the build status
[08:54:42.368] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:42.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:45:0) after 1m0s
[08:55:42.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:45:0)
[08:55:42.455] Timer tick, asking Buildbucket for the build status
[08:55:42.577] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:42.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:46:0) after 1m0s
[08:56:42.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:46:0)
[08:56:42.611] Timer tick, asking Buildbucket for the build status
[08:56:42.701] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:42.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:47:0) after 1m0s
[08:57:42.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:47:0)
[08:57:42.722] Timer tick, asking Buildbucket for the build status
[08:57:43.422] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:43.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:48:0) after 1m0s
[08:58:43.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:48:0)
[08:58:43.429] Timer tick, asking Buildbucket for the build status
[08:58:43.734] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:43.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:49:0) after 1m0s
[08:59:43.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:49:0)
[08:59:43.758] Timer tick, asking Buildbucket for the build status
[08:59:43.867] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:43.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:50:0) after 1m0s
[09:00:43.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:50:0)
[09:00:43.945] Timer tick, asking Buildbucket for the build status
[09:00:44.260] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:44.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:51:0) after 1m0s
[09:01:44.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:51:0)
[09:01:44.319] Timer tick, asking Buildbucket for the build status
[09:01:44.398] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:44.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:52:0) after 1m0s
[09:02:44.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:52:0)
[09:02:44.397] Timer tick, asking Buildbucket for the build status
[09:02:44.549] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:44.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:53:0) after 1m0s
[09:03:44.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:53:0)
[09:03:44.643] Timer tick, asking Buildbucket for the build status
[09:03:44.737] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:44.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:54:0) after 1m0s
[09:04:44.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:54:0)
[09:04:44.787] Timer tick, asking Buildbucket for the build status
[09:04:45.012] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:45.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:55:0) after 1m0s
[09:05:45.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:55:0)
[09:05:45.084] Timer tick, asking Buildbucket for the build status
[09:05:45.231] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:45.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:56:0) after 1m0s
[09:06:45.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:56:0)
[09:06:45.281] Timer tick, asking Buildbucket for the build status
[09:06:45.369] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:45.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:57:0) after 1m0s
[09:07:45.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:57:0)
[09:07:45.453] Timer tick, asking Buildbucket for the build status
[09:07:45.614] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:45.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:58:0) after 1m0s
[09:08:45.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:58:0)
[09:08:45.643] Timer tick, asking Buildbucket for the build status
[09:08:45.809] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:45.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:59:0) after 1m0s
[09:09:45.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:59:0)
[09:09:45.900] Timer tick, asking Buildbucket for the build status
[09:09:46.590] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:46.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:60:0) after 1m0s
[09:10:46.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:60:0)
[09:10:46.599] Timer tick, asking Buildbucket for the build status
[09:10:46.778] Build 8855064379429094576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:46.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020494441485291088:61:0) after 1m0s
[09:10:58.451] Received PubSub notification, asking Buildbucket for the build status
[09:10:58.581] Build 8855064379429094576: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:10:58.581] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613553057070902",
"created_by": "project:chromium",
"created_ts": "1613549553422702",
"id": "8855064379429094576",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ddb697122fdc1361080330285ff5d93f40f3f4ac\"}}",
"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\": \"ddb697122fdc1361080330285ff5d93f40f3f4ac\", \"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-0-6l07\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17388, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"3c28b2a0b4813f94730b2e8ca29d490bfb5d4c41\", \"got_dawn_revision\": \"a5ba2827f5810b7b1cd6ee046e8df6e109bb02b6\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"ddb697122fdc1361080330285ff5d93f40f3f4ac\", \"got_revision_cp\": \"refs/heads/master@{#854668}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"bb04803b8daf08e186b327d18e14c78378f44e37\", \"got_v8_revision\": \"972f21b5ed71a418e8ed86f3b39bb7c2b7f175ac\", \"got_v8_revision_cp\": \"refs/heads/9.0.197@{#1}\", \"got_webrtc_revision\": \"8408c9938cb543a7cd5e7127927f3b4d0834ad59\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33272}\", \"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\": \"ddb697122fdc1361080330285ff5d93f40f3f4ac\", \"swarm_hashes_refs/heads/master(at){#854668}_without_patch\": {\"absl_hardening_tests\": \"0a628f8bbc5e4d80f255e4b53544929b307f2682\", \"accessibility_unittests\": \"6c982cb569a223b4dad62d4cd4d7b2cb545befe0\", \"angle_unittests\": \"051363136f5adad8eed838bc06103cf458df1100\", \"app_shell_unittests\": \"2de79770fd17abda44dcac498237fc6e75d2fc7f\", \"aura_unittests\": \"0f75dec020be08988dd3ce62f010ea343b35146b\", \"base_unittests\": \"4a45a35674d0311aa6f4807523b26e137a3faa18\", \"base_util_unittests\": \"8d1377081e5594a083a4c064236793ec057d0cbb\", \"blink_common_unittests\": \"f9d064cebaec26e1c8389d46c53a8f1ade997340\", \"blink_fuzzer_unittests\": \"55d2153a9551f3156d51c8c9d08cbcdb00d800d6\", \"blink_heap_unittests\": \"3e2a2774811e9a5f724416d12f728649bbe7b21b\", \"blink_platform_unittests\": \"6a10b7600d12c29ecfc157ed0c5a2e3913fc860d\", \"blink_unittests\": \"e7eb1d8e2abd0c0a8b94c4c9dbe30706ed06993c\", \"boringssl_crypto_tests\": \"f4e41508c5b4b4e159cc09a8c56e71372db9a544\", \"boringssl_ssl_tests\": \"fc9d455abec4391f041c03a65d40e501c2abfb53\", \"browser_switcher_bho_unittests\": \"6c41b36760843f6e9337a3bea68312661d7f8fbe\", \"browser_tests\": \"54fd278839c86b8154d0e861d252a96a78afcb6b\", \"capture_unittests\": \"36c8d57f0714e9484546702d540551a201b41b3f\", \"cast_unittests\": \"0c38ab873a633a1e6a3e5c9237fa5c3770eeb7f1\", \"cc_unittests\": \"b552792cbcf7c8f66d7511ab8dbb318628ae3b26\", \"chrome_app_unittests\": \"9713b9b1b2cc75c457b6568e06fc841097f04b55\", \"chrome_cleaner_unittests\": \"bd5968f850b3f19c2b31f5f9d26b67801afac8ab\", \"chrome_elf_unittests\": \"eb4e5208d14ee5dc6360032678cea040667ce664\", \"chromedriver_unittests\": \"0ffb3902a474614e46b72b54f816ffb4cb19d757\", \"components_browsertests\": \"cec78e58b33de42c0c30f086a3938304fe34d1a8\", \"components_unittests\": \"d1ed4674b5e65b9a061a3e892b9ec67d562be06b\", \"compositor_unittests\": \"293f0454f3c36460cbfac310d4a4a66e9375de18\", \"content_browsertests\": \"69e4ca1156a466c64355daaae81a2482c8ffd8d2\", \"content_unittests\": \"16b0686e9bc92638dc27a24b61a9f1071dace2d7\", \"courgette_unittests\": \"cf7afa69021ceb9060662627790f9685e9f43757\", \"crashpad_tests\": \"20526affcd66c48b9d6c0bd3a632054bec6eed91\", \"cronet_tests\": \"2a520bbaf290099e65eece6adde67c9b19cf0954\", \"cronet_unittests\": \"2ed31bb4c6495b411d1b8b6bde4072b102e0f0ce\", \"crypto_unittests\": \"ae9bf65d511dfa13a835f94a231594f6bb6ae2a2\", \"delayloads_unittests\": \"7f67b2fa7f5792f8574922dd13b82fd48c32d5ae\", \"device_unittests\": \"4fc02f22c6f56ba68bffb17f996f67dceeff1ed7\", \"display_unittests\": \"6a2af6c8de95c755524ea783177be58f78c1ce1c\", \"elevation_service_unittests\": \"45df785c5817c5c6feee43b3d23269667cf1da28\", \"events_unittests\": \"aa9bab7701484ae89e7f7371ff5fa30aa32d81d8\", \"extensions_browsertests\": \"2b7a8e5aa4032eeb1ef02249a288fca9b119af70\", \"extensions_unittests\": \"cc8b5b4e4705e1c04353cb22fc06c2b0f34cdf12\", \"filesystem_service_unittests\": \"cce1d229aeb4457f5aa939c89a5b4fc0fe938aa0\", \"gcm_unit_tests\": \"f1769bdc74ecfb0c156d08f039b559b625542b6d\", \"gcp_unittests\": \"7da024fb5cda31f0bfd464bd53ae60c529123432\", \"gfx_unittests\": \"52224f91419a3450d8630bd3d2f0c0c69b2fa640\", \"gin_unittests\": \"a9c74a2b2d1be267792e2401968d08563954f70a\", \"google_apis_unittests\": \"bc264833f9c967b81ce99231845bb42f99eb7ab3\", \"gpu_unittests\": \"085f95764faa15775703dccfdf8e67e82679510c\", \"gwp_asan_unittests\": \"5776f4c095658ba92eab1357fd7a1b97acda422a\", \"headless_browsertests\": \"98b33f1b53e48bac0dae3c9cb8e6b9aa0c1466ff\", \"headless_unittests\": \"0cb9df8d22ed6442ffb032c1828ae8abfc5f0b55\", \"install_static_unittests\": \"836c0f877f0f444396a0a0bb2d3a5fe141e6b940\", \"installer_util_unittests\": \"7db0ca43d8f503989cc8ee39fc1d7b004556800c\", \"interactive_ui_tests\": \"abe474dd11489dafb04eb4095a178c40aac6bbe4\", \"ipc_tests\": \"4af2abbebcf5a66b7c58d0fea3555c95f9e5427d\", \"jingle_unittests\": \"a84c6550829c0b3ea9ec226d05fbdfc012aafe91\", \"latency_unittests\": \"5ffbae5311ce93946ff3fb01884acd7ccbb79e56\", \"libjingle_xmpp_unittests\": \"d1b7d1bb989320ba7658d0dc8c9662136928cbf1\", \"liburlpattern_unittests\": \"4d1cf9472165a27ded000a3f45fb596c3e44596f\", \"media_blink_unittests\": \"bbe32e6905e93cd2a034db4df3687ed30f975e55\", \"media_unittests\": \"f461231617b310d2e93a1883a4e8df5b4a8c06fa\", \"message_center_unittests\": \"19ee72f99d42f0bd3199a766a3a682fb987ff2bf\", \"midi_unittests\": \"373b263c33c6bb08f4da125eb1739c83d148e853\", \"mojo_core_unittests\": \"52565d1b7baf58b23bd3f10aa90813026809a3fb\", \"mojo_unittests\": \"d2575a3e7fdd5942324ef07ae438b5a201e3ecc8\", \"nacl_loader_unittests\": \"86a15e3975819ec0d53fd758707acd865634ecff\", \"native_theme_unittests\": \"ef4848d2465932568eeb104012e2c003d30919f6\", \"net_unittests\": \"e3e19ac3b7f185aa820091ccf7e65bac8c658ff3\", \"notification_helper_unittests\": \"b73a7774947c3bcf40a614202d90b7ba25bff5c4\", \"pdf_unittests\": \"af90480772db846d6bac47968ce46a549e7a8315\", \"perfetto_unittests\": \"d30e3394cc0c61ea61deae2a317a5b8e05f0a6fd\", \"ppapi_unittests\": \"d563aac87c8817abab0421d138e637b236c8c80b\", \"printing_unittests\": \"6a17e40e82d07aa406ae778fea088b4fe11c74d5\", \"remoting_unittests\": \"2f645028f9651ef8cd5e5e364600cf1dc9b5d329\", \"sbox_integration_tests\": \"5a23210139717e79f01db286912ae2d9819827e4\", \"sbox_unittests\": \"d4c3b85299859e07fd1387d1f7d4d163bb5ff2ca\", \"sbox_validation_tests\": \"6d208b36d5669b4e0ffaf82d7e0208ab4f97860b\", \"service_manager_unittests\": \"58eb58d5f0a3406bc145c390b6eef80f7e1d6bdb\", \"services_unittests\": \"f952b522c334090b5817578f662f6fdf978297d6\", \"setup_unittests\": \"fc1312828492705f335b0eb75d99cf3813170697\", \"shell_dialogs_unittests\": \"0af662c2a34ddcfadea534642f5c9df821c6b563\", \"skia_unittests\": \"3e7ed48140c56ced2b8fd086affa74f5b96234ad\", \"snapshot_unittests\": \"f959d96831a5f37be30ddf75fdfe4b60061044ba\", \"sql_unittests\": \"adea4f26b56617edb368126b44c8a967b850badb\", \"storage_unittests\": \"c109e287a49785a2f47e87d15b25f7a0da28dc4a\", \"sync_integration_tests\": \"d0baef77d88157b3ca4aba3510e4d733ab140b53\", \"traffic_annotation_auditor_unittests\": \"922f468a27edad5513a5ea9e0d6c3ddefe20858b\", \"ui_base_unittests\": \"9dd4f9b540cd87714e418fd85081d5b65538e8aa\", \"ui_touch_selection_unittests\": \"065d24dd8560bd31faee608f3528e4ff852fd936\", \"unit_tests\": \"0ebabc60c8868a953f7d0db0a9c6988d27cd1d07\", \"updater_tests\": \"c38c2ba9468d2c57c6d3f170a57d419d73e0c954\", \"url_unittests\": \"eaddb19706c7edc82c624cab7565a13f711cd874\", \"views_unittests\": \"ecaa96fb53beffdf8fe9c2ca498737f83c548d05\", \"viz_unittests\": \"d9c92e25ef34b429b3e034d6d2624d50db3b1c5b\", \"vr_common_unittests\": \"54ad038c08f4581ee28cf4fecfba6318f86cc6c5\", \"vr_pixeltests\": \"14573b7207f2429d23ad8b894317446248c4b549\", \"weblayer_browsertests\": \"e3529238b76d63d51a23cd65802d35860c4c3522\", \"weblayer_unittests\": \"010b35957ea3440b1004657d386f24bc2a356b2f\", \"wm_unittests\": \"2b3bfe2fc74ed976b072c40bc0af165cba8cd2c4\", \"wtf_unittests\": \"a547bd54a7632119fee1361bbe62a30f08e80f2b\", \"zlib_unittests\": \"47f71713589c4515d03f067008a3f9efa926bcd6\", \"zucchini_unittests\": \"0fdb3c21c5a490318e0c404ccedb5abd30e7168c\"}}, \"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-0-6l07\"], \"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\": [\"5700-b36f18b\"], \"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": "1613549557944582",
"status": "COMPLETED",
"status_changed_ts": "1613553057648868",
"tags": [
"build_address:luci.chromium.ci/win-asan/17388",
"builder:win-asan",
"buildset:commit/git/ddb697122fdc1361080330285ff5d93f40f3f4ac",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ddb697122fdc1361080330285ff5d93f40f3f4ac",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020494441485291088",
"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/8855064379429094576/+/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:51c7ddd52effea10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613553057658011",
"url": "https://ci.chromium.org/b/8855064379429094576",
"utcnow_ts": "1613553058557796"
}
}
[09:10:58.581] Invocation finished in 58m26.502911619s with status SUCCEEDED