[06:34:28.075] New invocation is queued and will start shortly
[06:34:29.200] Starting the invocation (attempt 1)
[06:34:29.247] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:34:29.248] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:34:29.248] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027476577742930672",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1203475a766a988cfbd30d91df19675752552bc1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027476577742930672",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1203475a766a988cfbd30d91df19675752552bc1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1203475a766a988cfbd30d91df19675752552bc1",
"gitiles_ref:refs/heads/master"
]
}
[06:34:30.001] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606890869341610",
"id": "8862046515748579568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1203475a766a988cfbd30d91df19675752552bc1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606890869730401",
"tags": [
"build_address:luci.chromium.ci/win-asan/15980",
"builder:win-asan",
"buildset:commit/git/1203475a766a988cfbd30d91df19675752552bc1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1203475a766a988cfbd30d91df19675752552bc1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027476577742930672",
"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/8862046515748579568/+/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": "1606890869730517",
"url": "https://ci.chromium.org/b/8862046515748579568",
"utcnow_ts": "1606890869991521"
}
}
[06:34:30.001] Task URL: https://ci.chromium.org/b/8862046515748579568
[06:34:30.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:2:0) after 1m0s
[06:35:30.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:2:0)
[06:35:30.024] Timer tick, asking Buildbucket for the build status
[06:35:30.148] Build 8862046515748579568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:35:30.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:3:0) after 1m0s
[06:36:30.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:3:0)
[06:36:30.159] Timer tick, asking Buildbucket for the build status
[06:36:30.878] Build 8862046515748579568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:36:30.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:4:0) after 1m0s
[06:37:30.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:4:0)
[06:37:30.936] Timer tick, asking Buildbucket for the build status
[06:37:31.022] Build 8862046515748579568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:37:31.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:5:0) after 1m0s
[06:38:31.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:5:0)
[06:38:31.089] Timer tick, asking Buildbucket for the build status
[06:38:31.173] Build 8862046515748579568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:38:31.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:6:0) after 1m0s
[06:39:18.356] Received PubSub notification, asking Buildbucket for the build status
[06:39:18.421] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:31.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:6:0)
[06:39:31.263] Timer tick, asking Buildbucket for the build status
[06:39:31.431] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:31.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:8:0) after 1m0s
[06:40:31.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:8:0)
[06:40:31.559] Timer tick, asking Buildbucket for the build status
[06:40:31.690] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:31.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:9:0) after 1m0s
[06:41:31.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:9:0)
[06:41:31.815] Timer tick, asking Buildbucket for the build status
[06:41:31.944] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:31.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:10:0) after 1m0s
[06:42:31.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:10:0)
[06:42:31.958] Timer tick, asking Buildbucket for the build status
[06:42:32.089] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:32.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:11:0) after 1m0s
[06:43:32.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:11:0)
[06:43:32.168] Timer tick, asking Buildbucket for the build status
[06:43:32.620] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:32.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:12:0) after 1m0s
[06:44:32.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:12:0)
[06:44:32.695] Timer tick, asking Buildbucket for the build status
[06:44:32.838] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:32.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:13:0) after 1m0s
[06:45:32.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:13:0)
[06:45:32.859] Timer tick, asking Buildbucket for the build status
[06:45:32.984] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:32.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:14:0) after 1m0s
[06:46:33.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:14:0)
[06:46:33.024] Timer tick, asking Buildbucket for the build status
[06:46:33.119] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:33.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:15:0) after 1m0s
[06:47:33.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:15:0)
[06:47:33.183] Timer tick, asking Buildbucket for the build status
[06:47:34.084] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:34.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:16:0) after 1m0s
[06:48:34.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:16:0)
[06:48:34.176] Timer tick, asking Buildbucket for the build status
[06:48:34.294] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:34.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:17:0) after 1m0s
[06:49:34.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:17:0)
[06:49:34.365] Timer tick, asking Buildbucket for the build status
[06:49:34.465] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:34.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:18:0) after 1m0s
[06:50:34.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:18:0)
[06:50:34.542] Timer tick, asking Buildbucket for the build status
[06:50:34.608] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:34.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:19:0) after 1m0s
[06:51:34.652] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:19:0)
[06:51:34.652] Timer tick, asking Buildbucket for the build status
[06:51:34.761] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:34.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:20:0) after 1m0s
[06:52:34.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:20:0)
[06:52:34.875] Timer tick, asking Buildbucket for the build status
[06:52:34.991] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:34.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:21:0) after 1m0s
[06:53:34.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:21:0)
[06:53:34.999] Timer tick, asking Buildbucket for the build status
[06:53:35.133] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:35.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:22:0) after 1m0s
[06:54:35.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:22:0)
[06:54:35.135] Timer tick, asking Buildbucket for the build status
[06:54:35.575] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:35.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:23:0) after 1m0s
[06:55:35.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:23:0)
[06:55:35.614] Timer tick, asking Buildbucket for the build status
[06:55:35.753] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:35.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:24:0) after 1m0s
[06:56:35.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:24:0)
[06:56:35.771] Timer tick, asking Buildbucket for the build status
[06:56:35.909] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:35.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:25:0) after 1m0s
[06:57:35.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:25:0)
[06:57:35.951] Timer tick, asking Buildbucket for the build status
[06:57:36.868] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:36.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:26:0) after 1m0s
[06:58:36.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:26:0)
[06:58:36.912] Timer tick, asking Buildbucket for the build status
[06:58:37.028] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:37.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:27:0) after 1m0s
[06:59:37.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:27:0)
[06:59:37.099] Timer tick, asking Buildbucket for the build status
[06:59:37.254] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:37.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:28:0) after 1m0s
[07:00:37.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:28:0)
[07:00:37.337] Timer tick, asking Buildbucket for the build status
[07:00:37.456] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:37.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:29:0) after 1m0s
[07:01:37.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:29:0)
[07:01:37.532] Timer tick, asking Buildbucket for the build status
[07:01:37.729] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:37.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:30:0) after 1m0s
[07:02:37.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:30:0)
[07:02:37.770] Timer tick, asking Buildbucket for the build status
[07:02:37.890] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:37.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:31:0) after 1m0s
[07:03:38.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:31:0)
[07:03:38.016] Timer tick, asking Buildbucket for the build status
[07:03:38.174] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:38.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:32:0) after 1m0s
[07:04:38.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:32:0)
[07:04:38.179] Timer tick, asking Buildbucket for the build status
[07:04:38.302] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:38.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:33:0) after 1m0s
[07:05:38.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:33:0)
[07:05:38.344] Timer tick, asking Buildbucket for the build status
[07:05:40.353] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:40.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:34:0) after 1m0s
[07:06:40.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:34:0)
[07:06:40.393] Timer tick, asking Buildbucket for the build status
[07:06:40.557] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:40.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:35:0) after 1m0s
[07:07:40.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:35:0)
[07:07:40.601] Timer tick, asking Buildbucket for the build status
[07:07:40.698] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:40.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:36:0) after 1m0s
[07:08:40.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:36:0)
[07:08:40.730] Timer tick, asking Buildbucket for the build status
[07:08:40.853] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:40.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:37:0) after 1m0s
[07:09:40.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:37:0)
[07:09:40.841] Timer tick, asking Buildbucket for the build status
[07:09:41.035] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:41.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:38:0) after 1m0s
[07:10:41.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:38:0)
[07:10:41.049] Timer tick, asking Buildbucket for the build status
[07:10:41.195] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:41.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:39:0) after 1m0s
[07:11:41.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:39:0)
[07:11:41.245] Timer tick, asking Buildbucket for the build status
[07:11:41.314] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:41.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:40:0) after 1m0s
[07:12:41.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:40:0)
[07:12:41.349] Timer tick, asking Buildbucket for the build status
[07:12:41.435] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:41.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:41:0) after 1m0s
[07:13:41.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:41:0)
[07:13:41.523] Timer tick, asking Buildbucket for the build status
[07:13:41.836] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:41.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:42:0) after 1m0s
[07:14:41.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:42:0)
[07:14:41.864] Timer tick, asking Buildbucket for the build status
[07:14:41.978] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:41.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:43:0) after 1m0s
[07:15:41.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:43:0)
[07:15:41.975] Timer tick, asking Buildbucket for the build status
[07:15:42.106] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:42.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:44:0) after 1m0s
[07:16:42.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:44:0)
[07:16:42.126] Timer tick, asking Buildbucket for the build status
[07:16:42.608] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:42.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:45:0) after 1m0s
[07:17:42.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:45:0)
[07:17:42.658] Timer tick, asking Buildbucket for the build status
[07:17:42.847] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:42.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:46:0) after 1m0s
[07:18:42.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:46:0)
[07:18:42.879] Timer tick, asking Buildbucket for the build status
[07:18:43.025] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:43.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:47:0) after 1m0s
[07:19:43.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:47:0)
[07:19:43.046] Timer tick, asking Buildbucket for the build status
[07:19:43.145] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:43.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:48:0) after 1m0s
[07:20:43.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:48:0)
[07:20:43.168] Timer tick, asking Buildbucket for the build status
[07:20:43.331] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:43.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:49:0) after 1m0s
[07:21:43.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:49:0)
[07:21:43.372] Timer tick, asking Buildbucket for the build status
[07:21:43.558] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:43.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:50:0) after 1m0s
[07:22:43.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:50:0)
[07:22:43.544] Timer tick, asking Buildbucket for the build status
[07:22:43.638] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:43.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:51:0) after 1m0s
[07:23:43.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:51:0)
[07:23:43.628] Timer tick, asking Buildbucket for the build status
[07:23:44.256] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:44.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:52:0) after 1m0s
[07:24:44.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:52:0)
[07:24:44.301] Timer tick, asking Buildbucket for the build status
[07:24:44.496] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:44.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:53:0) after 1m0s
[07:25:44.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:53:0)
[07:25:44.513] Timer tick, asking Buildbucket for the build status
[07:25:44.981] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:44.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:54:0) after 1m0s
[07:26:45.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:54:0)
[07:26:45.006] Timer tick, asking Buildbucket for the build status
[07:26:45.183] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:45.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:55:0) after 1m0s
[07:27:45.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:55:0)
[07:27:45.235] Timer tick, asking Buildbucket for the build status
[07:27:45.819] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:45.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:56:0) after 1m0s
[07:28:45.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:56:0)
[07:28:45.840] Timer tick, asking Buildbucket for the build status
[07:28:46.428] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:46.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:57:0) after 1m0s
[07:29:46.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:57:0)
[07:29:46.485] Timer tick, asking Buildbucket for the build status
[07:29:46.680] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:46.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:58:0) after 1m0s
[07:30:48.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:58:0)
[07:30:48.217] Timer tick, asking Buildbucket for the build status
[07:30:48.872] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:48.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:59:0) after 1m0s
[07:31:50.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:59:0)
[07:31:50.087] Timer tick, asking Buildbucket for the build status
[07:31:50.259] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:50.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:60:0) after 1m0s
[07:32:50.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:60:0)
[07:32:50.962] Timer tick, asking Buildbucket for the build status
[07:32:51.088] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:51.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:61:0) after 1m0s
[07:33:51.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:61:0)
[07:33:51.062] Timer tick, asking Buildbucket for the build status
[07:33:51.175] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:51.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:62:0) after 1m0s
[07:34:51.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:62:0)
[07:34:51.638] Timer tick, asking Buildbucket for the build status
[07:34:51.845] Build 8862046515748579568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:51.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:63:0) after 1m0s
[07:35:52.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027476577742930672:63:0)
[07:35:52.433] Timer tick, asking Buildbucket for the build status
[07:35:52.629] Build 8862046515748579568: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:35:52.629] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606894551274956",
"created_by": "project:chromium",
"created_ts": "1606890869341610",
"id": "8862046515748579568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1203475a766a988cfbd30d91df19675752552bc1\"}}",
"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\": \"1203475a766a988cfbd30d91df19675752552bc1\", \"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-v8md\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15980, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"0c47015b57a03230e0cab10532c3deed80604512\", \"got_dawn_revision\": \"6f5151f6572bf22c88d0b3152bebeaa460ac4723\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"1203475a766a988cfbd30d91df19675752552bc1\", \"got_revision_cp\": \"refs/heads/master@{#832698}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bfb211914759cc5deffbe9419cd1c2cae0573488\", \"got_v8_revision\": \"d4fd978f49d91844ea41f85afd8f3d917ef13e95\", \"got_v8_revision_cp\": \"refs/heads/8.9.74@{#1}\", \"got_webrtc_revision\": \"69662a99d30da64cbe064f5cc646bb75d77c0b71\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32735}\", \"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\": \"1203475a766a988cfbd30d91df19675752552bc1\", \"swarm_hashes_refs/heads/master(at){#832698}_without_patch\": {\"absl_hardening_tests\": \"7c42ee2f3448c3519ac554b5153e5b288c1c9a44\", \"accessibility_unittests\": \"98de93824d660784f7b2364ffa47ca9be02e5dde\", \"angle_unittests\": \"6c6346a5dd52f746b75bcecba8c795f6f8651a2b\", \"app_shell_unittests\": \"8b61c57f26ff40919b72f64f9561c5b9dd9533b9\", \"aura_unittests\": \"4e283691b40da6e07f45faef112d95068885c047\", \"base_unittests\": \"71509ef59557fa69e802d0d1e6e9008551c38fec\", \"base_util_unittests\": \"cd267e961893337ee1b0e30dbbd171aceafdebb8\", \"blink_common_unittests\": \"1d3f199bbbe7d78f749ec77d3a847f7d041fe93e\", \"blink_fuzzer_unittests\": \"9af4ec402a0254e2fb20fe1236b4a4a76925e473\", \"blink_heap_unittests\": \"50259dfca1daeee0ef1c7bdafdd42b03da2a02f8\", \"blink_platform_unittests\": \"c72b4ce7ee04242f0235c1f67ea6a3a76ae539d2\", \"blink_unittests\": \"e07bbda7f05c8df27a9be3183769a6a1352c86f6\", \"boringssl_crypto_tests\": \"b8fcede12a9bbc92d676ba0cb725984695de65ec\", \"boringssl_ssl_tests\": \"cd6981f6899312beacce4cd3d941edf8562cedcd\", \"browser_switcher_bho_unittests\": \"1969eaacdbdba1b81d75a8a0b3c533ed7e7ae22f\", \"browser_tests\": \"8bb94f4de131a6cc94af4fb655f58e1027462601\", \"capture_unittests\": \"705c7d66bed576a395368bb431c50ae81e3f60da\", \"cast_unittests\": \"a0e4b8514483d959d0d27eed43ae6749aed06437\", \"cc_unittests\": \"f98856afb91da14f25b7cd5ac5530f3da3a4698b\", \"chrome_app_unittests\": \"7024c3d021101dbb14bea974721277f38a773c21\", \"chrome_cleaner_unittests\": \"62d11e153315a7ff28f15429b3abb4cc6b16271c\", \"chrome_elf_unittests\": \"402fc3a5c943da034c1155fd8b5e99ff2cc57b0a\", \"chromedriver_unittests\": \"b380a0f72f50daba0c1d3158738c92eeaa763175\", \"components_browsertests\": \"81f1175643d312164193e52653839143b3eb72fd\", \"components_unittests\": \"cd344366f5c4d3fcf61bd739fe46f903cb1fefef\", \"compositor_unittests\": \"770073889f2dbcb60a254cc2b532876749dcaf7c\", \"content_browsertests\": \"c316a6e1e5277036e517f185b9af927b881049fd\", \"content_unittests\": \"b65c661db3540694532eebb40b42b8225361231d\", \"courgette_unittests\": \"162dc283e5c3ee292ff4e908e8fbc4c64fc6ed30\", \"crashpad_tests\": \"08c22eb02a6564590e9f495ae83845324dab8c04\", \"cronet_tests\": \"0ca1f1b7e660b04abb5cb46e41a38d57dbca3719\", \"cronet_unittests\": \"a596a6787546582dd4c56ccf741a481dec29ff2b\", \"crypto_unittests\": \"de79a65b9425ee492c60dd46d6b89bc5abd8ae09\", \"delayloads_unittests\": \"38627fdef8d3e7dffcc6811e0e67694e80ae79eb\", \"device_unittests\": \"ab84f3d523cb5777a68111a188e5299628293d60\", \"display_unittests\": \"53879b3442f2a5384d1c8a7461dc2e70652eafbe\", \"elevation_service_unittests\": \"f4640a3798480ea9648b36e5ce23c3e76f936531\", \"events_unittests\": \"3673bcd42161d2fac7229722eb4cbff7dd8a8d28\", \"extensions_browsertests\": \"5eee2cf9983a1aee4d4f0720ccb916929735cdf2\", \"extensions_unittests\": \"3b5b737d0f4edfe593f17a4d752763fdc940e5c7\", \"filesystem_service_unittests\": \"1ed432e4a5886d4b0907d21600795a5ef6c80382\", \"gcm_unit_tests\": \"663935517bb1cbc3f16b6596401c0a09464a5d66\", \"gcp_unittests\": \"647b367e942f06283f1354821524a243feea62ed\", \"gfx_unittests\": \"61588ac7002035449d7a674ea0dc94785d0f2163\", \"gin_unittests\": \"cc1b672742b308512b3a3d527527895c171bec16\", \"google_apis_unittests\": \"b5292d2038ecfa11d9aff9b1bba187403a441f7a\", \"gpu_unittests\": \"047aa84f2001e353fd32ee78717dd3bd873740b0\", \"gwp_asan_unittests\": \"70ad551aad648f221936b6c09098129ab894ddf7\", \"headless_browsertests\": \"e0b02139d95ba6a745fdced406e079bd4c2d4e77\", \"headless_unittests\": \"764f6d2168908343673e137ac3c028924821f38d\", \"install_static_unittests\": \"a442b5a10b87396118a824a26fc53fa2573d53be\", \"installer_util_unittests\": \"5201421e1e64b545cabd321e1d5a0290fda9114a\", \"interactive_ui_tests\": \"c4385db2cbceb2ad341f1f49aaaf3760dd678db7\", \"ipc_tests\": \"6fefd32e97979485e6ec8e4f0cbf043f22031e24\", \"jingle_unittests\": \"35ccbb7b12ad22c17dcecadbcaf266826f23c61f\", \"latency_unittests\": \"e8e819dab39fa68a0312a445134786098215414d\", \"libjingle_xmpp_unittests\": \"e745fc299c0b9240ef3c675c612e03af1885455d\", \"liburlpattern_unittests\": \"de4da837abbe9cad3128f95bfc5e9c128b9f15b2\", \"media_blink_unittests\": \"5716a2c64c0e44f2479d74330dec03e42c76cb52\", \"media_unittests\": \"c050f256dbc1ddca24cd0033d7c2751ba571f678\", \"message_center_unittests\": \"ea0a4fe2f2c7a6ef78a767ac8ba2a2400f7b8085\", \"midi_unittests\": \"0068c5293cb13f36a0820595eee23c894e3ee89e\", \"mojo_core_unittests\": \"5ff0131a6498546cea378902c7fc89075fe115da\", \"mojo_unittests\": \"59b93c9770cf0b3320143bacb452ed789fa8736f\", \"nacl_loader_unittests\": \"18b945f428029eea734cf4876a383a466d549fde\", \"native_theme_unittests\": \"6a9b35adca8b774bf514d45e5e6056591e9f3d5d\", \"net_unittests\": \"90c4a71d95bf0da72195354b10800b8f122fb739\", \"notification_helper_unittests\": \"21b1fed504e7d7ccb4285892ac6931e0f1d75429\", \"pdf_unittests\": \"2bef3a3fd83825761fdb7fb570165f6d0b1931f9\", \"perfetto_unittests\": \"b517c50c186eca399e69145c0524b9be4d5f312f\", \"ppapi_unittests\": \"09305c339a2d4b267501c02074b5021183e49f99\", \"printing_unittests\": \"f2d0124c0db0d368c3c0e845d21c12ddacc92b3e\", \"remoting_unittests\": \"fcdffa7e82298b25a1b530ed6704d430dda26997\", \"sbox_integration_tests\": \"c65876ff8d6ac2b43c20ce917064b2e670767f77\", \"sbox_unittests\": \"14fea59d7576c1b10318439161e1bc25327569a2\", \"sbox_validation_tests\": \"5dcfb574dfd95f96862d4efc00e5dd5f8de4665e\", \"service_manager_unittests\": \"a141aa470aec8158b3f2e8ddd9e601e8e1f6b65b\", \"services_unittests\": \"c6f3cfd59b8602b8f7005bcb17b47265b8435882\", \"setup_unittests\": \"6429ccb789f6d3e4f6896f0821fab263d6619732\", \"shell_dialogs_unittests\": \"8f6ec8c6cb05c333da4da17dd5fcda1434f4c111\", \"skia_unittests\": \"290e7ccd6ba56d2025f07bacfb422d63536fec11\", \"snapshot_unittests\": \"9b31ac23b79e417802fbce00ef65e7a76955204c\", \"sql_unittests\": \"f8fd925fa9a95f9a3851e48065638fa04c9a7494\", \"storage_unittests\": \"42dd33dc532e44c54c1ea58b52b0b05a7f065671\", \"sync_integration_tests\": \"f015ba29adfab9450724210aaa77f8d365acb2a4\", \"traffic_annotation_auditor_unittests\": \"408aaa4006ab1610f2242819e37e3adac8ce6053\", \"ui_base_unittests\": \"f552d0ac813ebcbf1cacba2c542c698b269b94d3\", \"ui_touch_selection_unittests\": \"b2d0339cf18a4ad45475c9a8f72095bafab85616\", \"unit_tests\": \"0efde81fb836ad9565338ef9e40b4265b8da1d3c\", \"updater_tests\": \"13e6a39c1ac5ea10938661a93d8fd092b711af5e\", \"url_unittests\": \"ed53394550c1d7a23d9a94ed9549c0fbdb14f83d\", \"views_unittests\": \"c39c96e591388db16d51b358eda479f79f72ac7f\", \"viz_unittests\": \"687c45c2718b543e540e2d151cff515bdfd18ebb\", \"vr_common_unittests\": \"e8e360afd45657b38f8133f0bbad9fe2b2f6e8f1\", \"vr_pixeltests\": \"530ba42deb0ff34e4bcafb99c03a795edc4d5c89\", \"weblayer_browsertests\": \"9a6eff21dcd486086c52d8732c01a278b8287d94\", \"weblayer_unittests\": \"05ea4279350fc5cf2c15db7d09ff619b16eb8604\", \"wm_unittests\": \"8eb4be5cd2e8aac13d24736e981f5ccfab45cf3d\", \"wtf_unittests\": \"0e7a3b4ffaaeeda3be6bbb8f1c314076adae76ff\", \"zlib_unittests\": \"aef25922a438e1a6c42f4ec68d9a1fc3bfef7c17\", \"zucchini_unittests\": \"34d8667b81699e5db2569402bd5156981a18a88a\"}}, \"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-v8md\"], \"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\": [\"5640-d0eb544\"], \"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": "1606891154401075",
"status": "COMPLETED",
"status_changed_ts": "1606894551949828",
"tags": [
"build_address:luci.chromium.ci/win-asan/15980",
"builder:win-asan",
"buildset:commit/git/1203475a766a988cfbd30d91df19675752552bc1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1203475a766a988cfbd30d91df19675752552bc1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027476577742930672",
"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/8862046515748579568/+/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:503afa5dd7e1a310",
"user_agent:luci-scheduler"
],
"updated_ts": "1606894551954927",
"url": "https://ci.chromium.org/b/8862046515748579568",
"utcnow_ts": "1606894552619122"
}
}
[07:35:52.629] Invocation finished in 1h1m24.574197171s with status SUCCEEDED