[01:16:48.113] New invocation is queued and will start shortly
[01:16:49.183] Starting the invocation (attempt 1)
[01:16:49.227] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:16:49.227] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:16:49.228] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022966715239825536",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f26f7934cc4eadaf8df143133ebe2b0b73848484\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022966715239825536",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f26f7934cc4eadaf8df143133ebe2b0b73848484",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f26f7934cc4eadaf8df143133ebe2b0b73848484",
"gitiles_ref:refs/heads/master"
]
}
[01:16:50.345] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611191809398534",
"id": "8857536653226621392",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f26f7934cc4eadaf8df143133ebe2b0b73848484\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611191810037015",
"tags": [
"build_address:luci.chromium.ci/win-asan/16921",
"builder:win-asan",
"buildset:commit/git/f26f7934cc4eadaf8df143133ebe2b0b73848484",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f26f7934cc4eadaf8df143133ebe2b0b73848484",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022966715239825536",
"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/8857536653226621392/+/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": "1611191810037371",
"url": "https://ci.chromium.org/b/8857536653226621392",
"utcnow_ts": "1611191810337838"
}
}
[01:16:50.345] Task URL: https://ci.chromium.org/b/8857536653226621392
[01:16:50.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:2:0) after 1m0s
[01:16:53.351] Received PubSub notification, asking Buildbucket for the build status
[01:16:53.432] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:50.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:2:0)
[01:17:50.329] Timer tick, asking Buildbucket for the build status
[01:17:50.423] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:50.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:4:0) after 1m0s
[01:18:50.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:4:0)
[01:18:50.467] Timer tick, asking Buildbucket for the build status
[01:18:50.717] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:50.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:5:0) after 1m0s
[01:19:50.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:5:0)
[01:19:50.802] Timer tick, asking Buildbucket for the build status
[01:19:50.921] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:50.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:6:0) after 1m0s
[01:20:51.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:6:0)
[01:20:51.006] Timer tick, asking Buildbucket for the build status
[01:20:51.326] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:51.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:7:0) after 1m0s
[01:21:51.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:7:0)
[01:21:51.501] Timer tick, asking Buildbucket for the build status
[01:21:51.710] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:51.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:8:0) after 1m0s
[01:22:51.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:8:0)
[01:22:51.732] Timer tick, asking Buildbucket for the build status
[01:22:51.790] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:51.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:9:0) after 1m0s
[01:23:51.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:9:0)
[01:23:51.777] Timer tick, asking Buildbucket for the build status
[01:23:51.871] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:51.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:10:0) after 1m0s
[01:24:52.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:10:0)
[01:24:52.017] Timer tick, asking Buildbucket for the build status
[01:24:52.072] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:52.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:11:0) after 1m0s
[01:25:52.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:11:0)
[01:25:52.152] Timer tick, asking Buildbucket for the build status
[01:25:52.219] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:52.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:12:0) after 1m0s
[01:26:52.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:12:0)
[01:26:52.254] Timer tick, asking Buildbucket for the build status
[01:26:52.594] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:52.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:13:0) after 1m0s
[01:27:52.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:13:0)
[01:27:52.665] Timer tick, asking Buildbucket for the build status
[01:27:52.815] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:52.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:14:0) after 1m0s
[01:28:52.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:14:0)
[01:28:52.876] Timer tick, asking Buildbucket for the build status
[01:28:53.162] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:53.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:15:0) after 1m0s
[01:29:53.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:15:0)
[01:29:53.192] Timer tick, asking Buildbucket for the build status
[01:29:53.254] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:53.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:16:0) after 1m0s
[01:30:53.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:16:0)
[01:30:53.277] Timer tick, asking Buildbucket for the build status
[01:30:53.338] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:53.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:17:0) after 1m0s
[01:31:53.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:17:0)
[01:31:53.325] Timer tick, asking Buildbucket for the build status
[01:31:53.449] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:53.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:18:0) after 1m0s
[01:32:53.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:18:0)
[01:32:53.471] Timer tick, asking Buildbucket for the build status
[01:32:53.694] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:53.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:19:0) after 1m0s
[01:33:53.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:19:0)
[01:33:53.794] Timer tick, asking Buildbucket for the build status
[01:33:53.938] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:53.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:20:0) after 1m0s
[01:34:53.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:20:0)
[01:34:53.982] Timer tick, asking Buildbucket for the build status
[01:34:54.103] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:54.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:21:0) after 1m0s
[01:35:54.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:21:0)
[01:35:54.222] Timer tick, asking Buildbucket for the build status
[01:35:54.514] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:54.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:22:0) after 1m0s
[01:36:54.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:22:0)
[01:36:54.564] Timer tick, asking Buildbucket for the build status
[01:36:54.677] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:54.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:23:0) after 1m0s
[01:37:54.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:23:0)
[01:37:54.722] Timer tick, asking Buildbucket for the build status
[01:37:54.878] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:54.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:24:0) after 1m0s
[01:38:55.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:24:0)
[01:38:55.146] Timer tick, asking Buildbucket for the build status
[01:38:55.283] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:55.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:25:0) after 1m0s
[01:39:55.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:25:0)
[01:39:55.334] Timer tick, asking Buildbucket for the build status
[01:39:55.431] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:55.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:26:0) after 1m0s
[01:40:55.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:26:0)
[01:40:55.592] Timer tick, asking Buildbucket for the build status
[01:40:55.978] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:55.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:27:0) after 1m0s
[01:41:56.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:27:0)
[01:41:56.036] Timer tick, asking Buildbucket for the build status
[01:41:56.220] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:56.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:28:0) after 1m0s
[01:42:56.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:28:0)
[01:42:56.309] Timer tick, asking Buildbucket for the build status
[01:42:56.784] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:56.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:29:0) after 1m0s
[01:43:56.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:29:0)
[01:43:56.761] Timer tick, asking Buildbucket for the build status
[01:43:56.887] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:56.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:30:0) after 1m0s
[01:44:56.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:30:0)
[01:44:56.930] Timer tick, asking Buildbucket for the build status
[01:44:57.181] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:57.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:31:0) after 1m0s
[01:45:57.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:31:0)
[01:45:57.193] Timer tick, asking Buildbucket for the build status
[01:45:57.338] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:57.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:32:0) after 1m0s
[01:46:57.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:32:0)
[01:46:57.516] Timer tick, asking Buildbucket for the build status
[01:46:57.653] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:57.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:33:0) after 1m0s
[01:47:57.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:33:0)
[01:47:57.712] Timer tick, asking Buildbucket for the build status
[01:47:57.928] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:57.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:34:0) after 1m0s
[01:48:57.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:34:0)
[01:48:57.959] Timer tick, asking Buildbucket for the build status
[01:48:58.495] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:58.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:35:0) after 1m0s
[01:49:58.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:35:0)
[01:49:58.523] Timer tick, asking Buildbucket for the build status
[01:49:58.636] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:58.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:36:0) after 1m0s
[01:50:58.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:36:0)
[01:50:58.792] Timer tick, asking Buildbucket for the build status
[01:50:58.864] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:58.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:37:0) after 1m0s
[01:51:59.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:37:0)
[01:51:59.010] Timer tick, asking Buildbucket for the build status
[01:51:59.136] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:59.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:38:0) after 1m0s
[01:52:59.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:38:0)
[01:52:59.182] Timer tick, asking Buildbucket for the build status
[01:52:59.477] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:59.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:39:0) after 1m0s
[01:53:59.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:39:0)
[01:53:59.524] Timer tick, asking Buildbucket for the build status
[01:53:59.754] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:59.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:40:0) after 1m0s
[01:54:59.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:40:0)
[01:54:59.966] Timer tick, asking Buildbucket for the build status
[01:55:00.676] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:00.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:41:0) after 1m0s
[01:56:00.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:41:0)
[01:56:00.861] Timer tick, asking Buildbucket for the build status
[01:56:00.954] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:00.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:42:0) after 1m0s
[01:57:01.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:42:0)
[01:57:01.105] Timer tick, asking Buildbucket for the build status
[01:57:01.329] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:01.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:43:0) after 1m0s
[01:58:01.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:43:0)
[01:58:01.383] Timer tick, asking Buildbucket for the build status
[01:58:01.937] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:01.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:44:0) after 1m0s
[01:59:02.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:44:0)
[01:59:02.005] Timer tick, asking Buildbucket for the build status
[01:59:02.082] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:02.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:45:0) after 1m0s
[02:00:02.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:45:0)
[02:00:02.089] Timer tick, asking Buildbucket for the build status
[02:00:02.426] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:02.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:46:0) after 1m0s
[02:01:02.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:46:0)
[02:01:02.514] Timer tick, asking Buildbucket for the build status
[02:01:03.079] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:03.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:47:0) after 1m0s
[02:02:03.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:47:0)
[02:02:03.101] Timer tick, asking Buildbucket for the build status
[02:02:03.917] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:03.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:48:0) after 1m0s
[02:03:04.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:48:0)
[02:03:04.131] Timer tick, asking Buildbucket for the build status
[02:03:04.270] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:04.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:49:0) after 1m0s
[02:04:04.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:49:0)
[02:04:04.279] Timer tick, asking Buildbucket for the build status
[02:04:04.397] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:04.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:50:0) after 1m0s
[02:05:04.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:50:0)
[02:05:04.439] Timer tick, asking Buildbucket for the build status
[02:05:04.706] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:04.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:51:0) after 1m0s
[02:06:04.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:51:0)
[02:06:04.792] Timer tick, asking Buildbucket for the build status
[02:06:04.931] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:04.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:52:0) after 1m0s
[02:07:04.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:52:0)
[02:07:04.963] Timer tick, asking Buildbucket for the build status
[02:07:05.253] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:05.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:53:0) after 1m0s
[02:08:05.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:53:0)
[02:08:05.367] Timer tick, asking Buildbucket for the build status
[02:08:05.485] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:05.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:54:0) after 1m0s
[02:09:05.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:54:0)
[02:09:05.547] Timer tick, asking Buildbucket for the build status
[02:09:05.670] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:05.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:55:0) after 1m0s
[02:10:05.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:55:0)
[02:10:05.743] Timer tick, asking Buildbucket for the build status
[02:10:06.063] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:06.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:56:0) after 1m0s
[02:11:06.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:56:0)
[02:11:06.132] Timer tick, asking Buildbucket for the build status
[02:11:06.296] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:06.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:57:0) after 1m0s
[02:12:06.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:57:0)
[02:12:06.395] Timer tick, asking Buildbucket for the build status
[02:12:06.562] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:06.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:58:0) after 1m0s
[02:13:06.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:58:0)
[02:13:06.647] Timer tick, asking Buildbucket for the build status
[02:13:07.301] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:07.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:59:0) after 1m0s
[02:14:07.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:59:0)
[02:14:07.405] Timer tick, asking Buildbucket for the build status
[02:14:07.490] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:07.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:60:0) after 1m0s
[02:15:07.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:60:0)
[02:15:07.609] Timer tick, asking Buildbucket for the build status
[02:15:07.822] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:07.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:61:0) after 1m0s
[02:16:07.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:61:0)
[02:16:07.847] Timer tick, asking Buildbucket for the build status
[02:16:07.967] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:07.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:62:0) after 1m0s
[02:17:08.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:62:0)
[02:17:08.050] Timer tick, asking Buildbucket for the build status
[02:17:08.187] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:08.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:63:0) after 1m0s
[02:18:08.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:63:0)
[02:18:08.252] Timer tick, asking Buildbucket for the build status
[02:18:08.403] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:08.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:64:0) after 1m0s
[02:19:08.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:64:0)
[02:19:08.425] Timer tick, asking Buildbucket for the build status
[02:19:08.643] Build 8857536653226621392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:08.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022966715239825536:65:0) after 1m0s
[02:19:51.370] Received PubSub notification, asking Buildbucket for the build status
[02:19:51.453] Build 8857536653226621392: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:19:51.453] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611195590066648",
"created_by": "project:chromium",
"created_ts": "1611191809398534",
"id": "8857536653226621392",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f26f7934cc4eadaf8df143133ebe2b0b73848484\"}}",
"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\": \"f26f7934cc4eadaf8df143133ebe2b0b73848484\", \"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-0-gbsn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16921, \"clang_revision\": \"llvmorg-12-init-16548-gf1d5cbbd-1\", \"got_angle_revision\": \"bb3adfa42822d73420ca6a8e96863dcb6a35fc65\", \"got_dawn_revision\": \"762814bd922932d3d9da9b220463b6ce5f167d76\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"f26f7934cc4eadaf8df143133ebe2b0b73848484\", \"got_revision_cp\": \"refs/heads/master@{#845442}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1d5174b9dc3be500d63daf227b163b5e86bbc7b5\", \"got_v8_revision\": \"f9759b6f3a4521d370e66aec8240fcc731cd8fdd\", \"got_v8_revision_cp\": \"refs/heads/9.0.31@{#1}\", \"got_webrtc_revision\": \"0be1846477949c472a7a2bf42134a7bb08570ac4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33046}\", \"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\": \"f26f7934cc4eadaf8df143133ebe2b0b73848484\", \"swarm_hashes_refs/heads/master(at){#845442}_without_patch\": {\"absl_hardening_tests\": \"3f014dd4de63d7e9a7ca29581ec8f37e7812391b\", \"accessibility_unittests\": \"598735426314f919a1d4eafc9d760952ff077da2\", \"angle_unittests\": \"e58ba6ec414c06676f3ee771c233c24103bbbb76\", \"app_shell_unittests\": \"fd45625d94f829ba9d11933d557c2055c7b8c161\", \"aura_unittests\": \"3bb8fff756d9fd35861c80770268263edbc5837d\", \"base_unittests\": \"399dc1aa7441b22b74526936c93776f0fa70ebf9\", \"base_util_unittests\": \"5feac01c8bcee32e9be61b4d234f5c57ccfd5f14\", \"blink_common_unittests\": \"6cdbda3190459389f1b40994da72030331f5abc0\", \"blink_fuzzer_unittests\": \"378985d49224f04580e65d3452d1058d2092242d\", \"blink_heap_unittests\": \"98d289752572cfd4b314897d653e255e566aba50\", \"blink_platform_unittests\": \"f5f482fcaf6a193df8a43e6ea284cb956c8512e8\", \"blink_unittests\": \"588654edeb74158cbb54cea6f984f47189212523\", \"boringssl_crypto_tests\": \"5282675f6387eadd66b96136ef4a12cd746074b7\", \"boringssl_ssl_tests\": \"83d7251e3df241388c47fb434a026c33958d9cf4\", \"browser_switcher_bho_unittests\": \"4cb24c4ec8ecf478b004b6831b2284a28e95bc0b\", \"browser_tests\": \"6b3f00df8917141539486aa661904a5fc5a34ef1\", \"capture_unittests\": \"f55bdb4a7983a9218dcf86d0359bbf5d37b92cdf\", \"cast_unittests\": \"32fb6f71adcba94f047d1838a85686b4a99d998a\", \"cc_unittests\": \"39f52d2a6f3f2610981a76e1c4cb79cd28006810\", \"chrome_app_unittests\": \"e17782ff86444cbcd76a0bc112c76a24617351dd\", \"chrome_cleaner_unittests\": \"fd66f9d151ec933b8528522386be4e9c238e59d9\", \"chrome_elf_unittests\": \"ce455246ce35479cfe86a8a8891183784a55e80f\", \"chromedriver_unittests\": \"db448435e7e12937345d4a87dabe6abcd482f0ca\", \"components_browsertests\": \"e3c595ad552e5881a2df4f050c87bbface3db9ab\", \"components_unittests\": \"7dadb26035d1b64a891a989f0d2c013d3c64d06a\", \"compositor_unittests\": \"37b8526335d89a0c4bec57ef10031f24c051a801\", \"content_browsertests\": \"5ac6ec63ac1b002ddda5c02652ce0885215ff15b\", \"content_unittests\": \"a95f71e7216cf4f311e13b02fa8b8801e3453f01\", \"courgette_unittests\": \"c5b00908647a4357e4222ca8171329e3f64f76b7\", \"crashpad_tests\": \"487bb6a15a562ca0e9a2b95ec465163812ec74b6\", \"cronet_tests\": \"279086f480b6a530c781d4286d19d5ed59019cd4\", \"cronet_unittests\": \"6b5b29ddbad55b9d5efd0dab4c61c9483c90084c\", \"crypto_unittests\": \"e46087da67e759acd46f104161063dd549077bd0\", \"delayloads_unittests\": \"c49690a4f70d238d66ccb4193cef84fd09dab7c0\", \"device_unittests\": \"4d01bd63586508ac004de77ad0959ca6f2a50dd0\", \"display_unittests\": \"e8982c9255cb79a4829aa66e2134cf150d798579\", \"elevation_service_unittests\": \"968b8f309edcb9b827c91c8cf6a8d86e1a3cecc8\", \"events_unittests\": \"2f0c0dd0b8ec3958d33830ea9947b64c48b83cf5\", \"extensions_browsertests\": \"f50ecd47d9b7a00feb5e55ec199aeafbd7c53afa\", \"extensions_unittests\": \"7af9334e628971e2806cf85ba98a8c309ec449af\", \"filesystem_service_unittests\": \"d6cbffe41e97ba59167acbde9316baeb3774a0c3\", \"gcm_unit_tests\": \"0495e84f4b48f8d8ff14e083dee46b559fe73b99\", \"gcp_unittests\": \"758b018bc00e88c3441b80783cec4199830a6f78\", \"gfx_unittests\": \"1380032a6d63ad0048320668725b78e100042f30\", \"gin_unittests\": \"ff298e1b0202ae56c195d16f0a9439fd13e026ae\", \"google_apis_unittests\": \"6af3186db5fa5f27855c330547aaf7babccbd0a9\", \"gpu_unittests\": \"dd8a17e124ea514485c3c38ed0cc2dfe745e3b8c\", \"gwp_asan_unittests\": \"fad6e72c1489819e4a502ca54f5b0e1441b362b6\", \"headless_browsertests\": \"a20cd289b644ae9776e54371a8ae66693cf42db6\", \"headless_unittests\": \"45f6b577f221ad1392ef446f5b40e54875af6967\", \"install_static_unittests\": \"4ead9c9be42be84a6464a752e092f6a9378bf739\", \"installer_util_unittests\": \"068f8546b2b16252e3f1a48b7d0cedeb248e3c17\", \"interactive_ui_tests\": \"a7ea935c9807d202c5f787f2b6ff7c7b3fa89e52\", \"ipc_tests\": \"08f57c08735b6748327c1975db26ff5072c3f182\", \"jingle_unittests\": \"b3ca369d9805bbeb905b498c328d518ea78fb07c\", \"latency_unittests\": \"a2acb5f7cdcbf89c5e85aa48d56897c29de9402b\", \"libjingle_xmpp_unittests\": \"5097ce313c5d1790e2408f0a67fc153873a7276f\", \"liburlpattern_unittests\": \"03158c6a4b87c58307eab8d953c9a22c715bdd5c\", \"media_blink_unittests\": \"c3723e1a90d3e2fd55d574d40f873e97f782d749\", \"media_unittests\": \"57a6ffc56867a10d041958bd9a62c7203d2a1eff\", \"message_center_unittests\": \"507ad20014afc4100b8e6369331fea9e329a4122\", \"midi_unittests\": \"fa184b076c6f8a2eee199d0145580ee466eb7c3d\", \"mojo_core_unittests\": \"935cf046560a69ed21f97cce7068e624a2c1b421\", \"mojo_unittests\": \"535d9a1dc9e5023b8be5555e178d2505478d092a\", \"nacl_loader_unittests\": \"0aa2e27473b2932697b86cc96a43af57c344f2a7\", \"native_theme_unittests\": \"e8b13523e5563fd39fb8ded4b943b73c87ace81c\", \"net_unittests\": \"69c5466cbf65b976c384945d17de0ef52f8fb22e\", \"notification_helper_unittests\": \"a260e8548ac5f1f56d1db2f3dd3cdfb59b5005f1\", \"pdf_unittests\": \"546e87873acda22c2204d9e928f58645599728e3\", \"perfetto_unittests\": \"2454099c418a7747e4f8c8034b9dbc8906b8de27\", \"ppapi_unittests\": \"a1e58d19d6b4c316126c0eae3904bb4fccb9bd66\", \"printing_unittests\": \"65d880ec14212c15408c73fb6df1571e042a16d2\", \"remoting_unittests\": \"77aa3dad359213b1b29552c317baed60acd18577\", \"sbox_integration_tests\": \"d45ba21e08c9e70f45feb06eeea931dc50d56dc7\", \"sbox_unittests\": \"273c9bcb03b3181898bbc9451f77569dd3dbbbea\", \"sbox_validation_tests\": \"786e95c51bf0519cd4462eb660c474918f18583a\", \"service_manager_unittests\": \"ae30bb33de8146e4d14a42fcc6fc81ca26e7accc\", \"services_unittests\": \"473e74207537fa691adc9324a50b0a342fa4528c\", \"setup_unittests\": \"44f614b590101dd13a9baf0e8c131a01d0809d7b\", \"shell_dialogs_unittests\": \"6535052a1ed32a7133d95619048922f5910cd22d\", \"skia_unittests\": \"a72b52b8d1bf36073b4ab4f200d2ab9605c4ab73\", \"snapshot_unittests\": \"ae6c1128ccab29dbcc4acb4959fd523b52b928be\", \"sql_unittests\": \"465d2e510cbf2f7be3f5d9fd43dd47a0ca94b1ac\", \"storage_unittests\": \"490901d35708cfc4b9c754e4c3a5caee22b7b421\", \"sync_integration_tests\": \"aefd454aef3bf8e3e5cdab9b5ff8c7e1616b2575\", \"traffic_annotation_auditor_unittests\": \"84043274b40d333ec5db0325077b29290fdf5ce3\", \"ui_base_unittests\": \"0f7ad3810bd5c8a80d378fcefe84dfac046ba32c\", \"ui_touch_selection_unittests\": \"7c8096e59ccd13964cb9904792ae45f1f7c20bd4\", \"unit_tests\": \"5909a9f1f282dce187968f871cce0ec6166de8f1\", \"updater_tests\": \"3141524b1babc2e5eb23ef8592094a5850958965\", \"url_unittests\": \"b6ca0c25ee1539f9ed8d230599f9baf87cab6ee7\", \"views_unittests\": \"f49757cb38b98cd37c6df9d4f40db5890792879e\", \"viz_unittests\": \"1ea6f2d1176c5f7714be44ccd7dd37a63aaba9d6\", \"vr_common_unittests\": \"1c68d0a06059a555e5e1400523d542f81a8a9b41\", \"vr_pixeltests\": \"71d8c0ea5bf78ee381f95592baf994dc02332465\", \"weblayer_browsertests\": \"83193d9b446c4745aa21c48f1af9e4717d61d559\", \"weblayer_unittests\": \"ecfe6a1e3a3c5a40cfe02aec2836047b3e66dd78\", \"wm_unittests\": \"cb030b9c85c55912f8f0a1ed4de2d4794653c8df\", \"wtf_unittests\": \"3f473ce8ddea39474c67c85b03690dc38d16782e\", \"zlib_unittests\": \"e8484b0ce7e0b127838496686d5f68d394d1c969\", \"zucchini_unittests\": \"a3ddfdddea2fcfe37db70f2fc5f57026d56255e2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_91b2ef8341586e76bd64fa3093e74ce43aef344c2e2134fb3b046b47c3d4bcdb_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-0-gbsn\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611191812097470",
"status": "COMPLETED",
"status_changed_ts": "1611195590679789",
"tags": [
"build_address:luci.chromium.ci/win-asan/16921",
"builder:win-asan",
"buildset:commit/git/f26f7934cc4eadaf8df143133ebe2b0b73848484",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f26f7934cc4eadaf8df143133ebe2b0b73848484",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022966715239825536",
"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/8857536653226621392/+/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:513b5581f9509e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611195590692225",
"url": "https://ci.chromium.org/b/8857536653226621392",
"utcnow_ts": "1611195591441921"
}
}
[02:19:51.453] Invocation finished in 1h3m3.358221727s with status SUCCEEDED