[03:36:48.897] New invocation is queued and will start shortly
[03:36:50.056] Starting the invocation (attempt 1)
[03:36:50.088] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:36:50.088] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:36:50.088] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022595518511269360",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"09570e87f5f86583fc702a253f4b1ebfeaa29e02\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022595518511269360",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/09570e87f5f86583fc702a253f4b1ebfeaa29e02",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09570e87f5f86583fc702a253f4b1ebfeaa29e02",
"gitiles_ref:refs/heads/master"
]
}
[03:36:51.654] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611545810186199",
"id": "8857165456496561648",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"09570e87f5f86583fc702a253f4b1ebfeaa29e02\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611545811293079",
"tags": [
"build_address:luci.chromium.ci/win-asan/16999",
"builder:win-asan",
"buildset:commit/git/09570e87f5f86583fc702a253f4b1ebfeaa29e02",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09570e87f5f86583fc702a253f4b1ebfeaa29e02",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022595518511269360",
"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/8857165456496561648/+/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": "1611545811293251",
"url": "https://ci.chromium.org/b/8857165456496561648",
"utcnow_ts": "1611545811641558"
}
}
[03:36:51.654] Task URL: https://ci.chromium.org/b/8857165456496561648
[03:36:51.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:2:0) after 1m0s
[03:36:55.083] Received PubSub notification, asking Buildbucket for the build status
[03:36:55.239] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:51.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:2:0)
[03:37:51.672] Timer tick, asking Buildbucket for the build status
[03:37:51.786] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:51.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:4:0) after 1m0s
[03:38:51.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:4:0)
[03:38:51.805] Timer tick, asking Buildbucket for the build status
[03:38:51.980] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:51.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:5:0) after 1m0s
[03:39:52.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:5:0)
[03:39:52.004] Timer tick, asking Buildbucket for the build status
[03:39:52.115] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:52.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:6:0) after 1m0s
[03:40:52.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:6:0)
[03:40:52.141] Timer tick, asking Buildbucket for the build status
[03:40:52.256] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:52.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:7:0) after 1m0s
[03:41:52.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:7:0)
[03:41:52.242] Timer tick, asking Buildbucket for the build status
[03:41:52.463] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:52.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:8:0) after 1m0s
[03:42:52.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:8:0)
[03:42:52.497] Timer tick, asking Buildbucket for the build status
[03:42:52.626] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:52.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:9:0) after 1m0s
[03:43:52.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:9:0)
[03:43:52.645] Timer tick, asking Buildbucket for the build status
[03:43:52.753] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:52.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:10:0) after 1m0s
[03:44:52.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:10:0)
[03:44:52.804] Timer tick, asking Buildbucket for the build status
[03:44:52.946] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:52.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:11:0) after 1m0s
[03:45:52.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:11:0)
[03:45:52.997] Timer tick, asking Buildbucket for the build status
[03:45:53.099] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:53.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:12:0) after 1m0s
[03:46:53.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:12:0)
[03:46:53.078] Timer tick, asking Buildbucket for the build status
[03:46:53.204] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:53.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:13:0) after 1m0s
[03:47:53.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:13:0)
[03:47:53.222] Timer tick, asking Buildbucket for the build status
[03:47:53.288] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:53.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:14:0) after 1m0s
[03:48:53.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:14:0)
[03:48:53.342] Timer tick, asking Buildbucket for the build status
[03:48:53.398] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:53.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:15:0) after 1m0s
[03:49:53.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:15:0)
[03:49:53.392] Timer tick, asking Buildbucket for the build status
[03:49:53.458] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:53.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:16:0) after 1m0s
[03:50:53.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:16:0)
[03:50:53.476] Timer tick, asking Buildbucket for the build status
[03:50:53.693] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:53.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:17:0) after 1m0s
[03:51:53.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:17:0)
[03:51:53.722] Timer tick, asking Buildbucket for the build status
[03:51:54.020] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:54.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:18:0) after 1m0s
[03:52:54.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:18:0)
[03:52:54.005] Timer tick, asking Buildbucket for the build status
[03:52:54.074] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:54.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:19:0) after 1m0s
[03:53:54.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:19:0)
[03:53:54.106] Timer tick, asking Buildbucket for the build status
[03:53:54.285] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:54.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:20:0) after 1m0s
[03:54:54.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:20:0)
[03:54:54.311] Timer tick, asking Buildbucket for the build status
[03:54:54.421] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:54.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:21:0) after 1m0s
[03:55:54.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:21:0)
[03:55:54.439] Timer tick, asking Buildbucket for the build status
[03:55:54.612] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:54.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:22:0) after 1m0s
[03:56:54.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:22:0)
[03:56:54.648] Timer tick, asking Buildbucket for the build status
[03:56:54.774] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:54.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:23:0) after 1m0s
[03:57:54.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:23:0)
[03:57:54.780] Timer tick, asking Buildbucket for the build status
[03:57:54.871] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:54.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:24:0) after 1m0s
[03:58:54.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:24:0)
[03:58:54.905] Timer tick, asking Buildbucket for the build status
[03:58:55.104] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:55.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:25:0) after 1m0s
[03:59:55.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:25:0)
[03:59:55.175] Timer tick, asking Buildbucket for the build status
[03:59:55.284] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:55.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:26:0) after 1m0s
[04:00:55.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:26:0)
[04:00:55.339] Timer tick, asking Buildbucket for the build status
[04:00:55.488] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:55.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:27:0) after 1m0s
[04:01:55.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:27:0)
[04:01:55.564] Timer tick, asking Buildbucket for the build status
[04:01:55.851] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:55.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:28:0) after 1m0s
[04:02:55.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:28:0)
[04:02:55.873] Timer tick, asking Buildbucket for the build status
[04:02:56.410] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:56.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:29:0) after 1m0s
[04:03:56.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:29:0)
[04:03:56.441] Timer tick, asking Buildbucket for the build status
[04:03:56.792] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:56.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:30:0) after 1m0s
[04:04:56.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:30:0)
[04:04:56.809] Timer tick, asking Buildbucket for the build status
[04:04:57.598] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:57.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:31:0) after 1m0s
[04:05:57.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:31:0)
[04:05:57.619] Timer tick, asking Buildbucket for the build status
[04:05:57.700] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:57.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:32:0) after 1m0s
[04:06:57.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:32:0)
[04:06:57.720] Timer tick, asking Buildbucket for the build status
[04:06:57.886] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:57.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:33:0) after 1m0s
[04:07:57.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:33:0)
[04:07:57.972] Timer tick, asking Buildbucket for the build status
[04:07:58.087] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:58.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:34:0) after 1m0s
[04:08:58.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:34:0)
[04:08:58.112] Timer tick, asking Buildbucket for the build status
[04:08:58.205] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:58.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:35:0) after 1m0s
[04:09:58.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:35:0)
[04:09:58.238] Timer tick, asking Buildbucket for the build status
[04:09:58.305] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:58.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:36:0) after 1m0s
[04:10:58.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:36:0)
[04:10:58.426] Timer tick, asking Buildbucket for the build status
[04:10:58.788] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:58.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:37:0) after 1m0s
[04:11:58.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:37:0)
[04:11:58.860] Timer tick, asking Buildbucket for the build status
[04:11:58.965] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:58.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:38:0) after 1m0s
[04:12:58.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:38:0)
[04:12:58.984] Timer tick, asking Buildbucket for the build status
[04:12:59.096] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:59.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:39:0) after 1m0s
[04:13:59.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:39:0)
[04:13:59.092] Timer tick, asking Buildbucket for the build status
[04:13:59.198] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:59.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:40:0) after 1m0s
[04:14:59.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:40:0)
[04:14:59.228] Timer tick, asking Buildbucket for the build status
[04:14:59.292] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:59.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:41:0) after 1m0s
[04:15:59.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:41:0)
[04:15:59.333] Timer tick, asking Buildbucket for the build status
[04:15:59.409] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:59.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:42:0) after 1m0s
[04:16:59.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:42:0)
[04:16:59.428] Timer tick, asking Buildbucket for the build status
[04:16:59.562] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:59.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:43:0) after 1m0s
[04:17:59.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:43:0)
[04:17:59.581] Timer tick, asking Buildbucket for the build status
[04:17:59.763] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:59.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:44:0) after 1m0s
[04:18:59.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:44:0)
[04:18:59.793] Timer tick, asking Buildbucket for the build status
[04:18:59.908] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:59.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:45:0) after 1m0s
[04:19:59.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:45:0)
[04:19:59.976] Timer tick, asking Buildbucket for the build status
[04:20:00.075] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:00.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:46:0) after 1m0s
[04:21:00.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:46:0)
[04:21:00.085] Timer tick, asking Buildbucket for the build status
[04:21:00.192] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:00.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:47:0) after 1m0s
[04:22:00.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:47:0)
[04:22:00.241] Timer tick, asking Buildbucket for the build status
[04:22:00.345] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:00.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:48:0) after 1m0s
[04:23:00.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:48:0)
[04:23:00.400] Timer tick, asking Buildbucket for the build status
[04:23:00.696] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:00.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:49:0) after 1m0s
[04:24:01.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:49:0)
[04:24:01.338] Timer tick, asking Buildbucket for the build status
[04:24:01.797] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:01.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:50:0) after 1m0s
[04:25:01.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:50:0)
[04:25:01.841] Timer tick, asking Buildbucket for the build status
[04:25:02.227] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:02.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:51:0) after 1m0s
[04:26:02.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:51:0)
[04:26:02.331] Timer tick, asking Buildbucket for the build status
[04:26:02.536] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:02.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:52:0) after 1m0s
[04:27:02.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:52:0)
[04:27:02.557] Timer tick, asking Buildbucket for the build status
[04:27:03.148] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:03.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:53:0) after 1m0s
[04:28:03.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:53:0)
[04:28:03.219] Timer tick, asking Buildbucket for the build status
[04:28:03.940] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:03.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:54:0) after 1m0s
[04:29:03.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:54:0)
[04:29:03.960] Timer tick, asking Buildbucket for the build status
[04:29:04.050] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:04.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:55:0) after 1m0s
[04:30:04.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:55:0)
[04:30:04.161] Timer tick, asking Buildbucket for the build status
[04:30:04.453] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:04.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:56:0) after 1m0s
[04:31:04.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:56:0)
[04:31:04.493] Timer tick, asking Buildbucket for the build status
[04:31:04.577] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:04.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:57:0) after 1m0s
[04:32:04.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:57:0)
[04:32:04.618] Timer tick, asking Buildbucket for the build status
[04:32:04.733] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:04.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:58:0) after 1m0s
[04:33:04.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:58:0)
[04:33:04.783] Timer tick, asking Buildbucket for the build status
[04:33:05.340] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:05.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:59:0) after 1m0s
[04:34:05.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:59:0)
[04:34:05.360] Timer tick, asking Buildbucket for the build status
[04:34:05.437] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:05.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:60:0) after 1m0s
[04:35:05.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:60:0)
[04:35:05.495] Timer tick, asking Buildbucket for the build status
[04:35:05.793] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:05.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:61:0) after 1m0s
[04:36:05.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:61:0)
[04:36:05.819] Timer tick, asking Buildbucket for the build status
[04:36:05.904] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:05.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:62:0) after 1m0s
[04:37:05.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:62:0)
[04:37:05.931] Timer tick, asking Buildbucket for the build status
[04:37:06.114] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:06.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:63:0) after 1m0s
[04:38:06.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:63:0)
[04:38:06.144] Timer tick, asking Buildbucket for the build status
[04:38:06.425] Build 8857165456496561648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:06.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022595518511269360:64:0) after 1m0s
[04:38:12.493] Received PubSub notification, asking Buildbucket for the build status
[04:38:12.582] Build 8857165456496561648: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:38:12.583] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611549490027701",
"created_by": "project:chromium",
"created_ts": "1611545810186199",
"failure_reason": "BUILD_FAILURE",
"id": "8857165456496561648",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"09570e87f5f86583fc702a253f4b1ebfeaa29e02\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithInvalidModelInfoShouldDeleteTempFile\\n\\n**gcp_unittests** failed because of:\\n\\n- GcpSetupTest.DoInstall\\n\\n- GcpSetupTest.DoInstallWritesUninstallStrings\\n\\n- AvailableGaiaUserName/GcpGaiaUserCreationTest.ExistingGaiaUserTest/6\\n\\n- AvailableGaiaUserName/GcpGaiaUserCreationTest.ExistingGaiaUserTest/5\\n\\n- ...16 more failure(s) (20 total)...\"}, \"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\": \"09570e87f5f86583fc702a253f4b1ebfeaa29e02\", \"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-9-snau\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16999, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"3c3b82abcf0a5c18b4d97ae49726045ce6d3b832\", \"got_dawn_revision\": \"f1f8394de04ea9532de763dfb2f968fc9610683b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"09570e87f5f86583fc702a253f4b1ebfeaa29e02\", \"got_revision_cp\": \"refs/heads/master@{#846621}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"f2b2f28304b332e59226ee6c0688307ed9982884\", \"got_v8_revision_cp\": \"refs/heads/9.0.57@{#1}\", \"got_webrtc_revision\": \"90776cb0ef72d437983c8d29e5a7f2ac264c107d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33064}\", \"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\": \"09570e87f5f86583fc702a253f4b1ebfeaa29e02\", \"swarm_hashes_refs/heads/master(at){#846621}_without_patch\": {\"absl_hardening_tests\": \"1f2a710a685ac73b35047b1818ef70ed124bf33c\", \"accessibility_unittests\": \"6aa2127e0f2f6250272059ebef6f35ad5629e514\", \"angle_unittests\": \"069c1c9c80c861d5f0030d422cdff94d07317eba\", \"app_shell_unittests\": \"58b61c992adda2ec54249062bccb23a5d1ade4da\", \"aura_unittests\": \"2d87b415e04c965d3e7340e7499b59dce03e45b3\", \"base_unittests\": \"6f58242170690808b8ceade3fd1b9cdcb87054d2\", \"base_util_unittests\": \"9bf07a1a7a634ed272988db3c91c890b2ec5e7a8\", \"blink_common_unittests\": \"347feb5813fb6be4df9c56b5ac738bd62c54c209\", \"blink_fuzzer_unittests\": \"b4e84ed8ede8d6be874775345b52b5df7763da99\", \"blink_heap_unittests\": \"725c76759d702f5010bc8297d2dc18376fd8d476\", \"blink_platform_unittests\": \"fe0dc3d3bb68a65b9bcda3354f9e2af8a3b4da96\", \"blink_unittests\": \"84b798b0e04a48f5117113fbf664c03899f6b733\", \"boringssl_crypto_tests\": \"3d92088223316e0de4cd3a7dc50aaf695ac5258f\", \"boringssl_ssl_tests\": \"fce259e3006c34c2b95622e11032abf419efc721\", \"browser_switcher_bho_unittests\": \"5c3794f88ec637239bd1bc0ad82f29c5fb18f1ce\", \"browser_tests\": \"bec45811c559fcf9452d8a668f0f12b06d39ec40\", \"capture_unittests\": \"9aa5dc9cd253c9ca6a18129438b5980a1588bf4f\", \"cast_unittests\": \"0df008486a091d26fddf42af076448dfef7ef7b2\", \"cc_unittests\": \"8d5fd692ee3a44c09cd7c72bfd3225d26ab472f5\", \"chrome_app_unittests\": \"8a87d3fcea57fb4dd6138e98af52d5064ccc1ba0\", \"chrome_cleaner_unittests\": \"0f540e27b036513e9799f80d89d534e89433dd2a\", \"chrome_elf_unittests\": \"33b12ee482da5379d0fd9724e784cf8d4bc4eea6\", \"chromedriver_unittests\": \"890f92c3d7d113a495347ef8c0e42433abf8fefc\", \"components_browsertests\": \"c6fa46f99f488ce97d62f0d81eeeff6ac0972746\", \"components_unittests\": \"9d494543710bc29a3672374b09ca780aaa8b8cae\", \"compositor_unittests\": \"235f3a5b5c633b44404dbb72b7708b4495154743\", \"content_browsertests\": \"87a498c8a51f5e99e64fa5382bc0fb6973921633\", \"content_unittests\": \"7592dd0f81d53e9c874540986f26c0f5258db1a7\", \"courgette_unittests\": \"a2d2e2239b7d22e3b0ba41f472e09f5b23561dde\", \"crashpad_tests\": \"dc7f13cd1fd56d2cd76af1088da017ceafc7ba51\", \"cronet_tests\": \"2ac455776d7431699e592b21678cef4a29b6323c\", \"cronet_unittests\": \"1408377365b3ad8127ec3bc499c82590da7556d5\", \"crypto_unittests\": \"0dc89e8571a84296c9811a6bd97927b6cf192e8f\", \"delayloads_unittests\": \"0f0811bbc79c497a89654fd97582bcea3d547f21\", \"device_unittests\": \"d409e2784679ce4e129cba205e50e68fb87f0df7\", \"display_unittests\": \"65b66faa77d8ff264da4bba70467f46b7698191d\", \"elevation_service_unittests\": \"0d6cf889416d45b2087c71d33317489e19cbb1b2\", \"events_unittests\": \"fdd22ceb6ce69e1089a2c6b3fe54bfb31d50e31b\", \"extensions_browsertests\": \"0f1259efc180ea9c247c37178232b2d0e9f9d5e7\", \"extensions_unittests\": \"28e6766a75128ba140d2c7d2e3b1c58ba27bac41\", \"filesystem_service_unittests\": \"530cde63cd0994bdf7a7f8d4a6d7c95b207975b7\", \"gcm_unit_tests\": \"3d3df62ae7bd5c16560f3a86884ac36256721d25\", \"gcp_unittests\": \"557269e78ae80aad08000772c003750534b55236\", \"gfx_unittests\": \"2a1b600071a294957ed51e807e22c93dd71a06c2\", \"gin_unittests\": \"9e84284807c24a651eb12d0e2c44c13d500f712b\", \"google_apis_unittests\": \"9770bbdefc873e3772529a0122e91f2d2790ee9c\", \"gpu_unittests\": \"b58c5debd354180bad4d4baf8d8de87436d1d75c\", \"gwp_asan_unittests\": \"30f8313b9ab2f78b9f4cdd226a780db923ced183\", \"headless_browsertests\": \"b15baec5b78e9b9c3e04435e003844ffdefe5947\", \"headless_unittests\": \"d2542675a9d476b7c62bfdcdaa718d00c0146400\", \"install_static_unittests\": \"e3558ab83a39bc1578413b60168e2802dee94474\", \"installer_util_unittests\": \"b4311514b6243a6e99cbb1b7028e7995d3b62f46\", \"interactive_ui_tests\": \"8b9feb232030daca7106c192128e7a26505ac4bc\", \"ipc_tests\": \"96db36febbc89c9636a9e53e2676e6f95e9b74b6\", \"jingle_unittests\": \"9a35c9d01815782ec6ffe7a4ea62af0c9f671806\", \"latency_unittests\": \"38b6ab5e3547e42586edb20332f60c798c8a5849\", \"libjingle_xmpp_unittests\": \"1367ca97893cee47c5d71299dd2687e252a9c502\", \"liburlpattern_unittests\": \"9d811e5b616542cbe0210fdff76c31068fdfe2a2\", \"media_blink_unittests\": \"646e7666b7c20157cb399ce07eb1311754c2d975\", \"media_unittests\": \"4d15a49d4f1d9ebce9abd15b16d9ffe71edd6cbe\", \"message_center_unittests\": \"f9894d29098667d7ed82a0dd717b19cdc246cfe7\", \"midi_unittests\": \"901b43e2f6d1b5bd4956fde7e7f5220a6d876c75\", \"mojo_core_unittests\": \"df8edf8260ced679732c2ee056270feaeaab2edf\", \"mojo_unittests\": \"5a5d55f5a98fbadd059221ab10b9e5700bcde6e3\", \"nacl_loader_unittests\": \"5d6915a94c72170ec794e332712033d953ac28a0\", \"native_theme_unittests\": \"1ee90037ef7eb6ff1cf452a6d5353d1181ce049f\", \"net_unittests\": \"328cdbfdc9aa9a9f766562745b5415921536e2fb\", \"notification_helper_unittests\": \"9c689a3b22e921dd55e8c86562e561bca083ca78\", \"pdf_unittests\": \"004c0bf04c0b24aba55ab2e4238339a0a4a38cb2\", \"perfetto_unittests\": \"e26f45f7cfb9f7c86a6f3927dfdb2c22e707497d\", \"ppapi_unittests\": \"211af090226fa9e062ebf7eee49da51a8064d04a\", \"printing_unittests\": \"43a235692b0f913d100e5248b865f0ebc2a4061a\", \"remoting_unittests\": \"42bd2e107eb6e3d90b94c27d1808f2b8355ccd6a\", \"sbox_integration_tests\": \"362400fca18f6eb3dc8b0aaaa7acae27bb72f8fa\", \"sbox_unittests\": \"346139aa123aabada021834405b492381ac48b30\", \"sbox_validation_tests\": \"2c47a7279953de0af7cbb7eb52f3771ae8b9b70f\", \"service_manager_unittests\": \"29ba79fc993db1b45e58dbce470f43c2cc574bd4\", \"services_unittests\": \"eb38b1446f0711d477c9190a29c4db471878d10f\", \"setup_unittests\": \"5369a7a56c06ad17746124024cfd7f404a658a56\", \"shell_dialogs_unittests\": \"95e2c32ef6c8d9c98b12b3977cd344ce72d3c5a9\", \"skia_unittests\": \"01c24f5e5dbf0d664362b58d31d0116e9671b255\", \"snapshot_unittests\": \"e00e34fadb14e9538ce45659156189a47c7e196c\", \"sql_unittests\": \"4bdb684ccd1bd6fa2acaa4e9c4df7c254ae2d8e5\", \"storage_unittests\": \"ce8556439f23d8af77694c0e70cb8583c4bbf988\", \"sync_integration_tests\": \"81439f56488d22ebde5e66c651f7101e6e47fb46\", \"traffic_annotation_auditor_unittests\": \"c101715058c5ba0c365d7165b2b9bab721a57bfb\", \"ui_base_unittests\": \"48e989f9707a3ce2351e296bceedab6343c79f65\", \"ui_touch_selection_unittests\": \"216651b12fa2fc81aaf8d784a9afa9a2528466a0\", \"unit_tests\": \"d55c457661dc411f95924b4c5c47ec264380a17c\", \"updater_tests\": \"2a35e33064ae36dd51bd43d09285c8d488396ada\", \"url_unittests\": \"15574e119cb099c76bc44dd87f5abd84a78cf367\", \"views_unittests\": \"1d938902ae6c5d560c9dc73d80ffc9abcf773d81\", \"viz_unittests\": \"61a45c8c8fc90bd11a0687257f543e9cd871b0ac\", \"vr_common_unittests\": \"9e39c0e2f1bdb424189a0ea78bdaff27ccf0f58d\", \"vr_pixeltests\": \"a8bcd09a197b55c8af47312a334d1312d7508719\", \"weblayer_browsertests\": \"eca91024d55d6816cceffe545483fe5145f393b1\", \"weblayer_unittests\": \"6f7f8be188cc63e85951030c1c9db9cfc3760059\", \"wm_unittests\": \"e3fe70838534817b6cf7b8bded7aceef11d335a9\", \"wtf_unittests\": \"10c16cfdbe5664e4c440be8d2731ae87c70f1a10\", \"zlib_unittests\": \"a00dc59ab6d5709883183d97c12c7376b66062a8\", \"zucchini_unittests\": \"38d2844117cfb1def6325f047d5b35fd6b3096ee\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-9-snau\"], \"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\": [\"5671-1d9dea8\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"2 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithInvalidModelInfoShouldDeleteTempFile\\n\\n**gcp_unittests** failed because of:\\n\\n- GcpSetupTest.DoInstall\\n\\n- GcpSetupTest.DoInstallWritesUninstallStrings\\n\\n- AvailableGaiaUserName/GcpGaiaUserCreationTest.ExistingGaiaUserTest/6\\n\\n- AvailableGaiaUserName/GcpGaiaUserCreationTest.ExistingGaiaUserTest/5\\n\\n- ...16 more failure(s) (20 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611545813648045",
"status": "COMPLETED",
"status_changed_ts": "1611545813911057",
"tags": [
"build_address:luci.chromium.ci/win-asan/16999",
"builder:win-asan",
"buildset:commit/git/09570e87f5f86583fc702a253f4b1ebfeaa29e02",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09570e87f5f86583fc702a253f4b1ebfeaa29e02",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022595518511269360",
"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/8857165456496561648/+/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:51506f233c341210",
"user_agent:luci-scheduler"
],
"updated_ts": "1611549491989936",
"url": "https://ci.chromium.org/b/8857165456496561648",
"utcnow_ts": "1611549492572773"
}
}
[04:38:12.583] Invocation finished in 1h1m23.701684014s with status FAILED