[03:04:06.603] New invocation is queued and will start shortly
[03:04:07.659] Starting the invocation (attempt 1)
[03:04:07.691] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:04:07.691] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:04:07.691] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019970264103107920",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ee0acd1e10e863cfe6fe99fffc5c621340ecd702\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019970264103107920",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/ee0acd1e10e863cfe6fe99fffc5c621340ecd702",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ee0acd1e10e863cfe6fe99fffc5c621340ecd702",
"gitiles_ref:refs/heads/master"
]
}
[03:04:08.325] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1614049447790227",
"id": "8854540202192007264",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ee0acd1e10e863cfe6fe99fffc5c621340ecd702\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614049448200442",
"tags": [
"build_address:luci.chromium.ci/win-asan/17481",
"builder:win-asan",
"buildset:commit/git/ee0acd1e10e863cfe6fe99fffc5c621340ecd702",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ee0acd1e10e863cfe6fe99fffc5c621340ecd702",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019970264103107920",
"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/8854540202192007264/+/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": "1614049448200552",
"url": "https://ci.chromium.org/b/8854540202192007264",
"utcnow_ts": "1614049448317804"
}
}
[03:04:08.325] Task URL: https://ci.chromium.org/b/8854540202192007264
[03:04:08.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:2:0) after 1m0s
[03:04:15.476] Received PubSub notification, asking Buildbucket for the build status
[03:04:15.560] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:08.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:2:0)
[03:05:08.385] Timer tick, asking Buildbucket for the build status
[03:05:08.489] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:08.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:4:0) after 1m0s
[03:06:08.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:4:0)
[03:06:08.519] Timer tick, asking Buildbucket for the build status
[03:06:08.624] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:08.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:5:0) after 1m0s
[03:07:08.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:5:0)
[03:07:08.691] Timer tick, asking Buildbucket for the build status
[03:07:08.839] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:08.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:6:0) after 1m0s
[03:08:08.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:6:0)
[03:08:08.855] Timer tick, asking Buildbucket for the build status
[03:08:08.947] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:08.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:7:0) after 1m0s
[03:09:08.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:7:0)
[03:09:08.975] Timer tick, asking Buildbucket for the build status
[03:09:09.489] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:09.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:8:0) after 1m0s
[03:10:09.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:8:0)
[03:10:09.534] Timer tick, asking Buildbucket for the build status
[03:10:09.633] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:09.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:9:0) after 1m0s
[03:11:09.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:9:0)
[03:11:09.688] Timer tick, asking Buildbucket for the build status
[03:11:09.902] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:09.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:10:0) after 1m0s
[03:12:09.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:10:0)
[03:12:09.962] Timer tick, asking Buildbucket for the build status
[03:12:10.114] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:10.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:11:0) after 1m0s
[03:13:10.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:11:0)
[03:13:10.122] Timer tick, asking Buildbucket for the build status
[03:13:10.253] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:10.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:12:0) after 1m0s
[03:14:10.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:12:0)
[03:14:10.244] Timer tick, asking Buildbucket for the build status
[03:14:10.407] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:10.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:13:0) after 1m0s
[03:15:10.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:13:0)
[03:15:10.436] Timer tick, asking Buildbucket for the build status
[03:15:10.582] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:10.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:14:0) after 1m0s
[03:16:10.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:14:0)
[03:16:10.638] Timer tick, asking Buildbucket for the build status
[03:16:10.723] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:10.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:15:0) after 1m0s
[03:17:10.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:15:0)
[03:17:10.777] Timer tick, asking Buildbucket for the build status
[03:17:10.850] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:10.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:16:0) after 1m0s
[03:18:10.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:16:0)
[03:18:10.859] Timer tick, asking Buildbucket for the build status
[03:18:10.956] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:10.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:17:0) after 1m0s
[03:19:10.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:17:0)
[03:19:10.985] Timer tick, asking Buildbucket for the build status
[03:19:11.111] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:11.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:18:0) after 1m0s
[03:20:11.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:18:0)
[03:20:11.183] Timer tick, asking Buildbucket for the build status
[03:20:11.280] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:11.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:19:0) after 1m0s
[03:21:11.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:19:0)
[03:21:11.377] Timer tick, asking Buildbucket for the build status
[03:21:11.455] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:11.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:20:0) after 1m0s
[03:22:11.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:20:0)
[03:22:11.568] Timer tick, asking Buildbucket for the build status
[03:22:11.646] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:11.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:21:0) after 1m0s
[03:23:11.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:21:0)
[03:23:11.655] Timer tick, asking Buildbucket for the build status
[03:23:12.242] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:12.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:22:0) after 1m0s
[03:24:12.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:22:0)
[03:24:12.254] Timer tick, asking Buildbucket for the build status
[03:24:12.381] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:12.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:23:0) after 1m0s
[03:25:12.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:23:0)
[03:25:12.380] Timer tick, asking Buildbucket for the build status
[03:25:12.503] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:12.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:24:0) after 1m0s
[03:26:12.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:24:0)
[03:26:12.603] Timer tick, asking Buildbucket for the build status
[03:26:12.681] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:12.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:25:0) after 1m0s
[03:27:12.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:25:0)
[03:27:12.712] Timer tick, asking Buildbucket for the build status
[03:27:12.828] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:12.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:26:0) after 1m0s
[03:28:12.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:26:0)
[03:28:12.849] Timer tick, asking Buildbucket for the build status
[03:28:12.952] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:12.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:27:0) after 1m0s
[03:29:12.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:27:0)
[03:29:12.999] Timer tick, asking Buildbucket for the build status
[03:29:13.075] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:13.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:28:0) after 1m0s
[03:30:13.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:28:0)
[03:30:13.101] Timer tick, asking Buildbucket for the build status
[03:30:13.206] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:13.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:29:0) after 1m0s
[03:31:13.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:29:0)
[03:31:13.245] Timer tick, asking Buildbucket for the build status
[03:31:13.320] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:13.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:30:0) after 1m0s
[03:32:13.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:30:0)
[03:32:13.436] Timer tick, asking Buildbucket for the build status
[03:32:14.126] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:14.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:31:0) after 1m0s
[03:33:14.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:31:0)
[03:33:14.147] Timer tick, asking Buildbucket for the build status
[03:33:14.315] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:14.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:32:0) after 1m0s
[03:34:14.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:32:0)
[03:34:14.301] Timer tick, asking Buildbucket for the build status
[03:34:14.595] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:14.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:33:0) after 1m0s
[03:35:14.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:33:0)
[03:35:14.690] Timer tick, asking Buildbucket for the build status
[03:35:14.952] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:14.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:34:0) after 1m0s
[03:36:15.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:34:0)
[03:36:15.713] Timer tick, asking Buildbucket for the build status
[03:36:15.817] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:15.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:35:0) after 1m0s
[03:37:15.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:35:0)
[03:37:15.798] Timer tick, asking Buildbucket for the build status
[03:37:15.890] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:15.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:36:0) after 1m0s
[03:38:15.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:36:0)
[03:38:15.914] Timer tick, asking Buildbucket for the build status
[03:38:16.026] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:16.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:37:0) after 1m0s
[03:39:16.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:37:0)
[03:39:16.035] Timer tick, asking Buildbucket for the build status
[03:39:16.396] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:16.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:38:0) after 1m0s
[03:40:16.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:38:0)
[03:40:16.437] Timer tick, asking Buildbucket for the build status
[03:40:16.603] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:16.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:39:0) after 1m0s
[03:41:16.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:39:0)
[03:41:16.683] Timer tick, asking Buildbucket for the build status
[03:41:17.124] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:17.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:40:0) after 1m0s
[03:42:17.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:40:0)
[03:42:17.143] Timer tick, asking Buildbucket for the build status
[03:42:17.295] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:17.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:41:0) after 1m0s
[03:43:17.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:41:0)
[03:43:17.314] Timer tick, asking Buildbucket for the build status
[03:43:17.414] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:17.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:42:0) after 1m0s
[03:44:17.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:42:0)
[03:44:17.446] Timer tick, asking Buildbucket for the build status
[03:44:17.557] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:17.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:43:0) after 1m0s
[03:45:17.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:43:0)
[03:45:17.606] Timer tick, asking Buildbucket for the build status
[03:45:17.683] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:17.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:44:0) after 1m0s
[03:46:17.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:44:0)
[03:46:17.708] Timer tick, asking Buildbucket for the build status
[03:46:17.816] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:17.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:45:0) after 1m0s
[03:47:17.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:45:0)
[03:47:17.869] Timer tick, asking Buildbucket for the build status
[03:47:18.126] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:18.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:46:0) after 1m0s
[03:48:18.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:46:0)
[03:48:18.138] Timer tick, asking Buildbucket for the build status
[03:48:18.219] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:18.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:47:0) after 1m0s
[03:49:18.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:47:0)
[03:49:18.241] Timer tick, asking Buildbucket for the build status
[03:49:18.339] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:18.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:48:0) after 1m0s
[03:50:18.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:48:0)
[03:50:18.371] Timer tick, asking Buildbucket for the build status
[03:50:18.466] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:18.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:49:0) after 1m0s
[03:51:18.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:49:0)
[03:51:18.516] Timer tick, asking Buildbucket for the build status
[03:51:18.611] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:18.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:50:0) after 1m0s
[03:52:18.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:50:0)
[03:52:18.684] Timer tick, asking Buildbucket for the build status
[03:52:18.783] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:18.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:51:0) after 1m0s
[03:53:18.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:51:0)
[03:53:18.830] Timer tick, asking Buildbucket for the build status
[03:53:18.926] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:18.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:52:0) after 1m0s
[03:54:18.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:52:0)
[03:54:18.950] Timer tick, asking Buildbucket for the build status
[03:54:19.268] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:19.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:53:0) after 1m0s
[03:55:19.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:53:0)
[03:55:19.368] Timer tick, asking Buildbucket for the build status
[03:55:19.627] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:19.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:54:0) after 1m0s
[03:56:19.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:54:0)
[03:56:19.705] Timer tick, asking Buildbucket for the build status
[03:56:19.953] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:19.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:55:0) after 1m0s
[03:57:19.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:55:0)
[03:57:19.967] Timer tick, asking Buildbucket for the build status
[03:57:20.218] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:20.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:56:0) after 1m0s
[03:58:20.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:56:0)
[03:58:20.304] Timer tick, asking Buildbucket for the build status
[03:58:20.442] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:20.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:57:0) after 1m0s
[03:59:20.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:57:0)
[03:59:20.539] Timer tick, asking Buildbucket for the build status
[03:59:20.922] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:20.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:58:0) after 1m0s
[04:00:20.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:58:0)
[04:00:20.952] Timer tick, asking Buildbucket for the build status
[04:00:22.066] Build 8854540202192007264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:22.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019970264103107920:59:0) after 1m0s
[04:00:55.336] Received PubSub notification, asking Buildbucket for the build status
[04:00:55.435] Build 8854540202192007264: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:00:55.435] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614052854197841",
"created_by": "project:chromium",
"created_ts": "1614049447790227",
"failure_reason": "BUILD_FAILURE",
"id": "8854540202192007264",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ee0acd1e10e863cfe6fe99fffc5c621340ecd702\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithGoodModelFilesShouldDeleteDownloadFileButHaveContentExtracted\"}, \"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\": \"ee0acd1e10e863cfe6fe99fffc5c621340ecd702\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17481, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"4a95488c0edfb4590c808ee01079069da5a6b2b1\", \"got_dawn_revision\": \"2d3c2e355337037dfa6873536bfb3e3a558bf9ed\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"ee0acd1e10e863cfe6fe99fffc5c621340ecd702\", \"got_revision_cp\": \"refs/heads/master@{#856560}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"4120b5f2509821e8dd460b1d033708397520750a\", \"got_v8_revision_cp\": \"refs/heads/9.0.238@{#1}\", \"got_webrtc_revision\": \"6fe3fa14c6686ba9c51095b97ad2e6833a9b03e5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33319}\", \"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\": \"ee0acd1e10e863cfe6fe99fffc5c621340ecd702\", \"swarm_hashes_refs/heads/master(at){#856560}_without_patch\": {\"absl_hardening_tests\": \"23ae563af07817f654ab4dbfb85931417dea1f0f\", \"accessibility_unittests\": \"5aebda3529b0e70fe52abe2c05a40b990ae583bd\", \"angle_unittests\": \"b691f8b9f7f858655e4bab83d915089858d35fe0\", \"app_shell_unittests\": \"cb6e466dc36cb8e8b6832fd8f71eab5b57277eb3\", \"aura_unittests\": \"e1ab658935c1c0324cbfe4e4c39f1b77074b28e3\", \"base_unittests\": \"45417b10cc30360e07fd46a6d402eb9337f0234c\", \"base_util_unittests\": \"eee751042c0ab9f25c3a09a675941c51d98647cf\", \"blink_common_unittests\": \"0721db4357520bc0b05769611d527852adc40861\", \"blink_fuzzer_unittests\": \"a6c9440dcad520594dcd8390050c2465c30102de\", \"blink_heap_unittests\": \"bfb4c503e002bab8af5cd1733ec34e67a75107eb\", \"blink_platform_unittests\": \"c1912dffdbc9f083ca0ed6a2467e2cb945b4f8aa\", \"blink_unittests\": \"fb197b4181116cc3801d23889896d0c0df6ebcdf\", \"boringssl_crypto_tests\": \"ff5d956681afbb1bba991eafa3bbc846bff8a072\", \"boringssl_ssl_tests\": \"5553d20736860cc41af344a60bb8e975823fa3a3\", \"browser_switcher_bho_unittests\": \"752c1c9e704b63d403fd0df2bf9121b97f75d87e\", \"browser_tests\": \"ed115d599349ed4cebe6eca33b79ea5ee44687fe\", \"capture_unittests\": \"a981888017bc3e6965764370700c1a053cc02dbc\", \"cast_unittests\": \"28735ee7bf2a561d6d3f22b1612ea2bcc0f43591\", \"cc_unittests\": \"868f2b9ec565c41ee8818c4fdf99d78de5442aaa\", \"chrome_app_unittests\": \"574eb84e4ba3482e34203f4a6c8b924477617ded\", \"chrome_cleaner_unittests\": \"05a97a3cddbe8451924888a22667d56eda49af83\", \"chrome_elf_unittests\": \"c14c8127ee27a2d904cc5463956ae015ed9bd509\", \"chromedriver_unittests\": \"62c440ee1fbcc1dcee9156df1c1c97c297968592\", \"components_browsertests\": \"1a0b5564428f6b92e86e1d54acacb58d089bb470\", \"components_unittests\": \"0d546f922b78a0d74b5e800f429b9e2da9478fef\", \"compositor_unittests\": \"e3355c0e37ef844f11fd797053fa7c0314840c4c\", \"content_browsertests\": \"039aeabbbc3293dbbf546e8c7616667ab4722c36\", \"content_unittests\": \"fc618e028a7ef05466e9601fad3939e4e6f93f0b\", \"courgette_unittests\": \"04d39c416e7294e92f6aadef8b9eabb7d479694c\", \"crashpad_tests\": \"0e1da8fdd93564f326cdff7bc65614abf4e7913a\", \"cronet_tests\": \"ba4de3abe1405eb98df54f7662957e291f4dd3b9\", \"cronet_unittests\": \"f7b552f6d16b7aea9995b6d578a5e825ada0ef07\", \"crypto_unittests\": \"264eecd0a142dc5bce089902227d397ffc128c1c\", \"delayloads_unittests\": \"c2ac6d66d614d3ebb7328fa5551cf59899bb8e42\", \"device_unittests\": \"43954f2ce8c21b44e4c44a95e5b1df16d5f6f9a0\", \"display_unittests\": \"7039ed5bca5628a2696b0f2127e094a8bd1025c9\", \"elevation_service_unittests\": \"37bf23f68ea8fcb6d5bc81aa5972a18e0fefdf4f\", \"events_unittests\": \"3f582be5ba8a94c26bb55dca0ff7cdee912fef5f\", \"extensions_browsertests\": \"55d3731036212c543ed7314a281000b389508c8e\", \"extensions_unittests\": \"2a95d69a18c598d7dd23e3cbe301e9611ede70fd\", \"filesystem_service_unittests\": \"c0b0e6c23980243cbf063a19a5768bc73044a4b0\", \"gcm_unit_tests\": \"acd0f6ea51e7d934b6fe87dc634406f2442265c7\", \"gcp_unittests\": \"83276d033636f946abcb6340a3d872e42e73e7db\", \"gfx_unittests\": \"d57f8d7bffb699cbd2c4920499481880c77fe81c\", \"gin_unittests\": \"a4efa8f63d1797f7bb2b80dbc8c55c3e6f8e8d8e\", \"google_apis_unittests\": \"b0016deebc83bfcabac123a7781ac4f40e1b5030\", \"gpu_unittests\": \"2902ed11fd05dedbd7ee88a63c6492221a571f7d\", \"gwp_asan_unittests\": \"6948d1baa9eaae693bfe2ca92f93c7a9771b3dd0\", \"headless_browsertests\": \"a5584c9ef1b1ebdbb16f0ad9050d968e8b6b35f2\", \"headless_unittests\": \"ecf1e1bfbeecccbd3071bbd1464a3ce47d17b4f7\", \"install_static_unittests\": \"214e122c6971449628c99b6ff41fa10c07dce228\", \"installer_util_unittests\": \"9c10141b98b6b48e4fb92882b7e7167d3f2171ee\", \"interactive_ui_tests\": \"f04a205ddf9b03a6e4ef6ec4f9048635f582d30f\", \"ipc_tests\": \"f5de877c67dea99cccc1c3663320d90d82442743\", \"jingle_unittests\": \"958d07bde13422f1c757b876e1d46e189205baef\", \"latency_unittests\": \"cf069305f0064312d927586cb0b838a77a3c0099\", \"libjingle_xmpp_unittests\": \"8e0e4b1edca3d95f58102ad277b7b246bd0ef00b\", \"liburlpattern_unittests\": \"53998a33b1a55e65cb0e67634e1841e6f98560e2\", \"media_blink_unittests\": \"a8ba26c3e62f51fe6e6c68fe93f5186d82445f30\", \"media_unittests\": \"3085607aa877d92096c21b537c28991c571dd51e\", \"message_center_unittests\": \"76d78d05489468abdb32039f54cf21bf664777dc\", \"midi_unittests\": \"9eecbe9672e7af9220522608fe72e4935ea4c048\", \"mojo_core_unittests\": \"eb6aeff57aed32022912275ff8c7d7828609bcd3\", \"mojo_unittests\": \"6f34fc39fe6d090eca412ff10af4395278af3ae1\", \"nacl_loader_unittests\": \"28c352dd1e124cb67bb3e49bf7a8d5cfc5046306\", \"native_theme_unittests\": \"106ddc891a674be3a936742d519fd7a711e25437\", \"net_unittests\": \"289f5cccb17df04b193745bacae45b79e58c2088\", \"notification_helper_unittests\": \"baa3f7c1ea6c3bf22f0875f828c239e968701c23\", \"pdf_unittests\": \"738ad3c4f3c4bd503085ddd598c47b13a64f63cc\", \"perfetto_unittests\": \"5251e60168b4583e9bdab0fbfd743db6dc345521\", \"ppapi_unittests\": \"8e3191357961abca577275ff7736d1ddb686c46a\", \"printing_unittests\": \"8aa3e2cb829bb9a6b7a048564b4a758efe52e929\", \"remoting_unittests\": \"f1884c79af1b46542af489cbcbd2ebaf5fa3bc21\", \"sbox_integration_tests\": \"cdf1c15630eb07bd6df7f95f040b3172cd7fe815\", \"sbox_unittests\": \"e4a0f3e733ab0b5c6c261923e965f5584515bd4b\", \"sbox_validation_tests\": \"be99e74323b89e10297a00ae2b7295aa75b5217c\", \"service_manager_unittests\": \"11bb7e871457f27ca42b1baa346ed5baf8ce63ca\", \"services_unittests\": \"b918dacf4dc9ee1960970cbe1adcf779ed343113\", \"setup_unittests\": \"9f3c5b5ba696f3f96793de33552fea01c06d3af4\", \"shell_dialogs_unittests\": \"2197f823201ecf3e76f7f8847222c67a6c0498e4\", \"skia_unittests\": \"f39c5cda21ea695ee523d144551ad22a8e31e246\", \"snapshot_unittests\": \"f652d2cdbb51871425b6b3b553685537032d8a08\", \"sql_unittests\": \"2911dd30681571071e50e3e4d8ac229154f41bf9\", \"storage_unittests\": \"3389f8b9857b10b7cd841dbb99e94bd7d153082f\", \"sync_integration_tests\": \"a96c93e6a6f842f7f26042f0b4beac8106066079\", \"traffic_annotation_auditor_unittests\": \"bd837224d9b8e9f8a193b29cf8231eeb8452cedb\", \"ui_base_unittests\": \"7440bcae43d8729592de300d7bdce6c5bdc220ac\", \"ui_touch_selection_unittests\": \"01e277ed6eba26aa13e8913718a11422123ba4e2\", \"unit_tests\": \"7e9b3599dcbbedd54af85f404eb404c6f62f5550\", \"updater_tests\": \"5ae879d2ad409e9fe624b37728b203ea361f7d85\", \"url_unittests\": \"d6dadd221f498aa7cee242f506639145202c688e\", \"views_unittests\": \"3b4be8cae768a4cb12a0c5dbfa096a01313efa6e\", \"viz_unittests\": \"affa41792fae7472fe9e4957d17492b61bbd6d79\", \"vr_common_unittests\": \"e61bbf4f9d569cf897bf02abc6353e8c0dcc0d34\", \"vr_pixeltests\": \"d07580cfe661a437d9f7bf0901e4b65d9e3349e4\", \"weblayer_browsertests\": \"0cf02dc774437751a26c7e5570742551fe87f5a4\", \"weblayer_unittests\": \"bdefb883f9aae8504fe58987684ce481c48f2627\", \"wm_unittests\": \"eeee402deead468543c32b0c80d51b14c45313c5\", \"wtf_unittests\": \"c23a051508ca3d39a6d6061e97773e99a8f8e69e\", \"zlib_unittests\": \"a8ca5e0a4edfd7b2ba36a3eb4d6c40d626aba844\", \"zucchini_unittests\": \"203fbc1ba79e727c6d992d3ea1e8c78a38125eb5\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-xpf4\"], \"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\": [\"5706-397711c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithGoodModelFilesShouldDeleteDownloadFileButHaveContentExtracted\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614049452491934",
"status": "COMPLETED",
"status_changed_ts": "1614049453781790",
"tags": [
"build_address:luci.chromium.ci/win-asan/17481",
"builder:win-asan",
"buildset:commit/git/ee0acd1e10e863cfe6fe99fffc5c621340ecd702",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ee0acd1e10e863cfe6fe99fffc5c621340ecd702",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019970264103107920",
"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/8854540202192007264/+/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:51e5a9a4ea94f310",
"user_agent:luci-scheduler"
],
"updated_ts": "1614052855032913",
"url": "https://ci.chromium.org/b/8854540202192007264",
"utcnow_ts": "1614052855419241"
}
}
[04:00:55.435] Invocation finished in 56m48.85090485s with status FAILED