[00:15:35.102] New invocation is queued and will start shortly
[00:15:36.211] Starting the invocation (attempt 1)
[00:15:36.246] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:15:36.246] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:15:36.246] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025688475661642640",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"45c3c4d2d59cd8d98b3a68f3e9347847e5433172\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025688475661642640",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/45c3c4d2d59cd8d98b3a68f3e9347847e5433172",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/45c3c4d2d59cd8d98b3a68f3e9347847e5433172",
"gitiles_ref:refs/heads/master"
]
}
[00:15:38.213] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608596136373934",
"id": "8860258413665143888",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"45c3c4d2d59cd8d98b3a68f3e9347847e5433172\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608596137791907",
"tags": [
"build_address:luci.chromium.ci/win-asan/16372",
"builder:win-asan",
"buildset:commit/git/45c3c4d2d59cd8d98b3a68f3e9347847e5433172",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/45c3c4d2d59cd8d98b3a68f3e9347847e5433172",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025688475661642640",
"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/8860258413665143888/+/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": "1608596137792085",
"url": "https://ci.chromium.org/b/8860258413665143888",
"utcnow_ts": "1608596138195362"
}
}
[00:15:38.213] Task URL: https://ci.chromium.org/b/8860258413665143888
[00:15:38.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:2:0) after 1m0s
[00:15:40.336] Received PubSub notification, asking Buildbucket for the build status
[00:15:40.411] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:38.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:2:0)
[00:16:38.234] Timer tick, asking Buildbucket for the build status
[00:16:38.431] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:38.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:4:0) after 1m0s
[00:17:38.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:4:0)
[00:17:38.452] Timer tick, asking Buildbucket for the build status
[00:17:38.638] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:38.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:5:0) after 1m0s
[00:18:38.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:5:0)
[00:18:38.913] Timer tick, asking Buildbucket for the build status
[00:18:39.142] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:39.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:6:0) after 1m0s
[00:19:39.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:6:0)
[00:19:39.229] Timer tick, asking Buildbucket for the build status
[00:19:39.422] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:39.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:7:0) after 1m0s
[00:20:39.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:7:0)
[00:20:39.624] Timer tick, asking Buildbucket for the build status
[00:20:39.917] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:39.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:8:0) after 1m0s
[00:21:40.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:8:0)
[00:21:40.085] Timer tick, asking Buildbucket for the build status
[00:21:40.475] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:40.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:9:0) after 1m0s
[00:22:40.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:9:0)
[00:22:40.501] Timer tick, asking Buildbucket for the build status
[00:22:40.785] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:40.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:10:0) after 1m0s
[00:23:40.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:10:0)
[00:23:40.812] Timer tick, asking Buildbucket for the build status
[00:23:40.890] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:40.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:11:0) after 1m0s
[00:24:40.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:11:0)
[00:24:40.901] Timer tick, asking Buildbucket for the build status
[00:24:41.015] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:41.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:12:0) after 1m0s
[00:25:41.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:12:0)
[00:25:41.095] Timer tick, asking Buildbucket for the build status
[00:25:41.215] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:41.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:13:0) after 1m0s
[00:26:41.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:13:0)
[00:26:41.253] Timer tick, asking Buildbucket for the build status
[00:26:41.414] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:41.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:14:0) after 1m0s
[00:27:41.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:14:0)
[00:27:41.441] Timer tick, asking Buildbucket for the build status
[00:27:41.523] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:41.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:15:0) after 1m0s
[00:28:41.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:15:0)
[00:28:41.541] Timer tick, asking Buildbucket for the build status
[00:28:41.610] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:41.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:16:0) after 1m0s
[00:29:41.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:16:0)
[00:29:41.706] Timer tick, asking Buildbucket for the build status
[00:29:41.775] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:41.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:17:0) after 1m0s
[00:30:41.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:17:0)
[00:30:41.853] Timer tick, asking Buildbucket for the build status
[00:30:41.999] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:41.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:18:0) after 1m0s
[00:31:42.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:18:0)
[00:31:42.138] Timer tick, asking Buildbucket for the build status
[00:31:42.472] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:42.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:19:0) after 1m0s
[00:32:42.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:19:0)
[00:32:42.507] Timer tick, asking Buildbucket for the build status
[00:32:42.666] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:42.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:20:0) after 1m0s
[00:33:42.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:20:0)
[00:33:42.997] Timer tick, asking Buildbucket for the build status
[00:33:43.596] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:43.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:21:0) after 1m0s
[00:34:43.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:21:0)
[00:34:43.639] Timer tick, asking Buildbucket for the build status
[00:34:44.322] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:44.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:22:0) after 1m0s
[00:35:44.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:22:0)
[00:35:44.329] Timer tick, asking Buildbucket for the build status
[00:35:44.452] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:44.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:23:0) after 1m0s
[00:36:44.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:23:0)
[00:36:44.469] Timer tick, asking Buildbucket for the build status
[00:36:44.935] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:44.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:24:0) after 1m0s
[00:37:44.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:24:0)
[00:37:44.970] Timer tick, asking Buildbucket for the build status
[00:37:45.183] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:45.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:25:0) after 1m0s
[00:38:45.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:25:0)
[00:38:45.277] Timer tick, asking Buildbucket for the build status
[00:38:45.410] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:45.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:26:0) after 1m0s
[00:39:46.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:26:0)
[00:39:46.749] Timer tick, asking Buildbucket for the build status
[00:39:46.861] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:46.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:27:0) after 1m0s
[00:40:47.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:27:0)
[00:40:47.083] Timer tick, asking Buildbucket for the build status
[00:40:47.227] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:47.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:28:0) after 1m0s
[00:41:47.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:28:0)
[00:41:47.248] Timer tick, asking Buildbucket for the build status
[00:41:47.447] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:47.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:29:0) after 1m0s
[00:42:47.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:29:0)
[00:42:47.551] Timer tick, asking Buildbucket for the build status
[00:42:47.728] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:47.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:30:0) after 1m0s
[00:43:47.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:30:0)
[00:43:47.715] Timer tick, asking Buildbucket for the build status
[00:43:47.868] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:47.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:31:0) after 1m0s
[00:44:47.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:31:0)
[00:44:47.977] Timer tick, asking Buildbucket for the build status
[00:44:49.475] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:49.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:32:0) after 1m0s
[00:45:49.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:32:0)
[00:45:49.618] Timer tick, asking Buildbucket for the build status
[00:45:49.721] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:49.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:33:0) after 1m0s
[00:46:49.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:33:0)
[00:46:49.772] Timer tick, asking Buildbucket for the build status
[00:46:50.105] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:50.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:34:0) after 1m0s
[00:47:50.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:34:0)
[00:47:50.123] Timer tick, asking Buildbucket for the build status
[00:47:50.211] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:50.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:35:0) after 1m0s
[00:48:50.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:35:0)
[00:48:50.243] Timer tick, asking Buildbucket for the build status
[00:48:50.399] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:50.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:36:0) after 1m0s
[00:49:50.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:36:0)
[00:49:50.496] Timer tick, asking Buildbucket for the build status
[00:49:50.581] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:50.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:37:0) after 1m0s
[00:50:50.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:37:0)
[00:50:50.614] Timer tick, asking Buildbucket for the build status
[00:50:50.733] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:50.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:38:0) after 1m0s
[00:51:50.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:38:0)
[00:51:50.806] Timer tick, asking Buildbucket for the build status
[00:51:50.973] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:50.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:39:0) after 1m0s
[00:52:50.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:39:0)
[00:52:50.999] Timer tick, asking Buildbucket for the build status
[00:52:51.111] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:51.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:40:0) after 1m0s
[00:53:51.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:40:0)
[00:53:51.192] Timer tick, asking Buildbucket for the build status
[00:53:51.301] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:51.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:41:0) after 1m0s
[00:54:51.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:41:0)
[00:54:51.429] Timer tick, asking Buildbucket for the build status
[00:54:51.590] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:51.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:42:0) after 1m0s
[00:55:51.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:42:0)
[00:55:51.628] Timer tick, asking Buildbucket for the build status
[00:55:51.781] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:51.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:43:0) after 1m0s
[00:56:51.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:43:0)
[00:56:51.801] Timer tick, asking Buildbucket for the build status
[00:56:52.170] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:52.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:44:0) after 1m0s
[00:57:52.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:44:0)
[00:57:52.227] Timer tick, asking Buildbucket for the build status
[00:57:52.379] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:52.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:45:0) after 1m0s
[00:58:52.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:45:0)
[00:58:52.453] Timer tick, asking Buildbucket for the build status
[00:58:52.762] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:52.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:46:0) after 1m0s
[00:59:52.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:46:0)
[00:59:52.767] Timer tick, asking Buildbucket for the build status
[00:59:52.852] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:52.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:47:0) after 1m0s
[01:00:52.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:47:0)
[01:00:52.941] Timer tick, asking Buildbucket for the build status
[01:00:53.587] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:53.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:48:0) after 1m0s
[01:01:53.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:48:0)
[01:01:53.618] Timer tick, asking Buildbucket for the build status
[01:01:53.760] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:53.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:49:0) after 1m0s
[01:02:53.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:49:0)
[01:02:53.786] Timer tick, asking Buildbucket for the build status
[01:02:54.324] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:54.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:50:0) after 1m0s
[01:03:54.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:50:0)
[01:03:54.346] Timer tick, asking Buildbucket for the build status
[01:03:54.428] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:54.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:51:0) after 1m0s
[01:04:54.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:51:0)
[01:04:54.448] Timer tick, asking Buildbucket for the build status
[01:04:54.732] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:54.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:52:0) after 1m0s
[01:05:54.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:52:0)
[01:05:54.754] Timer tick, asking Buildbucket for the build status
[01:05:54.909] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:54.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:53:0) after 1m0s
[01:06:54.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:53:0)
[01:06:54.936] Timer tick, asking Buildbucket for the build status
[01:06:55.204] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:55.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:54:0) after 1m0s
[01:07:55.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:54:0)
[01:07:55.234] Timer tick, asking Buildbucket for the build status
[01:07:55.717] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:55.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:55:0) after 1m0s
[01:08:55.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:55:0)
[01:08:55.789] Timer tick, asking Buildbucket for the build status
[01:08:55.937] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:55.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:56:0) after 1m0s
[01:09:56.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:56:0)
[01:09:56.372] Timer tick, asking Buildbucket for the build status
[01:09:56.444] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:56.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:57:0) after 1m0s
[01:10:56.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:57:0)
[01:10:56.738] Timer tick, asking Buildbucket for the build status
[01:10:57.145] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:57.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:58:0) after 1m0s
[01:11:57.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:58:0)
[01:11:57.303] Timer tick, asking Buildbucket for the build status
[01:11:57.372] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:57.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:59:0) after 1m0s
[01:12:57.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:59:0)
[01:12:57.735] Timer tick, asking Buildbucket for the build status
[01:12:57.868] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:57.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:60:0) after 1m0s
[01:13:58.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:60:0)
[01:13:58.347] Timer tick, asking Buildbucket for the build status
[01:13:58.573] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:58.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:61:0) after 1m0s
[01:14:58.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:61:0)
[01:14:58.712] Timer tick, asking Buildbucket for the build status
[01:14:59.217] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:59.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:62:0) after 1m0s
[01:15:59.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:62:0)
[01:15:59.809] Timer tick, asking Buildbucket for the build status
[01:16:00.322] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:00.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:63:0) after 1m0s
[01:17:00.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:63:0)
[01:17:00.347] Timer tick, asking Buildbucket for the build status
[01:17:00.720] Build 8860258413665143888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:00.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025688475661642640:64:0) after 1m0s
[01:17:51.338] Received PubSub notification, asking Buildbucket for the build status
[01:17:51.458] Build 8860258413665143888: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:17:51.458] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608599870249379",
"created_by": "project:chromium",
"created_ts": "1608596136373934",
"id": "8860258413665143888",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"45c3c4d2d59cd8d98b3a68f3e9347847e5433172\"}}",
"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\": \"45c3c4d2d59cd8d98b3a68f3e9347847e5433172\", \"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-6-gpyc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16372, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"4e2b6d6b01028728634d65d0f69523da145420e7\", \"got_dawn_revision\": \"ec56b90ceab0c16e3c9f5603cc26562ec7bc2430\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"45c3c4d2d59cd8d98b3a68f3e9347847e5433172\", \"got_revision_cp\": \"refs/heads/master@{#838751}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"ab90f8251bdc6bd9dc6033afdd2374af4883794f\", \"got_v8_revision_cp\": \"refs/heads/8.9.181@{#1}\", \"got_webrtc_revision\": \"8649e49d10e6e6efb5a98920f0b19a77abe8f070\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32867}\", \"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\": \"45c3c4d2d59cd8d98b3a68f3e9347847e5433172\", \"swarm_hashes_refs/heads/master(at){#838751}_without_patch\": {\"absl_hardening_tests\": \"39f20418589f7c30f3194c31d0f3075429c07b21\", \"accessibility_unittests\": \"46894e800ff66ae1c0375283f8dc52273837b841\", \"angle_unittests\": \"6eee94b129ea5795e8e0611162e68b6bf2d7e114\", \"app_shell_unittests\": \"1d8640d14098d1a5d4c3191fda091280934eef22\", \"aura_unittests\": \"07a87f9d2a65bd9d45d43afe41135e930c72351b\", \"base_unittests\": \"7eeb591ce1901eb66cf4ef1029956228a571df4b\", \"base_util_unittests\": \"980b1219da6d12b16ee8cd2163d13acd7bc447ea\", \"blink_common_unittests\": \"0f3a89f004ac83bf6d43f65e83e7056a338e401e\", \"blink_fuzzer_unittests\": \"4813c4230dbeae2453bb113ec1809d0b70ca923f\", \"blink_heap_unittests\": \"f947b2100516d378183f60d24ac96882ca0fded8\", \"blink_platform_unittests\": \"ebedfb53c5fc715874f58d6918055144ba914780\", \"blink_unittests\": \"a07325c8720193e18578b2a0b21cdc42c27f43e8\", \"boringssl_crypto_tests\": \"16fc97f8f1fc57e1aa10895e2a0c4d6bc2743348\", \"boringssl_ssl_tests\": \"99f1af84bcd30319868457a4c4ec2efae6688671\", \"browser_switcher_bho_unittests\": \"03562d54f20fb0e3c8188c91a2ebf5c318f8a595\", \"browser_tests\": \"8a17b58c9b29dee3b18e14607bdd516fcb9e8517\", \"capture_unittests\": \"810de63bbbc4f6aab0523fb6b86eac034d25d875\", \"cast_unittests\": \"215fa0c8483bdaf4495aecc49712f27de96d97ae\", \"cc_unittests\": \"b6c2b8a7e90dd3488bdcf39567b1dc02b8ab1e66\", \"chrome_app_unittests\": \"e72d44286090e1404589c5fdb6220a6f0bba6830\", \"chrome_cleaner_unittests\": \"ec2e04971b5255ff96ddc7339d958d9807325ff8\", \"chrome_elf_unittests\": \"9e155fb5165a583ce43aa33582cdcd3310113d1e\", \"chromedriver_unittests\": \"85e0486381f7abb6f33918c0761d13456e0907db\", \"components_browsertests\": \"05f756d237a64af90eb181f5392401959586e233\", \"components_unittests\": \"742d18a540d0bd56205ebaa1f5cdb0ba17aadfe4\", \"compositor_unittests\": \"1523b94ba5acd4cbfb37a08d632fee5af6926e0d\", \"content_browsertests\": \"442e3a182166bfb8f361c9112740e5d82e7e4239\", \"content_unittests\": \"2f1a4f0388e948b5d6e9e78fff049a21da62c626\", \"courgette_unittests\": \"3412500d7cd474065ae627bdfcf0d7ee32e524ec\", \"crashpad_tests\": \"24e92dc4a4b0e336c7d7cec2c5c604ba4577510d\", \"cronet_tests\": \"09351816eaf28cbc0cf47b2b727dcaac2b0aebda\", \"cronet_unittests\": \"ff9790095bee750984b2291ec7e0584c57273e2b\", \"crypto_unittests\": \"fafc1d6736085a6069a68a6683e072028261c2ad\", \"delayloads_unittests\": \"2ca250af0cf67ee83dfc198d76fe9a2334d82d7c\", \"device_unittests\": \"1154672df5064fb070705d7288a6e2c66c307fae\", \"display_unittests\": \"57d1c0010d80e52c97b6a901d695b4d48fb08beb\", \"elevation_service_unittests\": \"0fa2faa5f64173e4a862244083d4cb3aef1f782b\", \"events_unittests\": \"66a714e3c816c41dc55a0dc5f7ac77db742d2f95\", \"extensions_browsertests\": \"b1845cf92d5e7e0462cc702f90fc749c6b525aff\", \"extensions_unittests\": \"79ae504a59ef44cdea3239d04392b04a7874cfa6\", \"filesystem_service_unittests\": \"2c0883cb44fbaac918ab8067c5233cb64fce58d6\", \"gcm_unit_tests\": \"9cad3a7ac2959cfba663155613946368fe3171ac\", \"gcp_unittests\": \"1f77d44a8144b807585d2d05d5c6bd537cb84c2c\", \"gfx_unittests\": \"730ad5f4cf9bd193f2047c60ee93238662fcd869\", \"gin_unittests\": \"002a75a703934aa205772a60b148dc20acf8c221\", \"google_apis_unittests\": \"eff3120641ccaa8b479c38ce356d8c59f53059ce\", \"gpu_unittests\": \"abd9a84d9312b417954c0c097f211022b71d38ef\", \"gwp_asan_unittests\": \"befb8d1bc6a2e09b6e0d9cfe14d6bdaed9064c6a\", \"headless_browsertests\": \"4d63ab58f0e3c06b2fa39f59f6079dc1811de6fd\", \"headless_unittests\": \"3aca587b7a54be7a41e2f82a6a7f7bea18e6cff9\", \"install_static_unittests\": \"c4522f4a7f1a58578dd0a4c42eb4b43a430c7bb3\", \"installer_util_unittests\": \"2c0d34d19ad01d6de25ce4409b97eddaf6341fa8\", \"interactive_ui_tests\": \"17692993ecfe87ff61e1a1a3b15141e66df20bd3\", \"ipc_tests\": \"86b7892a8f7bfae0802b2c5420ecc8905ac385b7\", \"jingle_unittests\": \"1ed0dff18fff35c04d226bec2ab003c22113b5a3\", \"latency_unittests\": \"3cc9bc46a723c54d1a26ad8602b21d0acaba4e00\", \"libjingle_xmpp_unittests\": \"745cc5d939d01cdc340f665ad5e8f1e8b9dcf67f\", \"liburlpattern_unittests\": \"b572973968ae8e29c94963b512d8dce2500a120e\", \"media_blink_unittests\": \"c0a9301b982049db4b569e27c63fb64cd49ee2c2\", \"media_unittests\": \"012f23ee7e808a133e5b7b1f5079e5747b74561a\", \"message_center_unittests\": \"66eff63fc321f4721cd6d30f17dc7ff4c5c9a25f\", \"midi_unittests\": \"c187e938d903247f1468bc6f27e015a05a8b1d76\", \"mojo_core_unittests\": \"b9506ecd9af4262463bca9108f660032f8f6aaab\", \"mojo_unittests\": \"fa9d4c6cde53027bd64e0ae6f6f6af1d1daa89e3\", \"nacl_loader_unittests\": \"737f9fc4033b90daca30491edccf0e9940d08001\", \"native_theme_unittests\": \"6ff518a9ff529f24599e513d5ced8b89d0b3e0a6\", \"net_unittests\": \"880fa0a2a176a96d039b883c53baa92b67d8c5a2\", \"notification_helper_unittests\": \"c7c772dbb7b3f699431c182eb2f5d55c01734b21\", \"pdf_unittests\": \"f0bf3267081cceea0b84781cbf8b62b389f647a7\", \"perfetto_unittests\": \"ff781e25c4e9a53177d8c2933f514507a44deee4\", \"ppapi_unittests\": \"5138baecc8e36ed3d6aa3c2182b58d2cef9b44f9\", \"printing_unittests\": \"7fb221187d4bb9a5ed42961fc9480a4dc59c1085\", \"remoting_unittests\": \"eb1ea82165fb7362aa8bfc859630c0d21e3406fb\", \"sbox_integration_tests\": \"1102a703f28a17648290e4e61153acea42d34ed2\", \"sbox_unittests\": \"795f6dedb474e1c924819163a0cc9c914f367cdf\", \"sbox_validation_tests\": \"3d30d654d895acf4f2f18e5d76bb9016536799e1\", \"service_manager_unittests\": \"caf5266e833f080ee31b1b1851ab41456da2b31c\", \"services_unittests\": \"ad755dd455924bd3ea3f88c91557b0e92ff4cbab\", \"setup_unittests\": \"2e95fc9cdc7e6fc6962249502fe85b91de4eebb6\", \"shell_dialogs_unittests\": \"cf8b446454e814e6034881c80c1fd8bb174c7cc8\", \"skia_unittests\": \"f2c42be9b02974f01d2a314761720eb03897d520\", \"snapshot_unittests\": \"ee9d9b5ab74bba4c8db371288674a409741a7021\", \"sql_unittests\": \"d53535e7593c46e816252f46ad672c2ae6360ee9\", \"storage_unittests\": \"04d5758cf3596678d0a4ec54e4148201308a1b08\", \"sync_integration_tests\": \"bce32d068c751a4fca431e9c9ad0d3145b7054f9\", \"traffic_annotation_auditor_unittests\": \"4ee2d52231a6e52543fc9c0353a91a0821209ab0\", \"ui_base_unittests\": \"629a36e95a504eb06c9bf95ab9d46168fb9e3dfc\", \"ui_touch_selection_unittests\": \"038bb1c79896c15a7eec26360ce30f6005060421\", \"unit_tests\": \"311c0e85c3768e5f4102a90a4b33ca84769403ac\", \"updater_tests\": \"df29b57a7bd5bed998ba18f32055de9ad3523e76\", \"url_unittests\": \"fdc3a9d3fa164f1a5df450f25804a9f9febe3b6d\", \"views_unittests\": \"807af6a375c74cebfa8fb06dc342785d0b88cad1\", \"viz_unittests\": \"3f7f96f08beab5c84754403f4890030fb38285dc\", \"vr_common_unittests\": \"665afc079fe6e4ffde1572a4b3631da156917e82\", \"vr_pixeltests\": \"b2e57d5a442833887f3db4dfb4e09a4dd23cfa3e\", \"weblayer_browsertests\": \"2c869b61d75d8a92ab634c83b5423b25b2684e88\", \"weblayer_unittests\": \"c0db6de3b564a66df2b73315f675a4fd4c9c27da\", \"wm_unittests\": \"22e0904f387896703748e10bb5f21a247e003566\", \"wtf_unittests\": \"13695acbad63585642c11be3c052bb98b641fd15\", \"zlib_unittests\": \"6f2e532f45d8ca7b8f502379784df0afbb20b999\", \"zucchini_unittests\": \"ab18aafeafb6a4a5cdc2654c2d529922f274f300\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-gpyc\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608596139524707",
"status": "COMPLETED",
"status_changed_ts": "1608599870602315",
"tags": [
"build_address:luci.chromium.ci/win-asan/16372",
"builder:win-asan",
"buildset:commit/git/45c3c4d2d59cd8d98b3a68f3e9347847e5433172",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/45c3c4d2d59cd8d98b3a68f3e9347847e5433172",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025688475661642640",
"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/8860258413665143888/+/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:50a09eb0e6a89410",
"user_agent:luci-scheduler"
],
"updated_ts": "1608599870606284",
"url": "https://ci.chromium.org/b/8860258413665143888",
"utcnow_ts": "1608599871451284"
}
}
[01:17:51.458] Invocation finished in 1h2m16.373279969s with status SUCCEEDED