[13:16:21.380] New invocation is queued and will start shortly
[13:16:22.974] Starting the invocation (attempt 1)
[13:16:23.012] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:16:23.012] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:16:23.012] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023193235684028224",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9c2a6b0653c6d624e73da901fcfc27e629ee9571\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023193235684028224",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/9c2a6b0653c6d624e73da901fcfc27e629ee9571",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9c2a6b0653c6d624e73da901fcfc27e629ee9571",
"gitiles_ref:refs/heads/master"
]
}
[13:16:23.628] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610975783101365",
"id": "8857763173217734432",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9c2a6b0653c6d624e73da901fcfc27e629ee9571\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610975783519886",
"tags": [
"build_address:luci.chromium.ci/win-asan/16872",
"builder:win-asan",
"buildset:commit/git/9c2a6b0653c6d624e73da901fcfc27e629ee9571",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9c2a6b0653c6d624e73da901fcfc27e629ee9571",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023193235684028224",
"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/8857763173217734432/+/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": "1610975783519993",
"url": "https://ci.chromium.org/b/8857763173217734432",
"utcnow_ts": "1610975783621305"
}
}
[13:16:23.629] Task URL: https://ci.chromium.org/b/8857763173217734432
[13:16:23.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:2:0) after 1m0s
[13:16:25.958] Received PubSub notification, asking Buildbucket for the build status
[13:16:26.179] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:23.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:2:0)
[13:17:23.763] Timer tick, asking Buildbucket for the build status
[13:17:23.916] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:23.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:4:0) after 1m0s
[13:18:23.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:4:0)
[13:18:23.937] Timer tick, asking Buildbucket for the build status
[13:18:24.065] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:24.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:5:0) after 1m0s
[13:19:24.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:5:0)
[13:19:24.091] Timer tick, asking Buildbucket for the build status
[13:19:24.590] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:24.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:6:0) after 1m0s
[13:20:24.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:6:0)
[13:20:24.609] Timer tick, asking Buildbucket for the build status
[13:20:24.716] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:24.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:7:0) after 1m0s
[13:21:24.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:7:0)
[13:21:24.759] Timer tick, asking Buildbucket for the build status
[13:21:24.857] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:24.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:8:0) after 1m0s
[13:22:24.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:8:0)
[13:22:24.915] Timer tick, asking Buildbucket for the build status
[13:22:25.039] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:25.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:9:0) after 1m0s
[13:23:25.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:9:0)
[13:23:25.105] Timer tick, asking Buildbucket for the build status
[13:23:25.276] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:25.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:10:0) after 1m0s
[13:24:25.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:10:0)
[13:24:25.346] Timer tick, asking Buildbucket for the build status
[13:24:25.417] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:25.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:11:0) after 1m0s
[13:25:25.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:11:0)
[13:25:25.457] Timer tick, asking Buildbucket for the build status
[13:25:25.511] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:25.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:12:0) after 1m0s
[13:26:25.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:12:0)
[13:26:25.558] Timer tick, asking Buildbucket for the build status
[13:26:25.730] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:25.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:13:0) after 1m0s
[13:27:25.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:13:0)
[13:27:25.750] Timer tick, asking Buildbucket for the build status
[13:27:25.871] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:25.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:14:0) after 1m0s
[13:28:26.060] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:14:0)
[13:28:26.060] Timer tick, asking Buildbucket for the build status
[13:28:26.213] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:26.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:15:0) after 1m0s
[13:29:26.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:15:0)
[13:29:26.235] Timer tick, asking Buildbucket for the build status
[13:29:26.339] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:26.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:16:0) after 1m0s
[13:30:26.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:16:0)
[13:30:26.327] Timer tick, asking Buildbucket for the build status
[13:30:26.414] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:26.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:17:0) after 1m0s
[13:31:26.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:17:0)
[13:31:26.473] Timer tick, asking Buildbucket for the build status
[13:31:26.828] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:26.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:18:0) after 1m0s
[13:32:26.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:18:0)
[13:32:26.926] Timer tick, asking Buildbucket for the build status
[13:32:27.013] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:27.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:19:0) after 1m0s
[13:33:26.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:19:0)
[13:33:26.988] Timer tick, asking Buildbucket for the build status
[13:33:27.049] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:27.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:20:0) after 1m0s
[13:34:27.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:20:0)
[13:34:27.076] Timer tick, asking Buildbucket for the build status
[13:34:27.606] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:27.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:21:0) after 1m0s
[13:35:27.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:21:0)
[13:35:27.633] Timer tick, asking Buildbucket for the build status
[13:35:27.747] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:27.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:22:0) after 1m0s
[13:36:27.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:22:0)
[13:36:27.816] Timer tick, asking Buildbucket for the build status
[13:36:28.004] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:28.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:23:0) after 1m0s
[13:37:28.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:23:0)
[13:37:28.058] Timer tick, asking Buildbucket for the build status
[13:37:28.157] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:28.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:24:0) after 1m0s
[13:38:28.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:24:0)
[13:38:28.219] Timer tick, asking Buildbucket for the build status
[13:38:28.436] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:28.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:25:0) after 1m0s
[13:39:28.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:25:0)
[13:39:28.470] Timer tick, asking Buildbucket for the build status
[13:39:28.652] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:28.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:26:0) after 1m0s
[13:40:28.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:26:0)
[13:40:28.677] Timer tick, asking Buildbucket for the build status
[13:40:28.928] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:28.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:27:0) after 1m0s
[13:41:28.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:27:0)
[13:41:28.949] Timer tick, asking Buildbucket for the build status
[13:41:29.059] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:29.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:28:0) after 1m0s
[13:42:29.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:28:0)
[13:42:29.048] Timer tick, asking Buildbucket for the build status
[13:42:29.175] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:29.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:29:0) after 1m0s
[13:43:29.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:29:0)
[13:43:29.234] Timer tick, asking Buildbucket for the build status
[13:43:29.350] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:29.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:30:0) after 1m0s
[13:44:29.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:30:0)
[13:44:29.389] Timer tick, asking Buildbucket for the build status
[13:44:29.508] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:29.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:31:0) after 1m0s
[13:45:29.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:31:0)
[13:45:29.495] Timer tick, asking Buildbucket for the build status
[13:45:30.016] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:30.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:32:0) after 1m0s
[13:46:30.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:32:0)
[13:46:30.084] Timer tick, asking Buildbucket for the build status
[13:46:30.225] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:30.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:33:0) after 1m0s
[13:47:30.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:33:0)
[13:47:30.198] Timer tick, asking Buildbucket for the build status
[13:47:30.535] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:30.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:34:0) after 1m0s
[13:48:30.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:34:0)
[13:48:30.546] Timer tick, asking Buildbucket for the build status
[13:48:30.991] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:30.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:35:0) after 1m0s
[13:49:31.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:35:0)
[13:49:31.011] Timer tick, asking Buildbucket for the build status
[13:49:31.361] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:31.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:36:0) after 1m0s
[13:50:31.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:36:0)
[13:50:31.382] Timer tick, asking Buildbucket for the build status
[13:50:31.506] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:31.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:37:0) after 1m0s
[13:51:31.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:37:0)
[13:51:31.565] Timer tick, asking Buildbucket for the build status
[13:51:32.069] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:32.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:38:0) after 1m0s
[13:52:32.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:38:0)
[13:52:32.096] Timer tick, asking Buildbucket for the build status
[13:52:32.223] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:32.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:39:0) after 1m0s
[13:53:32.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:39:0)
[13:53:32.243] Timer tick, asking Buildbucket for the build status
[13:53:32.405] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:32.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:40:0) after 1m0s
[13:54:32.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:40:0)
[13:54:32.426] Timer tick, asking Buildbucket for the build status
[13:54:32.520] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:32.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:41:0) after 1m0s
[13:55:32.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:41:0)
[13:55:32.525] Timer tick, asking Buildbucket for the build status
[13:55:32.591] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:32.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:42:0) after 1m0s
[13:56:32.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:42:0)
[13:56:32.623] Timer tick, asking Buildbucket for the build status
[13:56:32.765] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:32.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:43:0) after 1m0s
[13:57:32.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:43:0)
[13:57:32.759] Timer tick, asking Buildbucket for the build status
[13:57:32.921] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:32.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:44:0) after 1m0s
[13:58:32.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:44:0)
[13:58:32.961] Timer tick, asking Buildbucket for the build status
[13:58:33.053] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:33.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:45:0) after 1m0s
[13:59:33.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:45:0)
[13:59:33.074] Timer tick, asking Buildbucket for the build status
[13:59:33.151] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:33.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:46:0) after 1m0s
[14:00:33.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:46:0)
[14:00:33.303] Timer tick, asking Buildbucket for the build status
[14:00:34.867] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:34.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:47:0) after 1m0s
[14:01:34.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:47:0)
[14:01:34.921] Timer tick, asking Buildbucket for the build status
[14:01:35.474] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:35.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:48:0) after 1m0s
[14:02:35.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:48:0)
[14:02:35.542] Timer tick, asking Buildbucket for the build status
[14:02:35.656] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:35.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:49:0) after 1m0s
[14:03:35.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:49:0)
[14:03:35.676] Timer tick, asking Buildbucket for the build status
[14:03:36.212] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:36.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:50:0) after 1m0s
[14:04:36.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:50:0)
[14:04:36.281] Timer tick, asking Buildbucket for the build status
[14:04:36.571] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:36.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:51:0) after 1m0s
[14:05:36.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:51:0)
[14:05:36.595] Timer tick, asking Buildbucket for the build status
[14:05:36.737] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:36.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:52:0) after 1m0s
[14:06:36.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:52:0)
[14:06:36.820] Timer tick, asking Buildbucket for the build status
[14:06:36.933] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:36.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:53:0) after 1m0s
[14:07:37.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:53:0)
[14:07:37.010] Timer tick, asking Buildbucket for the build status
[14:07:37.138] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:37.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:54:0) after 1m0s
[14:08:37.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:54:0)
[14:08:37.192] Timer tick, asking Buildbucket for the build status
[14:08:37.389] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:37.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:55:0) after 1m0s
[14:09:37.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:55:0)
[14:09:37.403] Timer tick, asking Buildbucket for the build status
[14:09:37.642] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:37.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:56:0) after 1m0s
[14:10:37.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:56:0)
[14:10:37.693] Timer tick, asking Buildbucket for the build status
[14:10:37.945] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:37.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:57:0) after 1m0s
[14:11:37.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:57:0)
[14:11:37.968] Timer tick, asking Buildbucket for the build status
[14:11:38.089] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:38.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:58:0) after 1m0s
[14:12:38.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:58:0)
[14:12:38.162] Timer tick, asking Buildbucket for the build status
[14:12:38.339] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:38.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:59:0) after 1m0s
[14:13:38.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:59:0)
[14:13:38.389] Timer tick, asking Buildbucket for the build status
[14:13:38.485] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:38.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:60:0) after 1m0s
[14:14:38.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:60:0)
[14:14:38.517] Timer tick, asking Buildbucket for the build status
[14:14:38.581] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:38.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:61:0) after 1m0s
[14:15:38.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:61:0)
[14:15:38.613] Timer tick, asking Buildbucket for the build status
[14:15:38.704] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:38.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:62:0) after 1m0s
[14:16:38.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:62:0)
[14:16:38.730] Timer tick, asking Buildbucket for the build status
[14:16:39.071] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:39.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:63:0) after 1m0s
[14:17:39.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:63:0)
[14:17:39.098] Timer tick, asking Buildbucket for the build status
[14:17:39.170] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:39.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:64:0) after 1m0s
[14:18:39.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:64:0)
[14:18:39.194] Timer tick, asking Buildbucket for the build status
[14:18:39.320] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:39.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:65:0) after 1m0s
[14:19:39.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:65:0)
[14:19:39.368] Timer tick, asking Buildbucket for the build status
[14:19:39.719] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:39.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:66:0) after 1m0s
[14:20:39.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:66:0)
[14:20:39.764] Timer tick, asking Buildbucket for the build status
[14:20:40.192] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:40.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:67:0) after 1m0s
[14:21:40.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:67:0)
[14:21:40.210] Timer tick, asking Buildbucket for the build status
[14:21:40.351] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:40.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:68:0) after 1m0s
[14:22:40.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:68:0)
[14:22:40.392] Timer tick, asking Buildbucket for the build status
[14:22:40.856] Build 8857763173217734432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:40.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023193235684028224:69:0) after 1m0s
[14:23:31.697] Received PubSub notification, asking Buildbucket for the build status
[14:23:31.767] Build 8857763173217734432: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:23:31.768] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610979810391740",
"created_by": "project:chromium",
"created_ts": "1610975783101365",
"id": "8857763173217734432",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9c2a6b0653c6d624e73da901fcfc27e629ee9571\"}}",
"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\": \"9c2a6b0653c6d624e73da901fcfc27e629ee9571\", \"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-kl3f\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16872, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"68f15c734d5cef3bfa3f8d86654fd7a5808679ec\", \"got_dawn_revision\": \"5c20f1ec9ca95008eb452e04133c332af3a2b3fa\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"9c2a6b0653c6d624e73da901fcfc27e629ee9571\", \"got_revision_cp\": \"refs/heads/master@{#844509}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"149733cead636de93d96c5c64f30168d5f6bb03f\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"12971a22d265c7ba1c3c388f5d487580939a1162\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33014}\", \"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\": \"9c2a6b0653c6d624e73da901fcfc27e629ee9571\", \"swarm_hashes_refs/heads/master(at){#844509}_without_patch\": {\"absl_hardening_tests\": \"b2bd6c342bca56214f0326f5b3d59c0751b1230f\", \"accessibility_unittests\": \"0305ac7256df98a8e6b04db377cf28bfd1d8c97e\", \"angle_unittests\": \"4df80b0ca2989d95fc3e5d16e4c8a8c0fdec6103\", \"app_shell_unittests\": \"9de115ae5987739d4ff6dfb596aa0008b3f6d593\", \"aura_unittests\": \"8c8f64822e8c68b08667e791afd3023b098fa7cb\", \"base_unittests\": \"f52dd605afd8909d08e613cf5bd15bd3989e3e6f\", \"base_util_unittests\": \"fa7da58c0b3c623357afaaf808657bf369fa6bf4\", \"blink_common_unittests\": \"3b83853471827cc97ca73ed350ae48f6df46fe27\", \"blink_fuzzer_unittests\": \"2c6527b9e7894af7a5665baa8476f364379237da\", \"blink_heap_unittests\": \"7f0ab1abf9615383e7490f151f2beb1afa9f9a53\", \"blink_platform_unittests\": \"1d349ba123b9282928be7bdc87edd34445289379\", \"blink_unittests\": \"d6fd3f20ad38e773fc34d781867efa17fce371f2\", \"boringssl_crypto_tests\": \"25552210dd63fca1c39520553252f3131cd083b1\", \"boringssl_ssl_tests\": \"b7c157ff01e7583885cb849c36f66181c5b50f78\", \"browser_switcher_bho_unittests\": \"824d32e969cea297d684eb91c38f1981d0ef79a6\", \"browser_tests\": \"35531561afdc4af51fee85eee9b297218314cd4c\", \"capture_unittests\": \"c8a80953bcad11e0afe8db9cc25a79fb89acc180\", \"cast_unittests\": \"011083a70e91d20055da19a9fb573ecee5c98748\", \"cc_unittests\": \"3d652164428fc3596d215d10e59fa8e8f0840354\", \"chrome_app_unittests\": \"a4aa2b4b57a69209b36f53d4b4c4da9360718d1f\", \"chrome_cleaner_unittests\": \"86fa756f318d880f94a1318cdf2c9200cd6fc0fe\", \"chrome_elf_unittests\": \"4c39a9b8a3401547c553ab44c3e0bd787b1d05c7\", \"chromedriver_unittests\": \"458d3e60eff3eded5af0d0befc54fbd9c80aa699\", \"components_browsertests\": \"f16e8d29eba2a0e8285ab0e7294c52f65fd62c8b\", \"components_unittests\": \"e727c84e862aa0987eb438b2fa76e7e79e6af935\", \"compositor_unittests\": \"bd6a059e34e040f46999752714c0dcd54cca7b90\", \"content_browsertests\": \"aefd8c7e12454cfa76bd9e506e360c95c7a1419c\", \"content_unittests\": \"634f536e83bafd6063a82d557265e67f3fd6c3a1\", \"courgette_unittests\": \"252468d642eccf1659a534c16845a2a44b9e7acc\", \"crashpad_tests\": \"5bf305981a908aa1fd664ffde728754b53f9ff26\", \"cronet_tests\": \"156bb7d14ceb2706635ae912b989d960e307a11f\", \"cronet_unittests\": \"2f20c0319db483e47d6c4b164ab479459e7ae80b\", \"crypto_unittests\": \"d427ee9490c70742ed60a17dfe54a4cd6d4de2ca\", \"delayloads_unittests\": \"9cacc668940a6d8b6812708ef4343fe9c7723282\", \"device_unittests\": \"46e779ed25cfbe3946be82792ff3e687db6a7b0a\", \"display_unittests\": \"98227a7ce9d9961aba38ffde4ff5f792a59ff76f\", \"elevation_service_unittests\": \"555c6b3acf4f61352506d46b3b4b57db44b42a9f\", \"events_unittests\": \"05ab273fb82bd0be3f19f3568e43ae8dd6a36f86\", \"extensions_browsertests\": \"d465bfbebd331405b92420a9685fe376911b6405\", \"extensions_unittests\": \"ddc25e431372289e143a9921355f7ed5c7600def\", \"filesystem_service_unittests\": \"b82db50dec44f57ce4a343c321b0fd47e1141b69\", \"gcm_unit_tests\": \"be8feaf29156d15f746c722bd1dfa2657d91cde1\", \"gcp_unittests\": \"baa19ff5ab574852424efca4a91107e3358f300a\", \"gfx_unittests\": \"144dbb4e9e665a9e883609a6bb6f62ab4fe678b3\", \"gin_unittests\": \"dfe3efda79287d95d0d2aec0bd10093fdfaf00cd\", \"google_apis_unittests\": \"331f4f223224e2ac8ef862851022127a043d586d\", \"gpu_unittests\": \"8d82f97194445d98dc14361a47fda6a451c5c696\", \"gwp_asan_unittests\": \"3ac71994306fdcb4b00649fff300287475a0d197\", \"headless_browsertests\": \"a7f2f150df99d06a9dfd82efa6487d32dca178ea\", \"headless_unittests\": \"b7950fcf03a266fa75c744a4725690c1e794caaf\", \"install_static_unittests\": \"d4a3944e7b44fbdddaefb10c69d7c90589e275c4\", \"installer_util_unittests\": \"3d48dc78525745d6b89708d4757dd0934f05da8a\", \"interactive_ui_tests\": \"25c28ae0c20176c84e348a2b23a6889436c609d6\", \"ipc_tests\": \"42094e68ea655daa13b21776e2f3fcdeb4f4f704\", \"jingle_unittests\": \"604a914567bb5932f1f2a223c9081d9952644bb6\", \"latency_unittests\": \"909ca51757d7c2be56f851725254297b1008c656\", \"libjingle_xmpp_unittests\": \"e68818a0491aaaa7b46f8d7cfa6c5d9fb14dffc3\", \"liburlpattern_unittests\": \"98734a0021b09f8e13d427f64c23a4c31ceebfa0\", \"media_blink_unittests\": \"ca46430a07a59892cbed0323e59ec75b10536739\", \"media_unittests\": \"45c76bde8b0a40ccf15d2c08e98d58d823d7872b\", \"message_center_unittests\": \"1ba390050d80cfc6a0b59758616c8350d2a1ba7b\", \"midi_unittests\": \"d84a591284d36d9248dd889581a996b522169a6b\", \"mojo_core_unittests\": \"516bc3fe88a70c529250d484ed5a57414d8bfce7\", \"mojo_unittests\": \"04be2fc06dfacd48a6ab5133a24628687cf224b2\", \"nacl_loader_unittests\": \"b121b39f495eb9c73929277980eb445afd3e9391\", \"native_theme_unittests\": \"06f4410c74686ddcef52dc109d50b4ec16769a90\", \"net_unittests\": \"6d1033db3423b70472766946fc23bb81cad8cd4b\", \"notification_helper_unittests\": \"cc46b559d223fb2b083421a9c85c2ebb49b399ae\", \"pdf_unittests\": \"3d96c6d5c5eb6d669a708c9daa968c4387b01dde\", \"perfetto_unittests\": \"2bb57c4988d49ded34d092f7a5654759f48a795f\", \"ppapi_unittests\": \"3e7a28d401fd8a699c3fbf4b76bcf25d522a9678\", \"printing_unittests\": \"4c40195da5d5f650bec6b99083e46b56e357a308\", \"remoting_unittests\": \"eafd3575dbb11c5e3df43192afc85e086395a0e7\", \"sbox_integration_tests\": \"1d469d045d60e1ffa5eebf1a84d3484bf2371080\", \"sbox_unittests\": \"129e8fb43ef315c61f61c0b92cbba1d495900180\", \"sbox_validation_tests\": \"2268053318ee6a817b17db7a33340629f7241862\", \"service_manager_unittests\": \"708d4a752cb70fb18e800e9490d0af8cc311f16e\", \"services_unittests\": \"393a56732f15030b39b342edc725a4911477de4f\", \"setup_unittests\": \"ad281627c24c6cd37923867c32e3e7c99cd4895d\", \"shell_dialogs_unittests\": \"c204766b61953d4ff9a0d713a9d7c1c77e354c97\", \"skia_unittests\": \"b19a91e11fb5be72bc088157bda0f5e4a6fc07cd\", \"snapshot_unittests\": \"ac2154c82f07a34c9c81aa93a0362477fe4fb0a5\", \"sql_unittests\": \"35a92e9304ea5dfc7da8f736e8b322830c90a6bd\", \"storage_unittests\": \"f4b17049f0bef51b44816ce5e8fb779d8590e754\", \"sync_integration_tests\": \"fb8556531444bfd18d897fb138138edabff8c1cb\", \"traffic_annotation_auditor_unittests\": \"8f4d5b9222f3e3a2c9cc6763a8bb30581e541d39\", \"ui_base_unittests\": \"d5c4b638f7ee00b38d66f5128bb90cdae6d97d03\", \"ui_touch_selection_unittests\": \"7ae4f07f39d58b8bd58c90cf7d7f22127b6a4883\", \"unit_tests\": \"a48020a23399acbb9d91740a2a956df68c7147dc\", \"updater_tests\": \"8afd0aeb72ff67358ca2f680111887c6cfd4ff52\", \"url_unittests\": \"6bc1437e481a5b549ef76301f9204f3d104bb177\", \"views_unittests\": \"aaf35c4f8d51af491cb6b828995aa82b4d9f9e51\", \"viz_unittests\": \"f35fa08cfe08c885cb0110523de77a76b8c34e8a\", \"vr_common_unittests\": \"3911f7c9535a285fea7c6267f5e44953e84b8bb9\", \"vr_pixeltests\": \"c78b8c5ca69c1ac12983d6fce2ae8427fedbbdf8\", \"weblayer_browsertests\": \"ee8107a47f2119ec04633891edda67d15a8998a8\", \"weblayer_unittests\": \"643730e7033be70bf1b9579c3c7d7cd69b62796a\", \"wm_unittests\": \"627d4e2bbd966c5b5184bfb9a3ed5e2539499c35\", \"wtf_unittests\": \"cd1445af803f2dc6ffddd48ad47b0673922fe95b\", \"zlib_unittests\": \"35170c277cc902a46a93a19d7a37646ec8afece0\", \"zucchini_unittests\": \"ff67a57ae7f3a5c1841dd163ac259b8cda2f5623\"}}, \"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-9-kl3f\"], \"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\": [\"5666-679c9bd\"], \"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": "1610975785224380",
"status": "COMPLETED",
"status_changed_ts": "1610979810747014",
"tags": [
"build_address:luci.chromium.ci/win-asan/16872",
"builder:win-asan",
"buildset:commit/git/9c2a6b0653c6d624e73da901fcfc27e629ee9571",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9c2a6b0653c6d624e73da901fcfc27e629ee9571",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023193235684028224",
"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/8857763173217734432/+/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:512e753421db7310",
"user_agent:luci-scheduler"
],
"updated_ts": "1610979810754322",
"url": "https://ci.chromium.org/b/8857763173217734432",
"utcnow_ts": "1610979811758745"
}
}
[14:23:31.768] Invocation finished in 1h7m10.402315866s with status SUCCEEDED