[05:07:14.121] New invocation is queued and will start shortly
[05:07:15.237] Starting the invocation (attempt 1)
[05:07:15.284] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:07:15.284] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:07:15.284] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029837587065794592",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"86f55bba453cfc1c0d1b17847c60cc13dbefeb70\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029837587065794592",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/86f55bba453cfc1c0d1b17847c60cc13dbefeb70",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86f55bba453cfc1c0d1b17847c60cc13dbefeb70",
"gitiles_ref:refs/heads/master"
]
}
[05:07:16.694] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604639235428181",
"id": "8864407525030237344",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"86f55bba453cfc1c0d1b17847c60cc13dbefeb70\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604639236324131",
"tags": [
"build_address:luci.chromium.ci/win-asan/15490",
"builder:win-asan",
"buildset:commit/git/86f55bba453cfc1c0d1b17847c60cc13dbefeb70",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86f55bba453cfc1c0d1b17847c60cc13dbefeb70",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029837587065794592",
"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/8864407525030237344/+/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": "1604639236324330",
"url": "https://ci.chromium.org/b/8864407525030237344",
"utcnow_ts": "1604639236641735"
}
}
[05:07:16.694] Task URL: https://ci.chromium.org/b/8864407525030237344
[05:07:16.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:2:0) after 1m0s
[05:07:22.028] Received PubSub notification, asking Buildbucket for the build status
[05:07:22.185] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:16.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:2:0)
[05:08:16.817] Timer tick, asking Buildbucket for the build status
[05:08:17.012] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:17.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:4:0) after 1m0s
[05:09:17.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:4:0)
[05:09:17.074] Timer tick, asking Buildbucket for the build status
[05:09:17.497] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:17.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:5:0) after 1m0s
[05:10:17.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:5:0)
[05:10:17.642] Timer tick, asking Buildbucket for the build status
[05:10:17.787] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:17.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:6:0) after 1m0s
[05:11:17.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:6:0)
[05:11:17.815] Timer tick, asking Buildbucket for the build status
[05:11:18.479] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:18.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:7:0) after 1m0s
[05:12:18.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:7:0)
[05:12:18.531] Timer tick, asking Buildbucket for the build status
[05:12:19.014] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:19.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:8:0) after 1m0s
[05:13:19.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:8:0)
[05:13:19.054] Timer tick, asking Buildbucket for the build status
[05:13:19.243] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:19.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:9:0) after 1m0s
[05:14:19.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:9:0)
[05:14:19.262] Timer tick, asking Buildbucket for the build status
[05:14:19.327] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:19.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:10:0) after 1m0s
[05:15:19.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:10:0)
[05:15:19.334] Timer tick, asking Buildbucket for the build status
[05:15:19.493] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:19.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:11:0) after 1m0s
[05:16:19.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:11:0)
[05:16:19.506] Timer tick, asking Buildbucket for the build status
[05:16:19.851] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:19.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:12:0) after 1m0s
[05:17:19.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:12:0)
[05:17:19.921] Timer tick, asking Buildbucket for the build status
[05:17:20.075] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:20.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:13:0) after 1m0s
[05:18:20.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:13:0)
[05:18:20.100] Timer tick, asking Buildbucket for the build status
[05:18:20.516] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:20.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:14:0) after 1m0s
[05:19:20.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:14:0)
[05:19:20.528] Timer tick, asking Buildbucket for the build status
[05:19:20.623] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:20.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:15:0) after 1m0s
[05:20:20.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:15:0)
[05:20:20.657] Timer tick, asking Buildbucket for the build status
[05:20:20.717] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:20.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:16:0) after 1m0s
[05:21:20.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:16:0)
[05:21:20.711] Timer tick, asking Buildbucket for the build status
[05:21:20.934] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:20.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:17:0) after 1m0s
[05:22:20.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:17:0)
[05:22:20.995] Timer tick, asking Buildbucket for the build status
[05:22:21.074] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:21.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:18:0) after 1m0s
[05:23:21.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:18:0)
[05:23:21.082] Timer tick, asking Buildbucket for the build status
[05:23:21.195] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:21.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:19:0) after 1m0s
[05:24:21.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:19:0)
[05:24:21.258] Timer tick, asking Buildbucket for the build status
[05:24:21.968] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:21.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:20:0) after 1m0s
[05:25:21.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:20:0)
[05:25:21.996] Timer tick, asking Buildbucket for the build status
[05:25:22.152] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:22.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:21:0) after 1m0s
[05:26:22.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:21:0)
[05:26:22.219] Timer tick, asking Buildbucket for the build status
[05:26:22.290] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:22.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:22:0) after 1m0s
[05:27:22.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:22:0)
[05:27:22.385] Timer tick, asking Buildbucket for the build status
[05:27:22.458] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:22.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:23:0) after 1m0s
[05:28:22.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:23:0)
[05:28:22.565] Timer tick, asking Buildbucket for the build status
[05:28:22.715] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:22.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:24:0) after 1m0s
[05:29:22.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:24:0)
[05:29:22.808] Timer tick, asking Buildbucket for the build status
[05:29:23.175] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:23.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:25:0) after 1m0s
[05:30:23.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:25:0)
[05:30:23.290] Timer tick, asking Buildbucket for the build status
[05:30:23.415] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:23.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:26:0) after 1m0s
[05:31:24.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:26:0)
[05:31:24.043] Timer tick, asking Buildbucket for the build status
[05:31:24.192] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:24.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:27:0) after 1m0s
[05:32:24.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:27:0)
[05:32:24.324] Timer tick, asking Buildbucket for the build status
[05:32:24.430] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:24.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:28:0) after 1m0s
[05:33:24.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:28:0)
[05:33:24.606] Timer tick, asking Buildbucket for the build status
[05:33:24.727] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:24.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:29:0) after 1m0s
[05:34:24.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:29:0)
[05:34:24.726] Timer tick, asking Buildbucket for the build status
[05:34:24.882] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:24.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:30:0) after 1m0s
[05:35:24.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:30:0)
[05:35:24.967] Timer tick, asking Buildbucket for the build status
[05:35:25.571] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:25.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:31:0) after 1m0s
[05:36:25.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:31:0)
[05:36:25.684] Timer tick, asking Buildbucket for the build status
[05:36:25.838] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:25.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:32:0) after 1m0s
[05:37:25.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:32:0)
[05:37:25.883] Timer tick, asking Buildbucket for the build status
[05:37:26.058] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:26.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:33:0) after 1m0s
[05:38:26.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:33:0)
[05:38:26.083] Timer tick, asking Buildbucket for the build status
[05:38:26.274] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:26.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:34:0) after 1m0s
[05:39:26.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:34:0)
[05:39:26.560] Timer tick, asking Buildbucket for the build status
[05:39:26.823] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:26.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:35:0) after 1m0s
[05:40:26.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:35:0)
[05:40:26.963] Timer tick, asking Buildbucket for the build status
[05:40:27.398] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:27.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:36:0) after 1m0s
[05:41:27.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:36:0)
[05:41:27.712] Timer tick, asking Buildbucket for the build status
[05:41:27.826] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:27.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:37:0) after 1m0s
[05:42:28.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:37:0)
[05:42:28.450] Timer tick, asking Buildbucket for the build status
[05:42:28.562] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:28.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:38:0) after 1m0s
[05:43:28.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:38:0)
[05:43:28.825] Timer tick, asking Buildbucket for the build status
[05:43:28.969] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:28.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:39:0) after 1m0s
[05:44:29.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:39:0)
[05:44:29.260] Timer tick, asking Buildbucket for the build status
[05:44:29.932] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:29.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:40:0) after 1m0s
[05:45:30.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:40:0)
[05:45:30.155] Timer tick, asking Buildbucket for the build status
[05:45:30.463] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:30.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:41:0) after 1m0s
[05:46:30.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:41:0)
[05:46:30.496] Timer tick, asking Buildbucket for the build status
[05:46:30.675] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:30.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:42:0) after 1m0s
[05:47:30.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:42:0)
[05:47:30.774] Timer tick, asking Buildbucket for the build status
[05:47:30.993] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:30.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:43:0) after 1m0s
[05:48:31.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:43:0)
[05:48:31.053] Timer tick, asking Buildbucket for the build status
[05:48:31.302] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:31.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:44:0) after 1m0s
[05:49:31.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:44:0)
[05:49:31.384] Timer tick, asking Buildbucket for the build status
[05:49:31.672] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:31.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:45:0) after 1m0s
[05:50:31.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:45:0)
[05:50:31.703] Timer tick, asking Buildbucket for the build status
[05:50:31.872] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:31.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:46:0) after 1m0s
[05:51:31.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:46:0)
[05:51:31.900] Timer tick, asking Buildbucket for the build status
[05:51:32.017] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:32.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:47:0) after 1m0s
[05:52:32.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:47:0)
[05:52:32.046] Timer tick, asking Buildbucket for the build status
[05:52:32.397] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:32.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:48:0) after 1m0s
[05:53:32.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:48:0)
[05:53:32.449] Timer tick, asking Buildbucket for the build status
[05:53:32.618] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:32.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:49:0) after 1m0s
[05:54:32.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:49:0)
[05:54:32.677] Timer tick, asking Buildbucket for the build status
[05:54:33.067] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:33.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:50:0) after 1m0s
[05:55:33.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:50:0)
[05:55:33.145] Timer tick, asking Buildbucket for the build status
[05:55:33.502] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:33.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:51:0) after 1m0s
[05:56:33.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:51:0)
[05:56:33.542] Timer tick, asking Buildbucket for the build status
[05:56:33.702] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:33.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:52:0) after 1m0s
[05:57:33.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:52:0)
[05:57:33.700] Timer tick, asking Buildbucket for the build status
[05:57:33.882] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:33.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:53:0) after 1m0s
[05:58:33.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:53:0)
[05:58:33.949] Timer tick, asking Buildbucket for the build status
[05:58:34.031] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:34.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:54:0) after 1m0s
[05:59:34.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:54:0)
[05:59:34.027] Timer tick, asking Buildbucket for the build status
[05:59:34.142] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:34.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:55:0) after 1m0s
[06:00:34.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:55:0)
[06:00:34.210] Timer tick, asking Buildbucket for the build status
[06:00:34.651] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:34.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:56:0) after 1m0s
[06:01:42.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:56:0)
[06:01:42.881] Timer tick, asking Buildbucket for the build status
[06:01:42.952] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:42.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:57:0) after 1m0s
[06:02:43.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:57:0)
[06:02:43.006] Timer tick, asking Buildbucket for the build status
[06:02:43.454] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:43.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:58:0) after 1m0s
[06:03:43.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:58:0)
[06:03:43.475] Timer tick, asking Buildbucket for the build status
[06:03:43.549] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:43.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:59:0) after 1m0s
[06:04:43.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:59:0)
[06:04:43.573] Timer tick, asking Buildbucket for the build status
[06:04:43.662] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:43.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:60:0) after 1m0s
[06:05:43.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:60:0)
[06:05:43.691] Timer tick, asking Buildbucket for the build status
[06:05:43.770] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:43.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:61:0) after 1m0s
[06:06:43.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:61:0)
[06:06:43.869] Timer tick, asking Buildbucket for the build status
[06:06:44.774] Build 8864407525030237344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:44.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029837587065794592:62:0) after 1m0s
[06:07:35.527] Received PubSub notification, asking Buildbucket for the build status
[06:07:35.763] Build 8864407525030237344: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:07:35.763] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604642853520288",
"created_by": "project:chromium",
"created_ts": "1604639235428181",
"id": "8864407525030237344",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"86f55bba453cfc1c0d1b17847c60cc13dbefeb70\"}}",
"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\": \"86f55bba453cfc1c0d1b17847c60cc13dbefeb70\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-8-b9eu\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15490, \"clang_revision\": \"llvmorg-12-init-10666-gc9f69ee7-1\", \"got_angle_revision\": \"d30f0114470888d3d85a92547f3d3b9c387ebb44\", \"got_dawn_revision\": \"2b6b0f45ff0597a847d0da25485dce9e2278ee80\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"86f55bba453cfc1c0d1b17847c60cc13dbefeb70\", \"got_revision_cp\": \"refs/heads/master@{#824742}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"612ded0654a027d64922448f136d125a9dc17956\", \"got_v8_revision\": \"e97171d2718ff00638282b995f8c46a917b20b6c\", \"got_v8_revision_cp\": \"refs/heads/8.8.242@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"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\": \"86f55bba453cfc1c0d1b17847c60cc13dbefeb70\", \"swarm_hashes_refs/heads/master(at){#824742}_without_patch\": {\"absl_hardening_tests\": \"021eb9f2050bd0e26fbeab5c71f548cfcdc30966\", \"accessibility_unittests\": \"3e50a8c48ce0f9fa0378fe91cbfd42fd5854fe41\", \"angle_unittests\": \"c9e08811d38365cb495bebbe838270b353a644dc\", \"app_shell_unittests\": \"56c2d9b11acf51c513328144f43252ad521293a8\", \"aura_unittests\": \"116052ac62bc7eb1d132fb021b5c77b2145af66b\", \"base_unittests\": \"48f12a61da28f4327bb52e269883246224d407c7\", \"base_util_unittests\": \"afa061f5e6b028e08827b6f427b25a4c2c46c752\", \"blink_common_unittests\": \"1c534e1ea7da7820db6c3384bf6bbe399baf5329\", \"blink_fuzzer_unittests\": \"3eeeb9cd42cba78f7e103ebce03b36c8c53f5c8b\", \"blink_heap_unittests\": \"9802c085ade8a53c8c333428803924ee8ee958a1\", \"blink_platform_unittests\": \"edea3474674ac159eccc171805f7ce3b736f2b31\", \"blink_unittests\": \"ce7f5a036698ed5e7a88cdc54c51afc688db514f\", \"boringssl_crypto_tests\": \"5056205557a2380160de094ec920ef7714c91ff7\", \"boringssl_ssl_tests\": \"e90058c71db486e655aa9cea1b9cabb4d6b8b9f4\", \"browser_switcher_bho_unittests\": \"7fc9c32f3e9334fe952633c59c8f165db2b3e1c6\", \"browser_tests\": \"59d0750f65d309fadb6c7b8d1c234e97c00f18dd\", \"capture_unittests\": \"8873a276eec175d4dc0a151b49d0f35d57334b31\", \"cast_unittests\": \"3bd6b7c7addcf911d2734909495f613afe29e39f\", \"cc_unittests\": \"75ab7cf15b63f647afdd5fad1fbc5f7b4437c6b5\", \"chrome_app_unittests\": \"54a4427418f2da41df67d49a19badfbb1e0b2db5\", \"chrome_cleaner_unittests\": \"481199c42c831bdb63ae393a312f3d414b17f1a3\", \"chrome_elf_unittests\": \"062953add554b915057963db3e8665f9590b9546\", \"chromedriver_unittests\": \"a77c4c44cb7e1ceec7c059cb242a355cb75c8a95\", \"components_browsertests\": \"342610ac16279c96a080b231d79460279e6ba6a5\", \"components_unittests\": \"18984c4dff8864e98eaef51dca246f56be8e7d2e\", \"compositor_unittests\": \"85749a764690f847ac2394d2f06e1cda5ede621f\", \"content_browsertests\": \"069e056dd06874bd6fe02c3bb8b77904d9def48a\", \"content_unittests\": \"a371504816645e34f39d5d2cfde66c3f70a99f5b\", \"courgette_unittests\": \"5addf8b2259453e0d76235a7262ac4da7fe56d0d\", \"crashpad_tests\": \"464e5d180c0077589fd179be33407c3f219e7bfc\", \"cronet_tests\": \"f603cf8baa7f5c5a71a436079aa01cf23770b6ac\", \"cronet_unittests\": \"bed7060292328b2315a087c7a3701f501281aa34\", \"crypto_unittests\": \"cf08f9b215137e7358cfedca640f088751a89b1c\", \"delayloads_unittests\": \"0b917b43a7bf6b5a9b8cdc7e1ae579c72dde19e6\", \"device_unittests\": \"dce9f1f67d05cc63e01d68b74e33c6278e3e9b54\", \"display_unittests\": \"8890c5ab21d97b5dc22de93a8a8f2d4c65e1a394\", \"elevation_service_unittests\": \"93545256e074ad1a82b6cada56a2b1cc546c691c\", \"events_unittests\": \"2d9cd43625505414b1e531653e105909d6a778d1\", \"extensions_browsertests\": \"6ac6cec1cc1c90be20529d9257c6da234ee28adc\", \"extensions_unittests\": \"e7840366820ca2d66405574dfd3382457523eadd\", \"filesystem_service_unittests\": \"e1b9f99c7e7a2aea6c4c57d6343f5edd357665e0\", \"gcm_unit_tests\": \"4d1123221691df8d6b8063b00dc517076021752f\", \"gcp_unittests\": \"c74b644e9f469c3631c4ae70a2e3d75fe33701c8\", \"gfx_unittests\": \"78ab4c36cb0dc0b0421f1d88b59f417411fe5895\", \"gin_unittests\": \"8ad18ad0f9a903c0a052ceeba128f296b6f3f85e\", \"google_apis_unittests\": \"eeefb58288c494a41e65390d13cff4ebc7544d3f\", \"gpu_unittests\": \"47f25a1ee84e97ec45e0c9a1c1f29a8a2ff7a7f7\", \"gwp_asan_unittests\": \"ddddc663f0defbcf5a7516890f47b42dd7e370ed\", \"headless_browsertests\": \"4539958465014c2c4d11d2179d8cb733425c80ce\", \"headless_unittests\": \"f80b035ffd993360b35f900a2eee1f2826932c63\", \"install_static_unittests\": \"90078e9c6ce81087916dda0b00eefa444638f667\", \"installer_util_unittests\": \"0cd3eb54dba4c0f8dcea082ebb506cc9ee918c1b\", \"interactive_ui_tests\": \"8e5c081a83d9bda3abe3891395ec4bcb928658a3\", \"ipc_tests\": \"f6cce61119f6a66c36a10f478f3d3c3edbe01c36\", \"jingle_unittests\": \"8baec5cbc041fb689f2880d083e92a06adbf0ef1\", \"latency_unittests\": \"3def60e1d53e2534b48617363b0cc745da9e8b8d\", \"libjingle_xmpp_unittests\": \"c5ee23768e8428dadfcb16b82ac0d963779ce634\", \"liburlpattern_unittests\": \"262de4a4b73a2b0fe45d2143bb3decc3347d3d38\", \"media_blink_unittests\": \"058fb1ba17a1c9a784ae53c2910b02d49b9704a7\", \"media_unittests\": \"f7938fc399f6e74e477a7a5d16cbe4ad12176eee\", \"message_center_unittests\": \"6183fc689ce5eaa0c12de76c8f8f07f08a9cf395\", \"midi_unittests\": \"2475d9830dbe9ee8013aebf7462b253666703abb\", \"mojo_core_unittests\": \"55f7376627bad252e08b74d974c65f60f5962f75\", \"mojo_unittests\": \"23fa935af97a3f87d2d5923706051d9d30353892\", \"nacl_loader_unittests\": \"6d1f89a011d93acc99c8615ad1afe4f7b8d9ee8a\", \"native_theme_unittests\": \"d7758389676c5af7fad90ef4fff861bb0662df79\", \"net_unittests\": \"5638a81d4466fdc7f1aa741bd795f7968547179e\", \"notification_helper_unittests\": \"50e461951dbf8c36c2fae25ed1aea8979163d359\", \"pdf_unittests\": \"47488c22723c675b4906286be669638385b85e99\", \"perfetto_unittests\": \"d24d9954a969e4cc5d7d278474b03a0ba65e52f7\", \"ppapi_unittests\": \"f8871b56bfeca766d304b54852567718c7e3effa\", \"printing_unittests\": \"6f1eb69d65e825a1c6a54503beea445351f1ce1a\", \"remoting_unittests\": \"cb81f623e41a083fba4df620c54566a8c9ec14a7\", \"sbox_integration_tests\": \"5f733291a5b6d0df8968c6e3ca6021f820277a45\", \"sbox_unittests\": \"cefdd0dc989ac5347e37cbddb407cf6c31584ebc\", \"sbox_validation_tests\": \"b74a233d52ca7a75ea17080e9d5c8c1abeee52af\", \"service_manager_unittests\": \"3e58fec5e0d18e3eebc884c4cfe9163bec1262ae\", \"services_unittests\": \"cbb2a3c4ef799f6c84968ee0563d9c91413e4d7f\", \"setup_unittests\": \"20c17f6fc1bbd9a8bf5d4f0d7d829c2be7956e72\", \"shell_dialogs_unittests\": \"3516f30584521dcf26025a84319714729c1462a2\", \"skia_unittests\": \"0108145b8a552a258aa31534cd836c303c19ac89\", \"snapshot_unittests\": \"37f3bca5b1b6f43e7cbdb5d5fa99136a7205d075\", \"sql_unittests\": \"3a7d3ca2f2569e94c883bd0043ee21a798ad6834\", \"storage_unittests\": \"b62f98274b4b55726ab1385ec37ec7ff8b3ac0fd\", \"sync_integration_tests\": \"63937912b544d645058fcda214cb36beff17cfeb\", \"traffic_annotation_auditor_unittests\": \"e6ccfd3b344f8ee42363d670ea4111585e286b03\", \"ui_base_unittests\": \"9487eb0c8eaf9f3f8830e6c881f0accedc0443e7\", \"ui_touch_selection_unittests\": \"3f925e2ec91dd141ff531fe5d186ebe835f9354e\", \"unit_tests\": \"5d1091d54b5823e8c47a9cbf049902551279bcbd\", \"updater_tests\": \"60a5bab2464de8f7be6fdb6da64070533c85ba35\", \"url_unittests\": \"4e075d6ef6100a1a82026b8ffd24ba06170250d3\", \"views_unittests\": \"1c8b849e2ad91ce17333b6eea93c60d0c9390597\", \"viz_unittests\": \"429105718430afcdb85d74612747905ce42b42a0\", \"vr_common_unittests\": \"8203574b6d9531b29500fa054b717873f4477dc6\", \"vr_pixeltests\": \"80f666e912e3c2f853da4985ce887117db3a965d\", \"weblayer_browsertests\": \"285fe76ab25ca01b19ae006ed32b88c2c3c67dea\", \"weblayer_unittests\": \"93d640fa7fb10d84cf78793d7db2e076f0d430f6\", \"wm_unittests\": \"0be2e515bd6195e48de82c63150691d43c6a69ac\", \"wtf_unittests\": \"c727e56342acca35e53fa10027b73ffaf03f919d\", \"zlib_unittests\": \"7d55904f60c2b5f678a8df1021c4f036a2f4e245\", \"zucchini_unittests\": \"3b108dbdbc5ba51129b4a90f88d7659f9646fb87\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-8-b9eu\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5614-d862eaa\"], \"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": "1604639241286683",
"status": "COMPLETED",
"status_changed_ts": "1604642854209866",
"tags": [
"build_address:luci.chromium.ci/win-asan/15490",
"builder:win-asan",
"buildset:commit/git/86f55bba453cfc1c0d1b17847c60cc13dbefeb70",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86f55bba453cfc1c0d1b17847c60cc13dbefeb70",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029837587065794592",
"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/8864407525030237344/+/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:4fb4c53487ec6610",
"user_agent:luci-scheduler"
],
"updated_ts": "1604642854285560",
"url": "https://ci.chromium.org/b/8864407525030237344",
"utcnow_ts": "1604642855744860"
}
}
[06:07:35.763] Invocation finished in 1h0m21.660689175s with status SUCCEEDED