[23:34:22.515] New invocation is queued and will start shortly
[23:34:23.856] Starting the invocation (attempt 1)
[23:34:23.934] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:34:23.934] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:34:23.934] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022248383633744176",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"923385187510d1b34e0b8176816139794fb4aa67\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022248383633744176",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/923385187510d1b34e0b8176816139794fb4aa67",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/923385187510d1b34e0b8176816139794fb4aa67",
"gitiles_ref:refs/heads/master"
]
}
[23:34:24.621] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1611876864044433",
"id": "8856818321366935728",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"923385187510d1b34e0b8176816139794fb4aa67\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611876864476748",
"tags": [
"build_address:luci.chromium.ci/win-asan/17057",
"builder:win-asan",
"buildset:commit/git/923385187510d1b34e0b8176816139794fb4aa67",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/923385187510d1b34e0b8176816139794fb4aa67",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022248383633744176",
"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/8856818321366935728/+/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": "1611876864476858",
"url": "https://ci.chromium.org/b/8856818321366935728",
"utcnow_ts": "1611876864612295"
}
}
[23:34:24.621] Task URL: https://ci.chromium.org/b/8856818321366935728
[23:34:24.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:2:0) after 1m0s
[23:34:27.971] Received PubSub notification, asking Buildbucket for the build status
[23:34:28.657] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:24.776] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:2:0)
[23:35:24.776] Timer tick, asking Buildbucket for the build status
[23:35:25.244] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:25.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:4:0) after 1m0s
[23:36:25.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:4:0)
[23:36:25.365] Timer tick, asking Buildbucket for the build status
[23:36:25.545] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:25.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:5:0) after 1m0s
[23:37:25.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:5:0)
[23:37:25.596] Timer tick, asking Buildbucket for the build status
[23:37:25.823] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:25.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:6:0) after 1m0s
[23:38:25.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:6:0)
[23:38:25.858] Timer tick, asking Buildbucket for the build status
[23:38:26.029] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:26.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:7:0) after 1m0s
[23:39:26.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:7:0)
[23:39:26.044] Timer tick, asking Buildbucket for the build status
[23:39:26.270] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:26.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:8:0) after 1m0s
[23:40:26.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:8:0)
[23:40:26.477] Timer tick, asking Buildbucket for the build status
[23:40:26.600] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:26.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:9:0) after 1m0s
[23:41:26.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:9:0)
[23:41:26.678] Timer tick, asking Buildbucket for the build status
[23:41:26.775] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:26.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:10:0) after 1m0s
[23:42:26.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:10:0)
[23:42:26.819] Timer tick, asking Buildbucket for the build status
[23:42:26.883] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:26.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:11:0) after 1m0s
[23:43:26.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:11:0)
[23:43:26.902] Timer tick, asking Buildbucket for the build status
[23:43:27.033] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:27.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:12:0) after 1m0s
[23:44:27.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:12:0)
[23:44:27.129] Timer tick, asking Buildbucket for the build status
[23:44:27.226] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:27.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:13:0) after 1m0s
[23:45:27.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:13:0)
[23:45:27.287] Timer tick, asking Buildbucket for the build status
[23:45:27.777] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:27.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:14:0) after 1m0s
[23:46:27.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:14:0)
[23:46:27.831] Timer tick, asking Buildbucket for the build status
[23:46:27.927] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:27.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:15:0) after 1m0s
[23:47:27.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:15:0)
[23:47:27.920] Timer tick, asking Buildbucket for the build status
[23:47:28.012] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:28.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:16:0) after 1m0s
[23:48:28.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:16:0)
[23:48:28.037] Timer tick, asking Buildbucket for the build status
[23:48:28.110] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:28.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:17:0) after 1m0s
[23:49:28.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:17:0)
[23:49:28.134] Timer tick, asking Buildbucket for the build status
[23:49:28.258] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:28.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:18:0) after 1m0s
[23:50:28.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:18:0)
[23:50:28.287] Timer tick, asking Buildbucket for the build status
[23:50:28.535] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:28.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:19:0) after 1m0s
[23:51:28.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:19:0)
[23:51:28.554] Timer tick, asking Buildbucket for the build status
[23:51:28.618] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:28.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:20:0) after 1m0s
[23:52:28.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:20:0)
[23:52:28.648] Timer tick, asking Buildbucket for the build status
[23:52:29.542] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:29.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:21:0) after 1m0s
[23:53:29.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:21:0)
[23:53:29.566] Timer tick, asking Buildbucket for the build status
[23:53:29.759] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:29.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:22:0) after 1m0s
[23:54:29.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:22:0)
[23:54:29.795] Timer tick, asking Buildbucket for the build status
[23:54:29.973] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:29.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:23:0) after 1m0s
[23:55:29.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:23:0)
[23:55:29.998] Timer tick, asking Buildbucket for the build status
[23:55:30.210] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:30.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:24:0) after 1m0s
[23:56:30.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:24:0)
[23:56:30.283] Timer tick, asking Buildbucket for the build status
[23:56:30.414] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:30.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:25:0) after 1m0s
[23:57:30.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:25:0)
[23:57:30.459] Timer tick, asking Buildbucket for the build status
[23:57:30.657] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:30.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:26:0) after 1m0s
[23:58:30.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:26:0)
[23:58:30.676] Timer tick, asking Buildbucket for the build status
[23:58:30.847] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:30.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:27:0) after 1m0s
[23:59:30.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:27:0)
[23:59:30.868] Timer tick, asking Buildbucket for the build status
[23:59:31.902] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:31.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:28:0) after 1m0s
[00:00:31.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:28:0)
[00:00:31.904] Timer tick, asking Buildbucket for the build status
[00:00:32.093] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:32.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:29:0) after 1m0s
[00:01:32.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:29:0)
[00:01:32.122] Timer tick, asking Buildbucket for the build status
[00:01:32.312] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:32.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:30:0) after 1m0s
[00:02:32.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:30:0)
[00:02:32.352] Timer tick, asking Buildbucket for the build status
[00:02:32.481] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:32.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:31:0) after 1m0s
[00:03:32.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:31:0)
[00:03:32.572] Timer tick, asking Buildbucket for the build status
[00:03:33.343] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:33.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:32:0) after 1m0s
[00:04:33.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:32:0)
[00:04:33.362] Timer tick, asking Buildbucket for the build status
[00:04:33.946] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:33.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:33:0) after 1m0s
[00:05:33.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:33:0)
[00:05:33.985] Timer tick, asking Buildbucket for the build status
[00:05:34.507] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:34.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:34:0) after 1m0s
[00:06:34.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:34:0)
[00:06:34.536] Timer tick, asking Buildbucket for the build status
[00:06:34.717] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:34.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:35:0) after 1m0s
[00:07:34.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:35:0)
[00:07:34.762] Timer tick, asking Buildbucket for the build status
[00:07:34.898] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:34.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:36:0) after 1m0s
[00:08:34.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:36:0)
[00:08:34.900] Timer tick, asking Buildbucket for the build status
[00:08:36.133] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:36.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:37:0) after 1m0s
[00:09:36.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:37:0)
[00:09:36.181] Timer tick, asking Buildbucket for the build status
[00:09:36.393] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:36.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:38:0) after 1m0s
[00:10:36.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:38:0)
[00:10:36.452] Timer tick, asking Buildbucket for the build status
[00:10:36.559] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:36.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:39:0) after 1m0s
[00:11:36.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:39:0)
[00:11:36.612] Timer tick, asking Buildbucket for the build status
[00:11:37.006] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:37.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:40:0) after 1m0s
[00:12:37.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:40:0)
[00:12:37.111] Timer tick, asking Buildbucket for the build status
[00:12:37.194] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:37.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:41:0) after 1m0s
[00:13:37.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:41:0)
[00:13:37.216] Timer tick, asking Buildbucket for the build status
[00:13:37.308] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:37.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:42:0) after 1m0s
[00:14:37.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:42:0)
[00:14:37.345] Timer tick, asking Buildbucket for the build status
[00:14:37.499] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:37.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:43:0) after 1m0s
[00:15:37.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:43:0)
[00:15:37.536] Timer tick, asking Buildbucket for the build status
[00:15:37.700] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:37.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:44:0) after 1m0s
[00:16:37.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:44:0)
[00:16:37.732] Timer tick, asking Buildbucket for the build status
[00:16:37.973] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:37.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:45:0) after 1m0s
[00:17:38.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:45:0)
[00:17:38.043] Timer tick, asking Buildbucket for the build status
[00:17:38.222] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:38.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:46:0) after 1m0s
[00:18:38.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:46:0)
[00:18:38.272] Timer tick, asking Buildbucket for the build status
[00:18:43.989] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:43.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:47:0) after 1m0s
[00:19:44.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:47:0)
[00:19:44.039] Timer tick, asking Buildbucket for the build status
[00:19:44.191] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:44.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:48:0) after 1m0s
[00:20:44.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:48:0)
[00:20:44.306] Timer tick, asking Buildbucket for the build status
[00:20:44.831] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:44.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:49:0) after 1m0s
[00:21:44.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:49:0)
[00:21:44.931] Timer tick, asking Buildbucket for the build status
[00:21:45.108] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:45.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:50:0) after 1m0s
[00:22:45.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:50:0)
[00:22:45.211] Timer tick, asking Buildbucket for the build status
[00:22:45.338] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:45.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:51:0) after 1m0s
[00:23:45.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:51:0)
[00:23:45.367] Timer tick, asking Buildbucket for the build status
[00:23:45.483] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:45.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:52:0) after 1m0s
[00:24:45.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:52:0)
[00:24:45.526] Timer tick, asking Buildbucket for the build status
[00:24:45.706] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:45.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:53:0) after 1m0s
[00:25:45.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:53:0)
[00:25:45.768] Timer tick, asking Buildbucket for the build status
[00:25:45.860] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:45.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:54:0) after 1m0s
[00:26:45.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:54:0)
[00:26:45.913] Timer tick, asking Buildbucket for the build status
[00:26:45.984] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:45.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:55:0) after 1m0s
[00:27:46.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:55:0)
[00:27:46.089] Timer tick, asking Buildbucket for the build status
[00:27:46.743] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:46.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:56:0) after 1m0s
[00:28:46.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:56:0)
[00:28:46.828] Timer tick, asking Buildbucket for the build status
[00:28:46.989] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:46.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:57:0) after 1m0s
[00:29:47.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:57:0)
[00:29:47.687] Timer tick, asking Buildbucket for the build status
[00:29:49.265] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:49.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:58:0) after 1m0s
[00:30:49.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:58:0)
[00:30:49.312] Timer tick, asking Buildbucket for the build status
[00:30:49.463] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:49.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:59:0) after 1m0s
[00:31:49.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:59:0)
[00:31:49.643] Timer tick, asking Buildbucket for the build status
[00:31:49.835] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:49.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:60:0) after 1m0s
[00:32:49.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:60:0)
[00:32:49.903] Timer tick, asking Buildbucket for the build status
[00:32:50.014] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:50.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:61:0) after 1m0s
[00:33:50.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:61:0)
[00:33:50.141] Timer tick, asking Buildbucket for the build status
[00:33:50.635] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:50.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:62:0) after 1m0s
[00:34:50.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:62:0)
[00:34:50.775] Timer tick, asking Buildbucket for the build status
[00:34:50.973] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:50.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:63:0) after 1m0s
[00:35:51.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:63:0)
[00:35:51.087] Timer tick, asking Buildbucket for the build status
[00:35:51.269] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:51.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:64:0) after 1m0s
[00:36:51.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:64:0)
[00:36:51.413] Timer tick, asking Buildbucket for the build status
[00:36:51.547] Build 8856818321366935728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:51.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022248383633744176:65:0) after 1m0s
[00:37:42.952] Received PubSub notification, asking Buildbucket for the build status
[00:37:43.087] Build 8856818321366935728: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:37:43.087] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1611880661408166",
"created_by": "project:chromium",
"created_ts": "1611876864044433",
"id": "8856818321366935728",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"923385187510d1b34e0b8176816139794fb4aa67\"}}",
"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\": \"923385187510d1b34e0b8176816139794fb4aa67\", \"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-us-central1-b-32-1-nlke\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17057, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"a408ce8349289c8fe457a5ebccb8c4cbac7b9c3f\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"923385187510d1b34e0b8176816139794fb4aa67\", \"got_revision_cp\": \"refs/heads/master@{#848314}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"609f77888b667dbb70edc38715437b69f15a4dc4\", \"got_v8_revision_cp\": \"refs/heads/9.0.99@{#1}\", \"got_webrtc_revision\": \"54b925cfc2cd7c2f8f83e8e484f281aa87b92dfc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33090}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"prev_got_revision\": \"5273cd642100d473218b28487c6e3fcdb450b008\", \"prev_got_revision_cp\": \"refs/heads/master@{#848289}\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"923385187510d1b34e0b8176816139794fb4aa67\", \"swarm_hashes_refs/heads/master(at){#848314}_without_patch\": {\"absl_hardening_tests\": \"48558cf6423274e2817ab9c28190051fc16cda6c\", \"accessibility_unittests\": \"afcf5374e05d389aa6543bd638485dcaa113cf4b\", \"angle_unittests\": \"338edd9004e707ffb209a6195633d251654939d0\", \"app_shell_unittests\": \"7b2b2699a28430b63df257ec109da04905cc3282\", \"aura_unittests\": \"133a05807cafa51e91dde643dfbd18cd13b00a32\", \"base_unittests\": \"c8c0f2067d0d98e19e355c577b47e4dfd7c29725\", \"base_util_unittests\": \"af4a6db8a5b14e0554ab68bc3754727f51c42b47\", \"blink_common_unittests\": \"cea4db0fc8712da191013b104f619569115564fd\", \"blink_fuzzer_unittests\": \"125a47602f2e1d1d42078f03c418bf65127c9a7e\", \"blink_heap_unittests\": \"2009c8e7c8aa9d244fd86c01e2e3915df570dce2\", \"blink_platform_unittests\": \"b36df4ebe5ea55fa7e6798665baf624830f5d0f1\", \"blink_unittests\": \"440613ae9e7df29f20fc3783b98d9aaaf6247697\", \"boringssl_crypto_tests\": \"8178d6f6a490bfbcf32527cb87dc7d84aeb1a2fb\", \"boringssl_ssl_tests\": \"0d6224c230a6a3747618fc48427f087a2c06bf2d\", \"browser_switcher_bho_unittests\": \"a838f45cf43d24a2720840ad4f21556534edcd85\", \"browser_tests\": \"f506f15301c5b31419e33e9a7991905e516fb672\", \"capture_unittests\": \"81e5e7ff8826441ba1247159a8709a41a3787f4f\", \"cast_unittests\": \"4aceefc11742789fc8e24973bdec796e6ced6bfb\", \"cc_unittests\": \"f103fb0e72f6a544bf5fd690eff977d6871f02e9\", \"chrome_app_unittests\": \"6cdcb2ecdb01805a1775eee9eeb172f5cde20614\", \"chrome_cleaner_unittests\": \"3faae100cd2a8fbcbfd42f1d22440ea7dd67d4c4\", \"chrome_elf_unittests\": \"7362fc6e8a89096a7f1e02597087fed99e7739ba\", \"chromedriver_unittests\": \"0a97eb51d06c1fb79013f66e2730c4462b1e8c50\", \"components_browsertests\": \"96dd963e10688ed4843c232de618a6258ad413f2\", \"components_unittests\": \"48864d7091eb9622e37e3ab83fbadcb40b413e8c\", \"compositor_unittests\": \"482603a2c0aa42ad39d1ac82520e3691339f2a88\", \"content_browsertests\": \"27cf1cc17cd6ab052f907f57c2fd1606796f5ba9\", \"content_unittests\": \"d17f48bf98f2290d9ff440b38e5168dfeac1a368\", \"courgette_unittests\": \"643f3a4b586cc5f71a8e9d84b4a6e62dc2628e68\", \"crashpad_tests\": \"c2b51085f9aa5f491653cec0306e566b68ccfc02\", \"cronet_tests\": \"d650fe9eec9d6588f913a33cdb0fcb343e010faa\", \"cronet_unittests\": \"749812702fbc0437814d7cdf7112b7e65149b67e\", \"crypto_unittests\": \"558b6018ac56eb3762068155c111f1e60f33be02\", \"delayloads_unittests\": \"8ede640ed963240026bab4c2b20fd5730edc3fc7\", \"device_unittests\": \"3cad8a9116bed6f2e2b9dde380a4b3bf7e272b08\", \"display_unittests\": \"0cd32f78052b11992c4c550c8295634c6fe3fae2\", \"elevation_service_unittests\": \"289f69e22f0167f3f386d70eddf1dc11a4e8c127\", \"events_unittests\": \"b56ca2b94e7532a053cf7a3929b3087c8b6e1f4e\", \"extensions_browsertests\": \"a8a4b29d62a1d28dae92af3f940bbcee749fe09d\", \"extensions_unittests\": \"9701515b5867b10468f4e95153cb4538eebd3b53\", \"filesystem_service_unittests\": \"920e4f619ba20e123113b7389816a00b3cd6c202\", \"gcm_unit_tests\": \"db9e64b010f21fe5c381849ca7b791b08cfad940\", \"gcp_unittests\": \"0575c192e1006cc7784dd53b66b69b6f8dd0551d\", \"gfx_unittests\": \"a2a4e1825dec19c6a0274e36d98d64fd16441047\", \"gin_unittests\": \"6169f99c6cf535f9d87f2de4c2b4cc0412bcd63f\", \"google_apis_unittests\": \"390bdd8f15b306913bd44ac9111ae36d1d0efede\", \"gpu_unittests\": \"9a85a11d911feb34cc3f2e68023cc47d961104ac\", \"gwp_asan_unittests\": \"08889ec643fa828318b178efc5e2ee027570dc65\", \"headless_browsertests\": \"e9eca1497d44b4316368d813a0dd47c51e648c41\", \"headless_unittests\": \"3d146a26ea427df0ad6c8ac618decadfa77a64b6\", \"install_static_unittests\": \"802f2f67dc5b93eff475a8a7ea1af3f42f0c80a9\", \"installer_util_unittests\": \"b8a7a69240e8fbaeec2bb13c7d2e85a325af5c2b\", \"interactive_ui_tests\": \"7e2448444044a57fc1b9adb0feb1135ddb74df0c\", \"ipc_tests\": \"d3909d53e8ce23f1909c9bae7cbdb5ccc96021ea\", \"jingle_unittests\": \"c2a44ded7aa35d777dda0662013521281a700dbd\", \"latency_unittests\": \"305d89d0684b31fc2cb87a7a58ad5f0443f5ef7f\", \"libjingle_xmpp_unittests\": \"82ce51f991ae7526da187d82a9dd664decfeb870\", \"liburlpattern_unittests\": \"5e3ab39c88e4d7cbff5dfae14d288ab9f23963b0\", \"media_blink_unittests\": \"4f85fbb8636ab5b9772ce5669d2eb1d309641b00\", \"media_unittests\": \"e1ced6699f29f696e5ea1e7b9130fef1987b6b01\", \"message_center_unittests\": \"ab253e02da0b566ea829ae4d58fe6736b4f61989\", \"midi_unittests\": \"fdefc70b123764c733eaa369419dbd868f3f0e5e\", \"mojo_core_unittests\": \"5c6c8c3cce8f93ecb938db38cab6954589038184\", \"mojo_unittests\": \"024d16582bf0c4092cd488ba5ad016c1b066705d\", \"nacl_loader_unittests\": \"3da3577934dddaefd3d1ec937a4a987866d4800c\", \"native_theme_unittests\": \"0ae20376a2e4e1dd1eb41ac7211dece93c10534a\", \"net_unittests\": \"0bc030b15290e5bf706eba694d45fe87ccb15304\", \"notification_helper_unittests\": \"8ba343f85e2ad8ad43b94bfa8bbe47f4f4e87e87\", \"pdf_unittests\": \"8768fd5ffdb9497e45adaee1434e22a80183f2ce\", \"perfetto_unittests\": \"03553a4b7ed4b34b01ac3c3b9bf476532675d839\", \"ppapi_unittests\": \"4fd6ce209e7bb0cbf0715ea074db9e801a72e953\", \"printing_unittests\": \"1660c85027bfb8f68c5dad2c974741cde07837fe\", \"remoting_unittests\": \"bacb57c77d19b923550248a15816af6b9e458728\", \"sbox_integration_tests\": \"61e63a800d728c0eb9c95290a94f7fd9eaaa13f1\", \"sbox_unittests\": \"8e80308e9456b57abe7453833255fccf58d29d65\", \"sbox_validation_tests\": \"1da2cea11bf92eeba94bd45704eaedf054efad03\", \"service_manager_unittests\": \"6e6c1dfd8a171ee39369f8ed1d4f6b04dd2992df\", \"services_unittests\": \"b6b0d361452bcda4b6004a22b347a97b6a606071\", \"setup_unittests\": \"6dc08076484d45abcee1c137ba4c4a005f59047a\", \"shell_dialogs_unittests\": \"a87f7c5be827b94ea66e4844b12fd5f034535d7d\", \"skia_unittests\": \"03001001566a24dd69c41ee70271ecb32dc662ad\", \"snapshot_unittests\": \"faf453dd0a206c1f47eefaab537e308aefc88b85\", \"sql_unittests\": \"f896b660d19a62073de0a60066ff88445c1dd63a\", \"storage_unittests\": \"b35132b536a687d110900af7028aa54a28b3dc44\", \"sync_integration_tests\": \"5400effa19c17a4ffab5212e95dd0f980b82160b\", \"traffic_annotation_auditor_unittests\": \"d899a65493039465f1b0186f56a3fed4873a0ec7\", \"ui_base_unittests\": \"0e72afb71ffcdcb365d69ade45b1fbdac68bd96e\", \"ui_touch_selection_unittests\": \"ee5e277fafaf80b0a61d8b0f7912b1cde4f1dbab\", \"unit_tests\": \"81c58b25d0a74cfcdb167c91d23c1ecac7583d4e\", \"updater_tests\": \"1e813a1fd7bfdbcd3610e567664046c0122f61f4\", \"url_unittests\": \"47fc184b8c9b2ef34261ee600c196afaea2d465f\", \"views_unittests\": \"d9c9ac47bfb3a110a7c6e4e8e076c6985c3866af\", \"viz_unittests\": \"db550824eaa033d0eb6099a003a1b8f02e2ef7d3\", \"vr_common_unittests\": \"694b01b193efb955069b738085b06b6eed24a4d5\", \"vr_pixeltests\": \"3c1bcfa57c12dc8b927bb18d205d04ad53d6aa44\", \"weblayer_browsertests\": \"ec5bf6ec90038076abafe09f36c9887208c98cc1\", \"weblayer_unittests\": \"bf61359430251bdc27f43021ca14cff7f4a615b7\", \"wm_unittests\": \"9c1e25fd33e062b57b7f060b21abea245ffbe484\", \"wtf_unittests\": \"f671249af1b7f343bd3668082ca1cbe53a78cbf7\", \"zlib_unittests\": \"12ef791692b2ff61afbbe318ea00150158cfdd81\", \"zucchini_unittests\": \"b85dfa3c68d6bb43e5e448b3fa3accfb358a8d32\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-nlke\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611876867399184",
"status": "COMPLETED",
"status_changed_ts": "1611880662032616",
"tags": [
"build_address:luci.chromium.ci/win-asan/17057",
"builder:win-asan",
"buildset:commit/git/923385187510d1b34e0b8176816139794fb4aa67",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/923385187510d1b34e0b8176816139794fb4aa67",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022248383633744176",
"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/8856818321366935728/+/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:51642aa359682e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611880662087068",
"url": "https://ci.chromium.org/b/8856818321366935728",
"utcnow_ts": "1611880663065728"
}
}
[00:37:43.087] Invocation finished in 1h3m20.588661458s with status SUCCEEDED