[02:57:20.088] New invocation is queued and will start shortly
[02:57:21.141] Starting the invocation (attempt 1)
[02:57:21.185] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:57:21.185] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:57:21.185] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021057853958031728",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bdd804b882bcb3c759ef22fd27d05476b2189826\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021057853958031728",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/bdd804b882bcb3c759ef22fd27d05476b2189826",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bdd804b882bcb3c759ef22fd27d05476b2189826",
"gitiles_ref:refs/heads/master"
]
}
[02:57:21.972] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613012241271860",
"id": "8855627792055194752",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bdd804b882bcb3c759ef22fd27d05476b2189826\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613012241652140",
"tags": [
"build_address:luci.chromium.ci/win-asan/17279",
"builder:win-asan",
"buildset:commit/git/bdd804b882bcb3c759ef22fd27d05476b2189826",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bdd804b882bcb3c759ef22fd27d05476b2189826",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021057853958031728",
"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/8855627792055194752/+/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": "1613012241652256",
"url": "https://ci.chromium.org/b/8855627792055194752",
"utcnow_ts": "1613012241965143"
}
}
[02:57:21.972] Task URL: https://ci.chromium.org/b/8855627792055194752
[02:57:21.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:2:0) after 1m0s
[02:57:24.959] Received PubSub notification, asking Buildbucket for the build status
[02:57:25.154] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:22.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:2:0)
[02:58:22.074] Timer tick, asking Buildbucket for the build status
[02:58:22.185] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:22.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:4:0) after 1m0s
[02:59:22.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:4:0)
[02:59:22.204] Timer tick, asking Buildbucket for the build status
[02:59:22.306] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:22.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:5:0) after 1m0s
[03:00:22.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:5:0)
[03:00:22.324] Timer tick, asking Buildbucket for the build status
[03:00:22.404] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:22.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:6:0) after 1m0s
[03:01:22.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:6:0)
[03:01:22.474] Timer tick, asking Buildbucket for the build status
[03:01:22.709] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:22.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:7:0) after 1m0s
[03:02:22.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:7:0)
[03:02:22.730] Timer tick, asking Buildbucket for the build status
[03:02:22.815] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:22.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:8:0) after 1m0s
[03:03:22.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:8:0)
[03:03:22.832] Timer tick, asking Buildbucket for the build status
[03:03:22.920] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:22.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:9:0) after 1m0s
[03:04:22.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:9:0)
[03:04:22.979] Timer tick, asking Buildbucket for the build status
[03:04:23.314] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:23.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:10:0) after 1m0s
[03:05:23.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:10:0)
[03:05:23.339] Timer tick, asking Buildbucket for the build status
[03:05:23.576] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:23.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:11:0) after 1m0s
[03:06:23.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:11:0)
[03:06:23.604] Timer tick, asking Buildbucket for the build status
[03:06:23.795] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:23.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:12:0) after 1m0s
[03:07:23.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:12:0)
[03:07:23.879] Timer tick, asking Buildbucket for the build status
[03:07:24.135] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:24.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:13:0) after 1m0s
[03:08:24.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:13:0)
[03:08:24.152] Timer tick, asking Buildbucket for the build status
[03:08:24.329] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:24.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:14:0) after 1m0s
[03:09:24.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:14:0)
[03:09:24.398] Timer tick, asking Buildbucket for the build status
[03:09:24.472] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:24.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:15:0) after 1m0s
[03:10:24.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:15:0)
[03:10:24.536] Timer tick, asking Buildbucket for the build status
[03:10:24.661] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:24.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:16:0) after 1m0s
[03:11:24.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:16:0)
[03:11:24.697] Timer tick, asking Buildbucket for the build status
[03:11:24.835] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:24.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:17:0) after 1m0s
[03:12:24.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:17:0)
[03:12:24.882] Timer tick, asking Buildbucket for the build status
[03:12:25.113] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:25.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:18:0) after 1m0s
[03:13:25.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:18:0)
[03:13:25.288] Timer tick, asking Buildbucket for the build status
[03:13:25.378] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:25.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:19:0) after 1m0s
[03:14:25.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:19:0)
[03:14:25.421] Timer tick, asking Buildbucket for the build status
[03:14:25.542] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:25.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:20:0) after 1m0s
[03:15:25.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:20:0)
[03:15:25.634] Timer tick, asking Buildbucket for the build status
[03:15:25.743] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:25.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:21:0) after 1m0s
[03:16:25.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:21:0)
[03:16:25.857] Timer tick, asking Buildbucket for the build status
[03:16:26.124] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:26.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:22:0) after 1m0s
[03:17:26.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:22:0)
[03:17:26.171] Timer tick, asking Buildbucket for the build status
[03:17:26.423] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:26.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:23:0) after 1m0s
[03:18:26.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:23:0)
[03:18:26.461] Timer tick, asking Buildbucket for the build status
[03:18:26.639] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:26.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:24:0) after 1m0s
[03:19:26.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:24:0)
[03:19:26.718] Timer tick, asking Buildbucket for the build status
[03:19:26.804] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:26.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:25:0) after 1m0s
[03:20:26.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:25:0)
[03:20:26.860] Timer tick, asking Buildbucket for the build status
[03:20:26.960] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:26.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:26:0) after 1m0s
[03:21:26.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:26:0)
[03:21:26.980] Timer tick, asking Buildbucket for the build status
[03:21:27.314] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:27.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:27:0) after 1m0s
[03:22:27.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:27:0)
[03:22:27.360] Timer tick, asking Buildbucket for the build status
[03:22:27.461] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:27.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:28:0) after 1m0s
[03:23:27.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:28:0)
[03:23:27.550] Timer tick, asking Buildbucket for the build status
[03:23:27.632] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:27.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:29:0) after 1m0s
[03:24:27.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:29:0)
[03:24:27.675] Timer tick, asking Buildbucket for the build status
[03:24:27.888] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:27.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:30:0) after 1m0s
[03:25:28.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:30:0)
[03:25:28.133] Timer tick, asking Buildbucket for the build status
[03:25:28.234] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:28.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:31:0) after 1m0s
[03:26:28.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:31:0)
[03:26:28.288] Timer tick, asking Buildbucket for the build status
[03:26:28.460] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:28.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:32:0) after 1m0s
[03:27:28.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:32:0)
[03:27:28.442] Timer tick, asking Buildbucket for the build status
[03:27:28.580] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:28.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:33:0) after 1m0s
[03:28:28.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:33:0)
[03:28:28.640] Timer tick, asking Buildbucket for the build status
[03:28:28.772] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:28.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:34:0) after 1m0s
[03:29:28.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:34:0)
[03:29:28.816] Timer tick, asking Buildbucket for the build status
[03:29:29.135] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:29.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:35:0) after 1m0s
[03:30:29.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:35:0)
[03:30:29.297] Timer tick, asking Buildbucket for the build status
[03:30:29.434] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:29.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:36:0) after 1m0s
[03:31:29.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:36:0)
[03:31:29.537] Timer tick, asking Buildbucket for the build status
[03:31:29.947] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:29.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:37:0) after 1m0s
[03:32:30.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:37:0)
[03:32:30.067] Timer tick, asking Buildbucket for the build status
[03:32:30.212] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:30.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:38:0) after 1m0s
[03:33:30.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:38:0)
[03:33:30.278] Timer tick, asking Buildbucket for the build status
[03:33:30.389] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:30.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:39:0) after 1m0s
[03:34:30.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:39:0)
[03:34:30.443] Timer tick, asking Buildbucket for the build status
[03:34:31.042] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:31.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:40:0) after 1m0s
[03:35:31.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:40:0)
[03:35:31.074] Timer tick, asking Buildbucket for the build status
[03:35:31.259] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:31.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:41:0) after 1m0s
[03:36:31.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:41:0)
[03:36:31.288] Timer tick, asking Buildbucket for the build status
[03:36:31.420] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:31.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:42:0) after 1m0s
[03:37:31.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:42:0)
[03:37:31.454] Timer tick, asking Buildbucket for the build status
[03:37:31.712] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:31.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:43:0) after 1m0s
[03:38:31.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:43:0)
[03:38:31.706] Timer tick, asking Buildbucket for the build status
[03:38:31.853] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:31.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:44:0) after 1m0s
[03:39:31.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:44:0)
[03:39:31.887] Timer tick, asking Buildbucket for the build status
[03:39:32.105] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:32.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:45:0) after 1m0s
[03:40:32.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:45:0)
[03:40:32.129] Timer tick, asking Buildbucket for the build status
[03:40:32.349] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:32.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:46:0) after 1m0s
[03:41:32.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:46:0)
[03:41:32.375] Timer tick, asking Buildbucket for the build status
[03:41:32.467] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:32.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:47:0) after 1m0s
[03:42:32.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:47:0)
[03:42:32.487] Timer tick, asking Buildbucket for the build status
[03:42:32.644] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:32.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:48:0) after 1m0s
[03:43:32.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:48:0)
[03:43:32.717] Timer tick, asking Buildbucket for the build status
[03:43:32.800] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:32.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:49:0) after 1m0s
[03:44:32.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:49:0)
[03:44:32.878] Timer tick, asking Buildbucket for the build status
[03:44:33.099] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:33.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:50:0) after 1m0s
[03:45:33.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:50:0)
[03:45:33.123] Timer tick, asking Buildbucket for the build status
[03:45:33.337] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:33.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:51:0) after 1m0s
[03:46:33.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:51:0)
[03:46:33.365] Timer tick, asking Buildbucket for the build status
[03:46:33.846] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:33.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:52:0) after 1m0s
[03:47:33.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:52:0)
[03:47:33.844] Timer tick, asking Buildbucket for the build status
[03:47:33.989] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:33.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:53:0) after 1m0s
[03:48:34.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:53:0)
[03:48:34.050] Timer tick, asking Buildbucket for the build status
[03:48:34.186] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:34.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:54:0) after 1m0s
[03:49:34.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:54:0)
[03:49:34.215] Timer tick, asking Buildbucket for the build status
[03:49:34.615] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:34.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:55:0) after 1m0s
[03:50:34.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:55:0)
[03:50:34.593] Timer tick, asking Buildbucket for the build status
[03:50:34.690] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:34.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:56:0) after 1m0s
[03:51:34.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:56:0)
[03:51:34.773] Timer tick, asking Buildbucket for the build status
[03:51:34.869] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:34.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:57:0) after 1m0s
[03:52:34.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:57:0)
[03:52:34.887] Timer tick, asking Buildbucket for the build status
[03:52:35.234] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:35.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:58:0) after 1m0s
[03:53:35.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:58:0)
[03:53:35.257] Timer tick, asking Buildbucket for the build status
[03:53:35.381] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:35.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:59:0) after 1m0s
[03:54:35.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:59:0)
[03:54:35.406] Timer tick, asking Buildbucket for the build status
[03:54:35.565] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:35.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:60:0) after 1m0s
[03:55:35.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:60:0)
[03:55:35.701] Timer tick, asking Buildbucket for the build status
[03:55:36.064] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:36.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:61:0) after 1m0s
[03:56:36.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:61:0)
[03:56:36.069] Timer tick, asking Buildbucket for the build status
[03:56:36.913] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:36.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:62:0) after 1m0s
[03:57:36.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:62:0)
[03:57:36.950] Timer tick, asking Buildbucket for the build status
[03:57:37.085] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:37.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:63:0) after 1m0s
[03:58:37.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:63:0)
[03:58:37.122] Timer tick, asking Buildbucket for the build status
[03:58:37.211] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:37.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:64:0) after 1m0s
[03:59:37.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:64:0)
[03:59:37.380] Timer tick, asking Buildbucket for the build status
[03:59:37.484] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:37.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:65:0) after 1m0s
[04:00:37.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:65:0)
[04:00:37.634] Timer tick, asking Buildbucket for the build status
[04:00:37.747] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:37.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:66:0) after 1m0s
[04:01:37.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:66:0)
[04:01:37.767] Timer tick, asking Buildbucket for the build status
[04:01:37.856] Build 8855627792055194752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:37.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021057853958031728:67:0) after 1m0s
[04:02:17.089] Received PubSub notification, asking Buildbucket for the build status
[04:02:17.180] Build 8855627792055194752: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:02:17.180] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613016135497955",
"created_by": "project:chromium",
"created_ts": "1613012241271860",
"id": "8855627792055194752",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bdd804b882bcb3c759ef22fd27d05476b2189826\"}}",
"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\": \"bdd804b882bcb3c759ef22fd27d05476b2189826\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-5-o33n\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17279, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"7e80cce1a9065d60522d470d24526394d642e6d5\", \"got_nacl_revision\": \"4de55da44c4b511989359381e8b7a88a01fe0634\", \"got_revision\": \"bdd804b882bcb3c759ef22fd27d05476b2189826\", \"got_revision_cp\": \"refs/heads/master@{#852961}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"673a7fe5c3ff6686c98951cafb24a93ca5bd1303\", \"got_v8_revision\": \"50e0c3a02268852f4c0d73e3b3a24d2f52a7f419\", \"got_v8_revision_cp\": \"refs/heads/9.0.161@{#1}\", \"got_webrtc_revision\": \"8bf61a3071b7306860261eadf43c157ce3ffe4fe\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33223}\", \"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\": \"bdd804b882bcb3c759ef22fd27d05476b2189826\", \"swarm_hashes_refs/heads/master(at){#852961}_without_patch\": {\"absl_hardening_tests\": \"183a5ac1b5a775454bf5e9ed4cf29e0e3d11b686\", \"accessibility_unittests\": \"e66758cce012ea2679f81d1f283d9ae4f5de9c56\", \"angle_unittests\": \"f8a311b71d6f72b448d80449797e51d3c41f3a46\", \"app_shell_unittests\": \"ff356a418cf6cee34ca4e58f12ce1a093a66f2e7\", \"aura_unittests\": \"71fe4f5e7ffc9609804d988c7e0fad5a2edea849\", \"base_unittests\": \"fffc532286281d6fe1fb41d952e11ee081a5914c\", \"base_util_unittests\": \"a962b1898168f8fd765b79a4087e735d74918ec0\", \"blink_common_unittests\": \"d5653338f5429cbb87c3964616f843e4548fecd5\", \"blink_fuzzer_unittests\": \"119733a88f6073c30a9c2cfda62b6058d8eaa262\", \"blink_heap_unittests\": \"f2f8dd334a09f1e2569346fbbd63e8d5956703f0\", \"blink_platform_unittests\": \"997633d2ee1c86f6113c932c302ac7cc2d956976\", \"blink_unittests\": \"bebc72f9d39460c3ab27ef629de96b01bcdecad0\", \"boringssl_crypto_tests\": \"f7a05caf5fc7695fcb000032970d6ffc8c1b5e7d\", \"boringssl_ssl_tests\": \"2bb15d070a8bcd316245651a1fb9cf26673539c4\", \"browser_switcher_bho_unittests\": \"d3d4a1f6acd6ee0b63580bc9efc9303580181e31\", \"browser_tests\": \"d7aa235ad4a0c4802274b624feef7890626a83f1\", \"capture_unittests\": \"61e99109bef3f72a372fd9b7fd7ea4d08d57af4b\", \"cast_unittests\": \"6abff2e790e30d8805391ff3fc5252e60c305ba9\", \"cc_unittests\": \"9cf6689804831e16e78ad13280d0a2a1d0c9dfc6\", \"chrome_app_unittests\": \"e221448b8cfcc49884b6ed905e70b9398c007eb8\", \"chrome_cleaner_unittests\": \"6b6d75584ca5a52aa211001c0077159763574ed7\", \"chrome_elf_unittests\": \"8d0a0d5b439978b5e89b7caa54bc03f2dadf6404\", \"chromedriver_unittests\": \"1fb3dc8ad2248448b31c5a83354a370ee7175605\", \"components_browsertests\": \"995f5080cb60e359b34f5c7b823928b0772dfde8\", \"components_unittests\": \"e9e81f845f4c9adac68b9e842af3c88f2b44054f\", \"compositor_unittests\": \"56ea383fdd90afe54d42238f115e08b65a6f8518\", \"content_browsertests\": \"11261998f19f2efde0e5bc168646bc825cf69a33\", \"content_unittests\": \"78b8bc7bdab6ace95103bfeed39cc53521af2daf\", \"courgette_unittests\": \"d55404ddfc6db42e37fe4ba9f9b7c32840456d3d\", \"crashpad_tests\": \"f864fb34b35cf9e3d36690286d49091de4aea7cf\", \"cronet_tests\": \"4548c4c30679e338388cbb3a6daeea7fee563e14\", \"cronet_unittests\": \"0b1eb3a3d6fe0bb9c5ca9821e3c34374aeb2ddf8\", \"crypto_unittests\": \"803b768b63e2212dcaa31883eb64371ec582763d\", \"delayloads_unittests\": \"66aab921f0b09ba25b3c2b26288c7519607298d1\", \"device_unittests\": \"444e6e8bca1ce629abee5827a054cb0e81e3f757\", \"display_unittests\": \"820a5e463f47ad9d47879ef6df19c2d702dfc2f6\", \"elevation_service_unittests\": \"cd3b5e44f1280c074861dea66077ffea616ed09f\", \"events_unittests\": \"60badb9c973014ff543a6fbaa2dc8dd26382baff\", \"extensions_browsertests\": \"04dee34038e8378242babd56590d5151ee4a09c1\", \"extensions_unittests\": \"629f729f76647d365265897ada364b70c02b6ab2\", \"filesystem_service_unittests\": \"7a852e4eecb73b50cca1ec90251340bcf72ff0ac\", \"gcm_unit_tests\": \"a8af48599dbaefc51f39849ccf0022b32fb2c763\", \"gcp_unittests\": \"df57f9886c43787526a5f49d09013d6eb12f7cbd\", \"gfx_unittests\": \"80d0cc243a94ffeec41b3d6b94e4719a594d3968\", \"gin_unittests\": \"66280ead9256ac8a8fe8f7c15f54e87e47b0350b\", \"google_apis_unittests\": \"c9ea2d74ee032243ea52b5fc996de5052fedf2fe\", \"gpu_unittests\": \"4171730f7b6eb7771404ad261a494203a790b007\", \"gwp_asan_unittests\": \"1fc10097925862b98a9f3110f5e9eed21981f0b1\", \"headless_browsertests\": \"88c011f893d02e6cf4af6246b60dd787ba7502db\", \"headless_unittests\": \"e02dbb3b256a07b179a0dcf542dfc175928fcdda\", \"install_static_unittests\": \"740ba7f339890d6e917658c219b2c95a962754af\", \"installer_util_unittests\": \"80311771b9caec7d534d8604a5508341fe50fce7\", \"interactive_ui_tests\": \"61c39f86485df51a86b3e6fbfb0bce57959a0668\", \"ipc_tests\": \"6c8ccaad08f6c2e26ba0c6a3694ecc7d8d7bf0f4\", \"jingle_unittests\": \"86ecf6c8481295cbb6bbd09ca9d86f8b17ea0ac3\", \"latency_unittests\": \"162598535f51e1013b4b352d8d4242d16a8b50a9\", \"libjingle_xmpp_unittests\": \"ad5f970e887a6fa8406ea8d7fdd78d62ebc214db\", \"liburlpattern_unittests\": \"90859241236b7613e3558e545ec5990afd0eb21f\", \"media_blink_unittests\": \"4983c1f496220781a5c26b52c0ca6845f1b35f7e\", \"media_unittests\": \"ae21cc1f82a09589b65924676a71d8967aeedaf6\", \"message_center_unittests\": \"488fece14f33723483c9820d50423c8d1fadb0d1\", \"midi_unittests\": \"1f0c61634c45f1b677c033a4c22d1fd845133dcf\", \"mojo_core_unittests\": \"c8a444a2b74a8d323a77fa65c351de3ad5ac8540\", \"mojo_unittests\": \"bb5ff0e86ac01badedaeb2913a03b114d5f458a4\", \"nacl_loader_unittests\": \"c256d4b4425efd95d4f72c89a99940a441458a00\", \"native_theme_unittests\": \"11abcb22dc20a51ef35c8e63b6c0b89fa2c0499e\", \"net_unittests\": \"7d622fe2c3a2fb7cb032a61d582d72a4a4209c48\", \"notification_helper_unittests\": \"49e1db0c3cb3dab0c67a26a34f97801f85e4d473\", \"pdf_unittests\": \"d6bbc6c60fe4a820ad9bc4169ffb7b236b6c13c4\", \"perfetto_unittests\": \"c72bf70233d38d7d3d6f218df74aeb6cd1b70415\", \"ppapi_unittests\": \"61ad8fb3e030f2a3ad146d04652358f366c2dc7a\", \"printing_unittests\": \"69b5057871cdb38469829711cac3a8e2d8e9dcb1\", \"remoting_unittests\": \"24d97e5f898302dca77908457d2fbae34ced8c95\", \"sbox_integration_tests\": \"a371cebe0b98c2c68a37fb8334d250703e3506fb\", \"sbox_unittests\": \"686e956297591d54e80e3e93b2d65cbfe0226f52\", \"sbox_validation_tests\": \"e52b244a4bb163b152efb0c166f77e7cd93b0e81\", \"service_manager_unittests\": \"29cd3454e5097ae006f77e4ca8d4ca00df6da057\", \"services_unittests\": \"88cbb53f186e286189582194768ef4855e6819d6\", \"setup_unittests\": \"db47e61d9c90731122884a482e0ab94f966a1708\", \"shell_dialogs_unittests\": \"67d95eebdd9a4cfe088ab0af47eec6d5e90e581f\", \"skia_unittests\": \"b6b3633751174000e56b73162f0dcb68b8e24cc9\", \"snapshot_unittests\": \"73625a480ea5d4ef29f00aa7974931eed3ce3069\", \"sql_unittests\": \"d4b02038a4c4fe0d1753e595836643fbd99375f6\", \"storage_unittests\": \"020022601075e775b51131e07bffa781af44389c\", \"sync_integration_tests\": \"068a40b6e6b67e99f465b6d4c009b1ca094f8e91\", \"traffic_annotation_auditor_unittests\": \"57aebf4cb1aefa2d262bc1633a64a769832713d5\", \"ui_base_unittests\": \"7d805242611c2324cee27c0b592ffc334ace4c89\", \"ui_touch_selection_unittests\": \"082c92615980eae9a9c44f2cf5ba30354f1e5f4f\", \"unit_tests\": \"37d308521b27f215c9cff4250772e0345016b9aa\", \"updater_tests\": \"ea977b3207ff07145faeff8f7d09c02a5da07e0c\", \"url_unittests\": \"ff8d501d8bf94d6d5a72446e4b66d304b47fc5ba\", \"views_unittests\": \"5884e696a1e17cba695ff3ce8e731f341e4d0a96\", \"viz_unittests\": \"1f556790765bdced7183de26294f8e80003397f4\", \"vr_common_unittests\": \"984812734c97c318db4becc7d9adc9df11939306\", \"vr_pixeltests\": \"bdac3549d62fe5b4f89bf5c833b236f6dd530760\", \"weblayer_browsertests\": \"25ce0489179be6686498455483d11773ad4b6356\", \"weblayer_unittests\": \"4207feb17e45f55dc1e5ce41751a23576b7bb051\", \"wm_unittests\": \"780c92c0f2dd43ed1b96185c3f841a5f9d059d14\", \"wtf_unittests\": \"701c5306d01c2855cf40fcddce3fed42efec84cd\", \"zlib_unittests\": \"14f3851554b369a841b005b9fb0a0886b0415ab3\", \"zucchini_unittests\": \"6287f3e347cc30fec5774afd87d24f5e0ba80946\"}}, \"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-5-o33n\"], \"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\": [\"5685-1877293\"], \"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": "1613012244094304",
"status": "COMPLETED",
"status_changed_ts": "1613016135780524",
"tags": [
"build_address:luci.chromium.ci/win-asan/17279",
"builder:win-asan",
"buildset:commit/git/bdd804b882bcb3c759ef22fd27d05476b2189826",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bdd804b882bcb3c759ef22fd27d05476b2189826",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021057853958031728",
"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/8855627792055194752/+/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:51a7d718dfe04610",
"user_agent:luci-scheduler"
],
"updated_ts": "1613016135786342",
"url": "https://ci.chromium.org/b/8855627792055194752",
"utcnow_ts": "1613016137172115"
}
}
[04:02:17.180] Invocation finished in 1h4m57.107191797s with status SUCCEEDED