[16:39:08.357] New invocation is queued and will start shortly
[16:39:09.519] Starting the invocation (attempt 1)
[16:39:09.558] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:39:09.558] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:39:09.558] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026079580610464752",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"270dc6d55d5e1dbfd92834e578c262a3ac0f9aeb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026079580610464752",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/270dc6d55d5e1dbfd92834e578c262a3ac0f9aeb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/270dc6d55d5e1dbfd92834e578c262a3ac0f9aeb",
"gitiles_ref:refs/heads/master"
]
}
[16:39:10.742] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608223149673905",
"id": "8860649518567025744",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"270dc6d55d5e1dbfd92834e578c262a3ac0f9aeb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608223150223325",
"tags": [
"build_address:luci.chromium.ci/win-asan/16284",
"builder:win-asan",
"buildset:commit/git/270dc6d55d5e1dbfd92834e578c262a3ac0f9aeb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/270dc6d55d5e1dbfd92834e578c262a3ac0f9aeb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026079580610464752",
"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/8860649518567025744/+/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": "1608223150223527",
"url": "https://ci.chromium.org/b/8860649518567025744",
"utcnow_ts": "1608223150720359"
}
}
[16:39:10.742] Task URL: https://ci.chromium.org/b/8860649518567025744
[16:39:10.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:2:0) after 1m0s
[16:39:11.714] Received PubSub notification, asking Buildbucket for the build status
[16:39:12.185] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:10.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:2:0)
[16:40:10.786] Timer tick, asking Buildbucket for the build status
[16:40:10.934] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:10.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:4:0) after 1m0s
[16:41:10.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:4:0)
[16:41:10.973] Timer tick, asking Buildbucket for the build status
[16:41:11.125] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:11.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:5:0) after 1m0s
[16:42:11.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:5:0)
[16:42:11.238] Timer tick, asking Buildbucket for the build status
[16:42:13.127] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:13.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:6:0) after 1m0s
[16:43:13.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:6:0)
[16:43:13.153] Timer tick, asking Buildbucket for the build status
[16:43:13.533] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:13.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:7:0) after 1m0s
[16:44:13.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:7:0)
[16:44:13.591] Timer tick, asking Buildbucket for the build status
[16:44:13.713] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:13.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:8:0) after 1m0s
[16:45:13.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:8:0)
[16:45:13.833] Timer tick, asking Buildbucket for the build status
[16:45:13.900] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:13.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:9:0) after 1m0s
[16:46:13.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:9:0)
[16:46:13.988] Timer tick, asking Buildbucket for the build status
[16:46:14.079] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:14.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:10:0) after 1m0s
[16:47:14.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:10:0)
[16:47:14.207] Timer tick, asking Buildbucket for the build status
[16:47:14.270] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:14.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:11:0) after 1m0s
[16:48:14.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:11:0)
[16:48:14.291] Timer tick, asking Buildbucket for the build status
[16:48:14.360] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:14.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:12:0) after 1m0s
[16:49:14.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:12:0)
[16:49:14.459] Timer tick, asking Buildbucket for the build status
[16:49:14.580] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:14.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:13:0) after 1m0s
[16:50:14.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:13:0)
[16:50:14.624] Timer tick, asking Buildbucket for the build status
[16:50:14.715] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:14.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:14:0) after 1m0s
[16:51:14.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:14:0)
[16:51:14.889] Timer tick, asking Buildbucket for the build status
[16:51:15.060] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:15.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:15:0) after 1m0s
[16:52:15.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:15:0)
[16:52:15.113] Timer tick, asking Buildbucket for the build status
[16:52:15.184] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:15.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:16:0) after 1m0s
[16:53:15.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:16:0)
[16:53:15.193] Timer tick, asking Buildbucket for the build status
[16:53:15.273] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:15.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:17:0) after 1m0s
[16:54:15.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:17:0)
[16:54:15.323] Timer tick, asking Buildbucket for the build status
[16:54:16.537] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:16.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:18:0) after 1m0s
[16:55:16.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:18:0)
[16:55:16.666] Timer tick, asking Buildbucket for the build status
[16:55:16.760] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:16.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:19:0) after 1m0s
[16:56:16.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:19:0)
[16:56:16.806] Timer tick, asking Buildbucket for the build status
[16:56:16.960] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:16.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:20:0) after 1m0s
[16:57:16.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:20:0)
[16:57:16.965] Timer tick, asking Buildbucket for the build status
[16:57:17.084] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:17.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:21:0) after 1m0s
[16:58:17.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:21:0)
[16:58:17.219] Timer tick, asking Buildbucket for the build status
[16:58:17.318] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:17.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:22:0) after 1m0s
[16:59:17.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:22:0)
[16:59:17.402] Timer tick, asking Buildbucket for the build status
[16:59:17.512] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:17.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:23:0) after 1m0s
[17:00:17.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:23:0)
[17:00:17.680] Timer tick, asking Buildbucket for the build status
[17:00:17.794] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:17.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:24:0) after 1m0s
[17:01:18.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:24:0)
[17:01:18.074] Timer tick, asking Buildbucket for the build status
[17:01:18.332] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:18.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:25:0) after 1m0s
[17:02:18.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:25:0)
[17:02:18.459] Timer tick, asking Buildbucket for the build status
[17:02:18.591] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:18.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:26:0) after 1m0s
[17:03:18.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:26:0)
[17:03:18.682] Timer tick, asking Buildbucket for the build status
[17:03:18.809] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:18.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:27:0) after 1m0s
[17:04:18.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:27:0)
[17:04:18.824] Timer tick, asking Buildbucket for the build status
[17:04:19.319] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:19.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:28:0) after 1m0s
[17:05:19.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:28:0)
[17:05:19.355] Timer tick, asking Buildbucket for the build status
[17:05:19.809] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:19.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:29:0) after 1m0s
[17:06:19.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:29:0)
[17:06:19.784] Timer tick, asking Buildbucket for the build status
[17:06:19.935] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:19.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:30:0) after 1m0s
[17:07:19.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:30:0)
[17:07:19.971] Timer tick, asking Buildbucket for the build status
[17:07:20.651] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:20.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:31:0) after 1m0s
[17:08:20.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:31:0)
[17:08:20.692] Timer tick, asking Buildbucket for the build status
[17:08:21.043] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:21.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:32:0) after 1m0s
[17:09:21.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:32:0)
[17:09:21.138] Timer tick, asking Buildbucket for the build status
[17:09:21.534] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:21.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:33:0) after 1m0s
[17:10:21.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:33:0)
[17:10:21.533] Timer tick, asking Buildbucket for the build status
[17:10:21.694] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:21.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:34:0) after 1m0s
[17:11:21.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:34:0)
[17:11:21.688] Timer tick, asking Buildbucket for the build status
[17:11:22.306] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:22.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:35:0) after 1m0s
[17:12:22.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:35:0)
[17:12:22.312] Timer tick, asking Buildbucket for the build status
[17:12:22.429] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:22.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:36:0) after 1m0s
[17:13:22.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:36:0)
[17:13:22.450] Timer tick, asking Buildbucket for the build status
[17:13:22.528] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:22.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:37:0) after 1m0s
[17:14:22.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:37:0)
[17:14:22.615] Timer tick, asking Buildbucket for the build status
[17:14:22.782] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:22.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:38:0) after 1m0s
[17:15:22.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:38:0)
[17:15:22.838] Timer tick, asking Buildbucket for the build status
[17:15:22.939] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:22.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:39:0) after 1m0s
[17:16:22.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:39:0)
[17:16:22.980] Timer tick, asking Buildbucket for the build status
[17:16:23.065] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:23.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:40:0) after 1m0s
[17:17:23.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:40:0)
[17:17:23.089] Timer tick, asking Buildbucket for the build status
[17:17:23.213] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:23.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:41:0) after 1m0s
[17:18:23.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:41:0)
[17:18:23.264] Timer tick, asking Buildbucket for the build status
[17:18:23.398] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:23.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:42:0) after 1m0s
[17:19:23.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:42:0)
[17:19:23.453] Timer tick, asking Buildbucket for the build status
[17:19:23.579] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:23.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:43:0) after 1m0s
[17:20:23.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:43:0)
[17:20:23.659] Timer tick, asking Buildbucket for the build status
[17:20:23.749] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:23.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:44:0) after 1m0s
[17:21:23.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:44:0)
[17:21:23.819] Timer tick, asking Buildbucket for the build status
[17:21:23.914] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:23.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:45:0) after 1m0s
[17:22:24.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:45:0)
[17:22:24.474] Timer tick, asking Buildbucket for the build status
[17:22:24.592] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:24.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:46:0) after 1m0s
[17:23:25.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:46:0)
[17:23:25.011] Timer tick, asking Buildbucket for the build status
[17:23:25.136] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:25.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:47:0) after 1m0s
[17:24:25.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:47:0)
[17:24:25.382] Timer tick, asking Buildbucket for the build status
[17:24:25.596] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:25.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:48:0) after 1m0s
[17:25:25.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:48:0)
[17:25:25.700] Timer tick, asking Buildbucket for the build status
[17:25:25.809] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:25.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:49:0) after 1m0s
[17:26:25.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:49:0)
[17:26:25.887] Timer tick, asking Buildbucket for the build status
[17:26:26.010] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:26.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:50:0) after 1m0s
[17:27:26.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:50:0)
[17:27:26.237] Timer tick, asking Buildbucket for the build status
[17:27:26.363] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:26.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:51:0) after 1m0s
[17:28:26.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:51:0)
[17:28:26.458] Timer tick, asking Buildbucket for the build status
[17:28:26.777] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:26.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:52:0) after 1m0s
[17:29:26.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:52:0)
[17:29:26.836] Timer tick, asking Buildbucket for the build status
[17:29:27.024] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:27.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:53:0) after 1m0s
[17:30:27.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:53:0)
[17:30:27.089] Timer tick, asking Buildbucket for the build status
[17:30:27.261] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:27.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:54:0) after 1m0s
[17:31:27.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:54:0)
[17:31:27.414] Timer tick, asking Buildbucket for the build status
[17:31:27.507] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:27.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:55:0) after 1m0s
[17:32:27.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:55:0)
[17:32:27.532] Timer tick, asking Buildbucket for the build status
[17:32:28.892] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:28.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:56:0) after 1m0s
[17:33:29.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:56:0)
[17:33:29.019] Timer tick, asking Buildbucket for the build status
[17:33:29.131] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:29.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:57:0) after 1m0s
[17:34:29.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:57:0)
[17:34:29.182] Timer tick, asking Buildbucket for the build status
[17:34:29.470] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:29.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:58:0) after 1m0s
[17:35:29.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:58:0)
[17:35:29.596] Timer tick, asking Buildbucket for the build status
[17:35:29.699] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:29.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:59:0) after 1m0s
[17:36:29.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:59:0)
[17:36:29.728] Timer tick, asking Buildbucket for the build status
[17:36:30.667] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:30.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:60:0) after 1m0s
[17:37:30.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:60:0)
[17:37:30.716] Timer tick, asking Buildbucket for the build status
[17:37:30.799] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:30.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:61:0) after 1m0s
[17:38:30.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:61:0)
[17:38:30.947] Timer tick, asking Buildbucket for the build status
[17:38:31.057] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:31.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:62:0) after 1m0s
[17:39:31.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:62:0)
[17:39:31.162] Timer tick, asking Buildbucket for the build status
[17:39:31.286] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:31.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:63:0) after 1m0s
[17:40:31.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:63:0)
[17:40:31.337] Timer tick, asking Buildbucket for the build status
[17:40:31.563] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:31.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:64:0) after 1m0s
[17:41:31.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:64:0)
[17:41:31.578] Timer tick, asking Buildbucket for the build status
[17:41:31.763] Build 8860649518567025744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:31.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026079580610464752:65:0) after 1m0s
[17:42:14.660] Received PubSub notification, asking Buildbucket for the build status
[17:42:14.746] Build 8860649518567025744: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:42:14.746] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608226933544952",
"created_by": "project:chromium",
"created_ts": "1608223149673905",
"id": "8860649518567025744",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"270dc6d55d5e1dbfd92834e578c262a3ac0f9aeb\"}}",
"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\": \"270dc6d55d5e1dbfd92834e578c262a3ac0f9aeb\", \"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-5-miap\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16284, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"c47b951ecbcbbba653db801bc7eb5f4fa5d72042\", \"got_dawn_revision\": \"155241b665f8305d54145ca460e0b8cebe888336\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"270dc6d55d5e1dbfd92834e578c262a3ac0f9aeb\", \"got_revision_cp\": \"refs/heads/master@{#838102}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1ca6504eb7bb9971d317cf8c8033b38b914036b7\", \"got_v8_revision\": \"804d2150372f0e8a19996e7152d984898e8cb85d\", \"got_v8_revision_cp\": \"refs/heads/8.9.164@{#1}\", \"got_webrtc_revision\": \"8592111e458d253e53806db65d23d21bfe9d9db4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32849}\", \"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\": \"270dc6d55d5e1dbfd92834e578c262a3ac0f9aeb\", \"swarm_hashes_refs/heads/master(at){#838102}_without_patch\": {\"absl_hardening_tests\": \"062f6f85867b504cea21de11b81af01ba08637af\", \"accessibility_unittests\": \"2fd6cbe3e168be51ae549eaace52dd02a8aa5c40\", \"angle_unittests\": \"25c5fe344b040c6483985a31306eda44ff965d14\", \"app_shell_unittests\": \"f0a284bce00213088c99d6824b4e1f5ff37748a3\", \"aura_unittests\": \"f84d3c4f18e27b0bc439cd4569901f7ca2730020\", \"base_unittests\": \"cc1fe48cb5a72d77d41194f19d64d891ac37a101\", \"base_util_unittests\": \"208c15488e9b52e3517a063c3ccfd38c9bd10aaf\", \"blink_common_unittests\": \"c06a36a3f5ab527e9c4db57dac1e20dc8bf658e8\", \"blink_fuzzer_unittests\": \"56e3d47f68a8246d93f0a732e1c1b291984fda3a\", \"blink_heap_unittests\": \"a344e54a6f9d7c963bd039ba59a8c6ff66055c55\", \"blink_platform_unittests\": \"c9403a085351978ea0f16cd935ae69debbf7fe92\", \"blink_unittests\": \"cdd0e184d446bbea614284a976ac35882a03c27f\", \"boringssl_crypto_tests\": \"3b343d2296b9d3bfefaec691b2335c2277ddc173\", \"boringssl_ssl_tests\": \"3bee9a1746b38b91d6a00bc9cedaa3bbc201af30\", \"browser_switcher_bho_unittests\": \"8588c66d2fdb75a771079b2649f6b0a297b382d3\", \"browser_tests\": \"ab320e7a1fcff8f9b0acc678cba59749659326cd\", \"capture_unittests\": \"64c01b1aa74d89fb48c0035e34e11932b7d06c36\", \"cast_unittests\": \"2a71d9c0512266c8ad5c6ff39943d4443c1be1b9\", \"cc_unittests\": \"b2412544a2c61e189332ca4cce098997a79ef923\", \"chrome_app_unittests\": \"5700fd09668e135f32b101acf583d30d27272463\", \"chrome_cleaner_unittests\": \"8e9d0aff38508827f783abee0ec53ba4a62c74ee\", \"chrome_elf_unittests\": \"e66083f51635c9d567cb25fdf980e8591c0fe473\", \"chromedriver_unittests\": \"44f37a7a07c57b1511d8f7f815e029448dec47b2\", \"components_browsertests\": \"08edab05888ea230a3238b924f4c4c604d56ddac\", \"components_unittests\": \"91328b868fb794b5367204e4a41587a161edcc0d\", \"compositor_unittests\": \"b1e48c400d9beecc181e27a7ed4af6e093b0dfd8\", \"content_browsertests\": \"bd09809dc1aa1e5bfba11a394d8a39461d0ed93e\", \"content_unittests\": \"768dde98256df9890de86dac2533a3c8fc4c8681\", \"courgette_unittests\": \"43a9c9dffb8ebe708f4a3f3462c1f2a2a7235b7e\", \"crashpad_tests\": \"01c041233de9193fd25317ddd4e7363d6d949013\", \"cronet_tests\": \"aaa94f5a886c06ca7f47ae9715d0b172759ac631\", \"cronet_unittests\": \"d971a8027cd6a25003b02d778b36b6083de00e5d\", \"crypto_unittests\": \"77d5a7ba0534f83e76d5057b57bd9127093fe231\", \"delayloads_unittests\": \"a29c68954aff0623e5959b3d56ae9aea963dcfd1\", \"device_unittests\": \"f99326c75384bd56bf09ba8282c2fa0679b38c89\", \"display_unittests\": \"418ff2a44de4fe1642232d52f85fa3017013d07b\", \"elevation_service_unittests\": \"f2a63e4c5334e2d8c60f186fcbde871fc2d3b72e\", \"events_unittests\": \"77137449abc4bca238d59464f487a83bd4fdec5c\", \"extensions_browsertests\": \"c161a69089fd1225b047086b7183267bea90c7ba\", \"extensions_unittests\": \"e94f2aa958726309cb3ea5b2d34eb759895feb41\", \"filesystem_service_unittests\": \"1c9abc39f32fe9d4d64010ebe54385e6114d530d\", \"gcm_unit_tests\": \"76fc0069500a95e9254c3256863ac00dd029c5e4\", \"gcp_unittests\": \"5de2f9e86d66b8dbf64da1f8d09ac0ce8f095937\", \"gfx_unittests\": \"4fa0eac54373f1c89b0a7136d25e5bddef76a08a\", \"gin_unittests\": \"84e5be5036f721a3141cf7230955c81902adc74c\", \"google_apis_unittests\": \"2bd5d40d883028f272d073d39c63d326882b35ce\", \"gpu_unittests\": \"c48866185f01933a59f45b1396cde29bfdc3b293\", \"gwp_asan_unittests\": \"1a02d3368132744b28927f3c4bb2adc22061b480\", \"headless_browsertests\": \"337f6053f372df17f5e6881b9f7d9c7b48ed2f97\", \"headless_unittests\": \"cc070ff6081458663f1af58405f8b624d011a7d3\", \"install_static_unittests\": \"e6e28e8df482033fe82c9cb892cac1777c8ec5c1\", \"installer_util_unittests\": \"e04fea9e7291872d5a709c93af3028b114864765\", \"interactive_ui_tests\": \"b58df5f91c1aa12a5355d26e8e7d56a13d7e4ab6\", \"ipc_tests\": \"1db301f5d90b138d51094a63c0c3aef9ee83a40f\", \"jingle_unittests\": \"d9c985cb81f6e65e1f99a39d8960e499d5631c32\", \"latency_unittests\": \"3309f3b37d345c76a765d17527f459a3fc8fe26a\", \"libjingle_xmpp_unittests\": \"b691ae92d5845e2d70f97860ac47de90017fa448\", \"liburlpattern_unittests\": \"fc7456f794097222d5166a40b5131525edd88835\", \"media_blink_unittests\": \"7a490056870cbe831f5e423f29efb46a8d69c591\", \"media_unittests\": \"fe18953a88a0eba814143a29d67ccf6d8f5aa2f9\", \"message_center_unittests\": \"8e405732d5ff7335a084e7d2063bc48d1bddb1e6\", \"midi_unittests\": \"9e9ae174e4df959213b80c13871fa74b5c127fd6\", \"mojo_core_unittests\": \"fffc4aa46b33e004580c9d209d4261b64fc40c34\", \"mojo_unittests\": \"66038ce276bc75da7e7639a416adfdec432a1420\", \"nacl_loader_unittests\": \"8361c5d9d336b48415c841bcc8601dbc1e58056f\", \"native_theme_unittests\": \"2270d3258b3be25c33d76ad776655e83141d4cee\", \"net_unittests\": \"7fe44810673fe2420f8b0ce2b631a6b1e4e5d8f7\", \"notification_helper_unittests\": \"87e81f0fae393b2c0692ffcffee31bd2ae9a50ff\", \"pdf_unittests\": \"ef27d884b69e40115b91b46c2bc6e1977fc6aa62\", \"perfetto_unittests\": \"bf39730a0456bffb8aaf65a328f43d7e38839ed8\", \"ppapi_unittests\": \"8329ebff29231480a3a545b89e13ed33d478587b\", \"printing_unittests\": \"bc548cebdd3fba0dcf6ecdb83558cd3377bfdde1\", \"remoting_unittests\": \"d65a455fc50624a7e6d5d83d45a270d14a723e66\", \"sbox_integration_tests\": \"882bb0f53dd78a2bfef71b5ba5e1a0ce0971d981\", \"sbox_unittests\": \"6db92eb8b9517511ec27f0b5d77c1c27903fec52\", \"sbox_validation_tests\": \"72b9619256a9f3a3d931d9edf160659f7395e50b\", \"service_manager_unittests\": \"57ba2998160800937a46ebb1067926f48805d028\", \"services_unittests\": \"b42db73ed333785a38f6d8f3434581e1a0de51dc\", \"setup_unittests\": \"baed4f225d1a2a7067ce025d7f2a1699df2cabc0\", \"shell_dialogs_unittests\": \"4989d2713e2be0b124e26ef28f751b9d50e4b22b\", \"skia_unittests\": \"77698bfdeab9e26e82abbbffcbf5063d4b55f0f5\", \"snapshot_unittests\": \"0fafc403c2bbfb33af63eec1015f1f814864560a\", \"sql_unittests\": \"fed13abbb1d9403c7f2e663829008358fb324844\", \"storage_unittests\": \"4a142f2dde47d7c3851182aa4cb5a92b2d9744b6\", \"sync_integration_tests\": \"b0dd0bed1405724e1e13129e289e0be4b4a92820\", \"traffic_annotation_auditor_unittests\": \"eba3241644f6285507a3c04d44a25649cd2d1652\", \"ui_base_unittests\": \"cf700e448e7cab9094f56d0158b3c33b7e34fb47\", \"ui_touch_selection_unittests\": \"a41f58908b0020b53a4c942bceeb66d6d83c2a7c\", \"unit_tests\": \"04681e4b1b5f07f5c5a04cb7494b4a67322a3462\", \"updater_tests\": \"06ca79e3840c02f633db72d9b250a399c5230a51\", \"url_unittests\": \"6226b2cd08d6fc09f48f67952d8883b7bf2c5b94\", \"views_unittests\": \"113662b2294ac41749538b9b318d354b945306f5\", \"viz_unittests\": \"5b3311f09b9eef82a1fca676f111b250542e1453\", \"vr_common_unittests\": \"09016f360e2ffca11b14f72e564025c7e704583a\", \"vr_pixeltests\": \"db32ac63f197dc389a160f3301ce7ac6ae37ed79\", \"weblayer_browsertests\": \"6d46ea9c3247d168d830606a52547e14e984f31a\", \"weblayer_unittests\": \"4ac286537819fddffe73877eeef7135e8a90c6b0\", \"wm_unittests\": \"7856a5f30461af7ba0bb1d23138e7c9c1fc32371\", \"wtf_unittests\": \"eb756e25e099668ea205423800d4113da80bab6e\", \"zlib_unittests\": \"84cd296be79c3be6d7a4598c56e5841edec32501\", \"zucchini_unittests\": \"a9505d61f79c06899a5c9c606ddf2d2340c66b16\"}}, \"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-5-miap\"], \"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\": [\"5657-0417a3f\"], \"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": "1608223151291587",
"status": "COMPLETED",
"status_changed_ts": "1608226933781596",
"tags": [
"build_address:luci.chromium.ci/win-asan/16284",
"builder:win-asan",
"buildset:commit/git/270dc6d55d5e1dbfd92834e578c262a3ac0f9aeb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/270dc6d55d5e1dbfd92834e578c262a3ac0f9aeb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026079580610464752",
"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/8860649518567025744/+/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:508a6359deac9410",
"user_agent:luci-scheduler"
],
"updated_ts": "1608226933786101",
"url": "https://ci.chromium.org/b/8860649518567025744",
"utcnow_ts": "1608226934735469"
}
}
[17:42:14.746] Invocation finished in 1h3m6.405460639s with status SUCCEEDED