[22:52:44.824] New invocation is queued and will start shortly
[22:52:45.936] Starting the invocation (attempt 1)
[22:52:45.972] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:52:45.972] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:52:45.972] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027143238836844272",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4dc4006889bb8ffd93ed7e36da4e51b624e010b9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027143238836844272",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/4dc4006889bb8ffd93ed7e36da4e51b624e010b9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4dc4006889bb8ffd93ed7e36da4e51b624e010b9",
"gitiles_ref:refs/heads/master"
]
}
[22:52:48.159] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1607208766073754",
"id": "8861713176864140640",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4dc4006889bb8ffd93ed7e36da4e51b624e010b9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607208767735875",
"tags": [
"build_address:luci.chromium.ci/win-asan/16050",
"builder:win-asan",
"buildset:commit/git/4dc4006889bb8ffd93ed7e36da4e51b624e010b9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4dc4006889bb8ffd93ed7e36da4e51b624e010b9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027143238836844272",
"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/8861713176864140640/+/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": "1607208767740307",
"url": "https://ci.chromium.org/b/8861713176864140640",
"utcnow_ts": "1607208768114719"
}
}
[22:52:48.159] Task URL: https://ci.chromium.org/b/8861713176864140640
[22:52:48.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:2:0) after 1m0s
[22:52:52.790] Received PubSub notification, asking Buildbucket for the build status
[22:52:52.930] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:48.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:2:0)
[22:53:48.179] Timer tick, asking Buildbucket for the build status
[22:53:49.178] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:49.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:4:0) after 1m0s
[22:54:49.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:4:0)
[22:54:49.265] Timer tick, asking Buildbucket for the build status
[22:54:54.363] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:54.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:5:0) after 1m0s
[22:55:54.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:5:0)
[22:55:54.401] Timer tick, asking Buildbucket for the build status
[22:55:54.624] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:54.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:6:0) after 1m0s
[22:56:54.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:6:0)
[22:56:54.657] Timer tick, asking Buildbucket for the build status
[22:56:54.759] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:54.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:7:0) after 1m0s
[22:57:54.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:7:0)
[22:57:54.828] Timer tick, asking Buildbucket for the build status
[22:57:54.996] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:54.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:8:0) after 1m0s
[22:58:55.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:8:0)
[22:58:55.021] Timer tick, asking Buildbucket for the build status
[22:58:55.149] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:55.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:9:0) after 1m0s
[22:59:55.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:9:0)
[22:59:55.178] Timer tick, asking Buildbucket for the build status
[22:59:55.343] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:55.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:10:0) after 1m0s
[23:00:55.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:10:0)
[23:00:55.405] Timer tick, asking Buildbucket for the build status
[23:00:55.546] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:55.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:11:0) after 1m0s
[23:01:55.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:11:0)
[23:01:55.590] Timer tick, asking Buildbucket for the build status
[23:01:55.922] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:55.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:12:0) after 1m0s
[23:02:55.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:12:0)
[23:02:55.945] Timer tick, asking Buildbucket for the build status
[23:02:56.205] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:56.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:13:0) after 1m0s
[23:03:56.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:13:0)
[23:03:56.184] Timer tick, asking Buildbucket for the build status
[23:03:56.275] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:56.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:14:0) after 1m0s
[23:04:56.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:14:0)
[23:04:56.310] Timer tick, asking Buildbucket for the build status
[23:04:56.568] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:56.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:15:0) after 1m0s
[23:05:56.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:15:0)
[23:05:56.592] Timer tick, asking Buildbucket for the build status
[23:05:56.709] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:56.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:16:0) after 1m0s
[23:06:56.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:16:0)
[23:06:56.803] Timer tick, asking Buildbucket for the build status
[23:06:57.231] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:57.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:17:0) after 1m0s
[23:07:57.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:17:0)
[23:07:57.300] Timer tick, asking Buildbucket for the build status
[23:07:57.471] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:57.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:18:0) after 1m0s
[23:08:57.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:18:0)
[23:08:57.501] Timer tick, asking Buildbucket for the build status
[23:08:57.849] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:57.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:19:0) after 1m0s
[23:09:57.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:19:0)
[23:09:57.873] Timer tick, asking Buildbucket for the build status
[23:09:58.253] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:58.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:20:0) after 1m0s
[23:10:58.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:20:0)
[23:10:58.235] Timer tick, asking Buildbucket for the build status
[23:10:58.484] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:58.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:21:0) after 1m0s
[23:11:58.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:21:0)
[23:11:58.509] Timer tick, asking Buildbucket for the build status
[23:11:58.636] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:58.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:22:0) after 1m0s
[23:12:58.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:22:0)
[23:12:58.658] Timer tick, asking Buildbucket for the build status
[23:12:59.370] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:59.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:23:0) after 1m0s
[23:13:59.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:23:0)
[23:13:59.426] Timer tick, asking Buildbucket for the build status
[23:13:59.729] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:59.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:24:0) after 1m0s
[23:14:59.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:24:0)
[23:14:59.805] Timer tick, asking Buildbucket for the build status
[23:14:59.970] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:59.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:25:0) after 1m0s
[23:15:59.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:25:0)
[23:15:59.968] Timer tick, asking Buildbucket for the build status
[23:16:00.674] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:00.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:26:0) after 1m0s
[23:17:00.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:26:0)
[23:17:00.915] Timer tick, asking Buildbucket for the build status
[23:17:01.332] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:01.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:27:0) after 1m0s
[23:18:01.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:27:0)
[23:18:01.407] Timer tick, asking Buildbucket for the build status
[23:18:01.742] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:01.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:28:0) after 1m0s
[23:19:01.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:28:0)
[23:19:01.806] Timer tick, asking Buildbucket for the build status
[23:19:02.108] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:02.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:29:0) after 1m0s
[23:20:02.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:29:0)
[23:20:02.134] Timer tick, asking Buildbucket for the build status
[23:20:02.276] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:02.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:30:0) after 1m0s
[23:21:02.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:30:0)
[23:21:02.297] Timer tick, asking Buildbucket for the build status
[23:21:02.505] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:02.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:31:0) after 1m0s
[23:22:02.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:31:0)
[23:22:02.529] Timer tick, asking Buildbucket for the build status
[23:22:02.938] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:02.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:32:0) after 1m0s
[23:23:02.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:32:0)
[23:23:02.961] Timer tick, asking Buildbucket for the build status
[23:23:03.691] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:03.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:33:0) after 1m0s
[23:24:03.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:33:0)
[23:24:03.686] Timer tick, asking Buildbucket for the build status
[23:24:04.271] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:04.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:34:0) after 1m0s
[23:25:04.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:34:0)
[23:25:04.320] Timer tick, asking Buildbucket for the build status
[23:25:08.368] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:08.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:35:0) after 1m0s
[23:26:08.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:35:0)
[23:26:08.399] Timer tick, asking Buildbucket for the build status
[23:26:09.233] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:09.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:36:0) after 1m0s
[23:27:09.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:36:0)
[23:27:09.257] Timer tick, asking Buildbucket for the build status
[23:27:09.464] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:09.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:37:0) after 1m0s
[23:28:09.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:37:0)
[23:28:09.494] Timer tick, asking Buildbucket for the build status
[23:28:09.758] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:09.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:38:0) after 1m0s
[23:29:09.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:38:0)
[23:29:09.779] Timer tick, asking Buildbucket for the build status
[23:29:10.222] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:10.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:39:0) after 1m0s
[23:30:10.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:39:0)
[23:30:10.301] Timer tick, asking Buildbucket for the build status
[23:30:10.415] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:10.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:40:0) after 1m0s
[23:31:10.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:40:0)
[23:31:10.486] Timer tick, asking Buildbucket for the build status
[23:31:10.632] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:10.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:41:0) after 1m0s
[23:32:10.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:41:0)
[23:32:10.653] Timer tick, asking Buildbucket for the build status
[23:32:11.226] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:11.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:42:0) after 1m0s
[23:33:11.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:42:0)
[23:33:11.314] Timer tick, asking Buildbucket for the build status
[23:33:11.491] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:11.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:43:0) after 1m0s
[23:34:11.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:43:0)
[23:34:11.527] Timer tick, asking Buildbucket for the build status
[23:34:11.767] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:11.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:44:0) after 1m0s
[23:35:11.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:44:0)
[23:35:11.842] Timer tick, asking Buildbucket for the build status
[23:35:11.952] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:11.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:45:0) after 1m0s
[23:36:11.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:45:0)
[23:36:11.971] Timer tick, asking Buildbucket for the build status
[23:36:12.042] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:12.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:46:0) after 1m0s
[23:37:12.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:46:0)
[23:37:12.066] Timer tick, asking Buildbucket for the build status
[23:37:12.201] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:12.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:47:0) after 1m0s
[23:38:12.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:47:0)
[23:38:12.222] Timer tick, asking Buildbucket for the build status
[23:38:12.572] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:12.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:48:0) after 1m0s
[23:39:12.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:48:0)
[23:39:12.643] Timer tick, asking Buildbucket for the build status
[23:39:13.674] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:13.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:49:0) after 1m0s
[23:40:13.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:49:0)
[23:40:13.749] Timer tick, asking Buildbucket for the build status
[23:40:16.769] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:16.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:50:0) after 1m0s
[23:41:16.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:50:0)
[23:41:16.831] Timer tick, asking Buildbucket for the build status
[23:41:16.951] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:16.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:51:0) after 1m0s
[23:42:16.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:51:0)
[23:42:16.987] Timer tick, asking Buildbucket for the build status
[23:42:17.116] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:17.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:52:0) after 1m0s
[23:43:17.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:52:0)
[23:43:17.154] Timer tick, asking Buildbucket for the build status
[23:43:17.263] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:17.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:53:0) after 1m0s
[23:44:17.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:53:0)
[23:44:17.287] Timer tick, asking Buildbucket for the build status
[23:44:17.631] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:17.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:54:0) after 1m0s
[23:45:17.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:54:0)
[23:45:17.663] Timer tick, asking Buildbucket for the build status
[23:45:17.807] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:17.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:55:0) after 1m0s
[23:46:17.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:55:0)
[23:46:17.854] Timer tick, asking Buildbucket for the build status
[23:46:19.565] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:19.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:56:0) after 1m0s
[23:47:19.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:56:0)
[23:47:19.654] Timer tick, asking Buildbucket for the build status
[23:47:19.815] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:19.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:57:0) after 1m0s
[23:48:19.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:57:0)
[23:48:19.796] Timer tick, asking Buildbucket for the build status
[23:48:19.937] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:19.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:58:0) after 1m0s
[23:49:19.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:58:0)
[23:49:19.960] Timer tick, asking Buildbucket for the build status
[23:49:20.122] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:20.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:59:0) after 1m0s
[23:50:20.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:59:0)
[23:50:20.183] Timer tick, asking Buildbucket for the build status
[23:50:21.578] Build 8861713176864140640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:21.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027143238836844272:60:0) after 1m0s
[23:51:08.069] Received PubSub notification, asking Buildbucket for the build status
[23:51:08.881] Build 8861713176864140640: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:51:08.881] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1607212266322776",
"created_by": "project:chromium",
"created_ts": "1607208766073754",
"id": "8861713176864140640",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4dc4006889bb8ffd93ed7e36da4e51b624e010b9\"}}",
"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\": \"4dc4006889bb8ffd93ed7e36da4e51b624e010b9\", \"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-jjte\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16050, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"251ba5cb119ff2fed0e861cbc9b096c45004c1fa\", \"got_dawn_revision\": \"d63d562d1b26e01b369d9a58eddeaccfaa688a02\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"4dc4006889bb8ffd93ed7e36da4e51b624e010b9\", \"got_revision_cp\": \"refs/heads/master@{#834043}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"f36e874ade1dbc6f5ddfc857753a892a8bb358c3\", \"got_v8_revision\": \"61f3e61408129afc0d9e1268d3354a283ea3c881\", \"got_v8_revision_cp\": \"refs/heads/8.9.100@{#1}\", \"got_webrtc_revision\": \"6c80aebd00360ee00c84c7b14422c5fe475d8916\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32774}\", \"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\": \"4dc4006889bb8ffd93ed7e36da4e51b624e010b9\", \"swarm_hashes_refs/heads/master(at){#834043}_without_patch\": {\"absl_hardening_tests\": \"b7756871b76c5c8f76958c3b65646b96710a3dd1\", \"accessibility_unittests\": \"cd22da871c1ae8bb7e26754ba8d88b640663ee5e\", \"angle_unittests\": \"b1485a29af17d361aec21fe0249230f2f510b4b0\", \"app_shell_unittests\": \"82818a940a85d901761730752ca8a556d7cc70e2\", \"aura_unittests\": \"6131c7a7610c22e4916ed8d324dffed24689ef38\", \"base_unittests\": \"470147db8cd2774dc39c47a92a85a9f7ee7a99a1\", \"base_util_unittests\": \"1699349db27a792e99eb8fc6ebb11a12a3bf5e29\", \"blink_common_unittests\": \"6b20d7a0614167b5cdffdaa83b3205de5a06e242\", \"blink_fuzzer_unittests\": \"4e57ad5ec7b29d318e11e62e3e94ec9800168f9d\", \"blink_heap_unittests\": \"a225fc7142e2d4e9c8e3b99faeae23489eb6193f\", \"blink_platform_unittests\": \"ca26176dd4c8ba7a44a3d79fdb6a78e43c7617d6\", \"blink_unittests\": \"6cc1cf8913b93acc09afafa43f7d844f83ab8df8\", \"boringssl_crypto_tests\": \"2a99d76fb3aa71918e26b158a081fb316dc1fc01\", \"boringssl_ssl_tests\": \"31d5e8b91d72b678d61592480e3cf8833a9a50ff\", \"browser_switcher_bho_unittests\": \"87964c28a7f623db64c5a68c90ef1bcab6e3b070\", \"browser_tests\": \"7f42d2d26e37258893c1e396a83f0f70f56852db\", \"capture_unittests\": \"81ce7bdfe1eb3d8202f3a5024396a1b537593775\", \"cast_unittests\": \"088c0cd890fb5a5b8e0eee31f62474392a7391fc\", \"cc_unittests\": \"f7b6c7a5ee757f7eff74fddcda0eb6217a46d3a2\", \"chrome_app_unittests\": \"9b34704f964d747f4aa2f455417f1542e0a42acb\", \"chrome_cleaner_unittests\": \"907799f935186fae8ae2c124aad11662d77cd8fc\", \"chrome_elf_unittests\": \"e71a34f2820c8858fd7dde0755ead3b7869d02d0\", \"chromedriver_unittests\": \"a99a2afd7d92bf1e7489c8984b24244eff19441c\", \"components_browsertests\": \"8f25420a7b575813073bd7ce3bb60dd406c36d27\", \"components_unittests\": \"398737c6085d92822096e36b614d5cef179b3be7\", \"compositor_unittests\": \"76af24e8e339aca22416bfcdc11eba1b3e6f2d66\", \"content_browsertests\": \"697bf44278e755662f1d6994866b88596b247243\", \"content_unittests\": \"7de349bbbacb4166a28f7545c55a41199b342f13\", \"courgette_unittests\": \"ecd1a3d3372684f2322baa183813c64a0438d2f1\", \"crashpad_tests\": \"a6d764f38b76722e3e5170d9b26abbac3f0ff477\", \"cronet_tests\": \"4565c0080c0172ea91e84a84ff4a741f630e8892\", \"cronet_unittests\": \"03caadc32f28e399f48e5d2340b2306a52c914e4\", \"crypto_unittests\": \"796e65fb3824b4989ad7e61b47c4fe26f4e2bdbd\", \"delayloads_unittests\": \"709062d16546019028571fde8b92908a68b9e61c\", \"device_unittests\": \"0f537192356ea9912c085d89f50c147de77fa198\", \"display_unittests\": \"aafef6883b6e9187137b4920aac18638d64d9103\", \"elevation_service_unittests\": \"725b60c5d2a3fb9f9b7e8d13825c63061f695909\", \"events_unittests\": \"de7cb558047063ccb1e15e784f9f6e4e440a5faf\", \"extensions_browsertests\": \"2aa5dbde9eabb1ce9fbc7740fb820c44d1f5a4ba\", \"extensions_unittests\": \"3fc54747c7d5e486fdbc4bd799fd9f25a0c66c2b\", \"filesystem_service_unittests\": \"87c588402629f4887312cd409b1dfd56b9f0cf4e\", \"gcm_unit_tests\": \"12159dd2afeb033d29fbef4d1d08731cf4f1970d\", \"gcp_unittests\": \"4eabdf60d0b24d2ee16bd947f0dbdff597073282\", \"gfx_unittests\": \"b40b77a148a090eb97164c66f8bb086d392f2fa8\", \"gin_unittests\": \"fbf3943c36bed4e1976df797b070566cc15a4047\", \"google_apis_unittests\": \"e85cac81b29e5d79af2a2fa8f636666a45eaec71\", \"gpu_unittests\": \"fe4f8a6e6a7967abbbdc4403d4ef02255d1290a7\", \"gwp_asan_unittests\": \"93cac1d852cbcaa6e4223d3961c80836796dc6d3\", \"headless_browsertests\": \"cc014454086cca2b63af64b54af05944c51295b9\", \"headless_unittests\": \"ed306dee97cfdf6403b8a1a4bd8467e8f6a0edb9\", \"install_static_unittests\": \"9438137441b9277db93d9e173a68dd473eb18a0a\", \"installer_util_unittests\": \"139bb2165de7f024f15e6ebeb24fcdbebaa47608\", \"interactive_ui_tests\": \"d6f97893096a7f16c310d8f026ed86395573d869\", \"ipc_tests\": \"81d059792b909665c1bee45a1fe7267026dcf87c\", \"jingle_unittests\": \"cb72b1ae1342a5788c862d933d4df92a4862d81d\", \"latency_unittests\": \"9493000909100dacd833aff2c4dd6efe2950aa09\", \"libjingle_xmpp_unittests\": \"855ecd4d8fbe212ec0aa2618e308d5a26a3bdf95\", \"liburlpattern_unittests\": \"21de48be7e51314cd70b29136416a837f3610650\", \"media_blink_unittests\": \"4882f5d23cba78364a6ac642b171a3ee844913db\", \"media_unittests\": \"2e193b5dad24e39167bc09c8149ed6ee1bb589d9\", \"message_center_unittests\": \"6affe614548c4752f268ae938f9f3b281e0175ee\", \"midi_unittests\": \"bd5a85b3ce8a1038d4de01df2e69d2574b15ec17\", \"mojo_core_unittests\": \"ae18bc36b3563c5abe80832c2005c84c63263c4f\", \"mojo_unittests\": \"2e414805ed15d0ec8d81b7a8ddef4b9b62f398b7\", \"nacl_loader_unittests\": \"86c25e7a9ad9a9c448c2c5c1df123acab4421629\", \"native_theme_unittests\": \"2a808ff3abe47df0c7554daf02339fe9550884a4\", \"net_unittests\": \"7b6897edd6ef6e2ad7df8852194c8a3ed01080d7\", \"notification_helper_unittests\": \"90ef2ee4b046c3be782e796180c0cde999da72b9\", \"pdf_unittests\": \"8dbbfddf739fcdca8e983866abcd3915495b0815\", \"perfetto_unittests\": \"e56fdd95c28e1e1de8b96ec3f09d5e95dee8822c\", \"ppapi_unittests\": \"c563729643d79740a749eb7adcfefac9d01762de\", \"printing_unittests\": \"a2590c412b2f57d16de1868152084588d25c862e\", \"remoting_unittests\": \"aa858d529f7fcd56c0e6e7fb40ef1f33b949cc57\", \"sbox_integration_tests\": \"d2fa8db8b4740eec10235bc7985ec56d3179986c\", \"sbox_unittests\": \"69a82b0364eb1dcb6957ef4e40f6d71b823ae847\", \"sbox_validation_tests\": \"9231cd0b5cc442d07029ac72db9f5a466ba4febf\", \"service_manager_unittests\": \"b4267037b2ece1c5e8aee126d334cfd086d27d9b\", \"services_unittests\": \"c818d603c1fcc2fd8e758ca2805a9bcdb599a424\", \"setup_unittests\": \"123d827e84266b62f1bb60bc94d19675ad119afc\", \"shell_dialogs_unittests\": \"8d657d16b89ab8381362b3ccf97aff04f01454cd\", \"skia_unittests\": \"5ba0a416de55b1cc6f22cd8d08ac74f8482c14d2\", \"snapshot_unittests\": \"9ba067b9b8f8aafb1d4fabf602f39f3bec0da4a4\", \"sql_unittests\": \"96e9c602bb664c98bec84967bb23e9c28806b91b\", \"storage_unittests\": \"6484647e700d53ab52c02221f3327568b763dfa2\", \"sync_integration_tests\": \"d0a4905c6fb61cebdbdffd182a9b3c43c9665e87\", \"traffic_annotation_auditor_unittests\": \"aa152cecf2a15987662c6627d857dea0fa112d0e\", \"ui_base_unittests\": \"5aef5a1cd369cd6354ef4add9c7b4da8f93a406e\", \"ui_touch_selection_unittests\": \"0b9f0ea2d2fd28dc12bba801e89643b968b32840\", \"unit_tests\": \"3d406330c5d8731329280cedd37d859528ef2e4a\", \"updater_tests\": \"43a2f54757236b5850bec584842fc0bdf7c42812\", \"url_unittests\": \"79bfaf70d0f060f3f9d1db725434db9ba5fdde75\", \"views_unittests\": \"2e6895c0d0bf3b6a254119d1be9bf2d92c8fdb80\", \"viz_unittests\": \"05c77eb6eb19be74214c945a3adcb77ed237f1ad\", \"vr_common_unittests\": \"1562c18369298a846bc7ac824eb12a57ba2efdb3\", \"vr_pixeltests\": \"ad693f503ba084dfdbe8315b356f48d9f0a8535b\", \"weblayer_browsertests\": \"ce88915f66adc91a4d3413216e49c4a98fb43863\", \"weblayer_unittests\": \"43a4789b754c5769d47f0abd2e9b3ab8f9fa448f\", \"wm_unittests\": \"7218731f422374165f51f2a965d9e3f12d393bb2\", \"wtf_unittests\": \"4330837124ad8ac4bce56186da7561ba63fa4fa8\", \"zlib_unittests\": \"69307228fd0317f2dd0ffa2574e71f6e04b426a1\", \"zucchini_unittests\": \"2f7383a3755fc337882ae2fa8d2444c53c8a171b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"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-jjte\"], \"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": "1607208770867100",
"status": "COMPLETED",
"status_changed_ts": "1607212266923254",
"tags": [
"build_address:luci.chromium.ci/win-asan/16050",
"builder:win-asan",
"buildset:commit/git/4dc4006889bb8ffd93ed7e36da4e51b624e010b9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4dc4006889bb8ffd93ed7e36da4e51b624e010b9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027143238836844272",
"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/8861713176864140640/+/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:504ded1b01bbfd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607212266940359",
"url": "https://ci.chromium.org/b/8861713176864140640",
"utcnow_ts": "1607212268771748"
}
}
[23:51:08.881] Invocation finished in 58m24.07303266s with status SUCCEEDED