[21:46:14.486] New invocation is queued and will start shortly
[21:46:18.049] Starting the invocation (attempt 1)
[21:46:18.092] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:46:18.092] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:46:18.093] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029140556274425760",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"916c884f442e35973d925b676f676194f6f4ae13\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029140556274425760",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/916c884f442e35973d925b676f676194f6f4ae13",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/916c884f442e35973d925b676f676194f6f4ae13",
"gitiles_ref:refs/heads/master"
]
}
[21:46:19.145] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605303978467191",
"id": "8863710491433463424",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"916c884f442e35973d925b676f676194f6f4ae13\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605303978951825",
"tags": [
"build_address:luci.chromium.ci/win-asan/15627",
"builder:win-asan",
"buildset:commit/git/916c884f442e35973d925b676f676194f6f4ae13",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/916c884f442e35973d925b676f676194f6f4ae13",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029140556274425760",
"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/8863710491433463424/+/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": "1605303978951953",
"url": "https://ci.chromium.org/b/8863710491433463424",
"utcnow_ts": "1605303979134419"
}
}
[21:46:19.145] Task URL: https://ci.chromium.org/b/8863710491433463424
[21:46:19.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:2:0) after 1m0s
[21:46:24.572] Received PubSub notification, asking Buildbucket for the build status
[21:46:24.728] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:19.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:2:0)
[21:47:19.163] Timer tick, asking Buildbucket for the build status
[21:47:19.381] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:19.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:4:0) after 1m0s
[21:48:19.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:4:0)
[21:48:19.431] Timer tick, asking Buildbucket for the build status
[21:48:19.941] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:19.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:5:0) after 1m0s
[21:49:19.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:5:0)
[21:49:19.995] Timer tick, asking Buildbucket for the build status
[21:49:20.121] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:20.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:6:0) after 1m0s
[21:50:20.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:6:0)
[21:50:20.131] Timer tick, asking Buildbucket for the build status
[21:50:20.446] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:20.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:7:0) after 1m0s
[21:51:20.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:7:0)
[21:51:20.520] Timer tick, asking Buildbucket for the build status
[21:51:20.602] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:20.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:8:0) after 1m0s
[21:52:20.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:8:0)
[21:52:20.710] Timer tick, asking Buildbucket for the build status
[21:52:21.153] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:21.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:9:0) after 1m0s
[21:53:21.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:9:0)
[21:53:21.143] Timer tick, asking Buildbucket for the build status
[21:53:21.243] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:21.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:10:0) after 1m0s
[21:54:21.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:10:0)
[21:54:21.329] Timer tick, asking Buildbucket for the build status
[21:54:21.399] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:21.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:11:0) after 1m0s
[21:55:21.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:11:0)
[21:55:21.441] Timer tick, asking Buildbucket for the build status
[21:55:21.515] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:21.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:12:0) after 1m0s
[21:56:21.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:12:0)
[21:56:21.533] Timer tick, asking Buildbucket for the build status
[21:56:21.640] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:21.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:13:0) after 1m0s
[21:57:21.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:13:0)
[21:57:21.660] Timer tick, asking Buildbucket for the build status
[21:57:21.749] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:21.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:14:0) after 1m0s
[21:58:21.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:14:0)
[21:58:21.766] Timer tick, asking Buildbucket for the build status
[21:58:21.827] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:21.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:15:0) after 1m0s
[21:59:21.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:15:0)
[21:59:21.826] Timer tick, asking Buildbucket for the build status
[21:59:21.937] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:21.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:16:0) after 1m0s
[22:00:22.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:16:0)
[22:00:22.018] Timer tick, asking Buildbucket for the build status
[22:00:22.093] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:22.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:17:0) after 1m0s
[22:01:22.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:17:0)
[22:01:22.146] Timer tick, asking Buildbucket for the build status
[22:01:22.255] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:22.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:18:0) after 1m0s
[22:02:22.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:18:0)
[22:02:22.274] Timer tick, asking Buildbucket for the build status
[22:02:22.393] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:22.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:19:0) after 1m0s
[22:03:22.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:19:0)
[22:03:22.431] Timer tick, asking Buildbucket for the build status
[22:03:22.506] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:22.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:20:0) after 1m0s
[22:04:22.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:20:0)
[22:04:22.533] Timer tick, asking Buildbucket for the build status
[22:04:23.413] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:23.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:21:0) after 1m0s
[22:05:23.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:21:0)
[22:05:23.420] Timer tick, asking Buildbucket for the build status
[22:05:23.647] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:23.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:22:0) after 1m0s
[22:06:23.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:22:0)
[22:06:23.744] Timer tick, asking Buildbucket for the build status
[22:06:23.847] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:23.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:23:0) after 1m0s
[22:07:23.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:23:0)
[22:07:23.889] Timer tick, asking Buildbucket for the build status
[22:07:23.973] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:23.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:24:0) after 1m0s
[22:08:24.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:24:0)
[22:08:24.031] Timer tick, asking Buildbucket for the build status
[22:08:24.902] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:24.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:25:0) after 1m0s
[22:09:24.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:25:0)
[22:09:24.900] Timer tick, asking Buildbucket for the build status
[22:09:25.540] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:25.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:26:0) after 1m0s
[22:10:25.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:26:0)
[22:10:25.526] Timer tick, asking Buildbucket for the build status
[22:10:25.725] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:25.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:27:0) after 1m0s
[22:11:25.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:27:0)
[22:11:25.796] Timer tick, asking Buildbucket for the build status
[22:11:26.063] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:26.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:28:0) after 1m0s
[22:12:26.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:28:0)
[22:12:26.123] Timer tick, asking Buildbucket for the build status
[22:12:26.352] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:26.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:29:0) after 1m0s
[22:13:26.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:29:0)
[22:13:26.428] Timer tick, asking Buildbucket for the build status
[22:13:26.540] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:26.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:30:0) after 1m0s
[22:14:26.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:30:0)
[22:14:26.718] Timer tick, asking Buildbucket for the build status
[22:14:26.860] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:26.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:31:0) after 1m0s
[22:15:26.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:31:0)
[22:15:26.928] Timer tick, asking Buildbucket for the build status
[22:15:27.062] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:27.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:32:0) after 1m0s
[22:16:27.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:32:0)
[22:16:27.259] Timer tick, asking Buildbucket for the build status
[22:16:27.898] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:27.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:33:0) after 1m0s
[22:17:27.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:33:0)
[22:17:27.933] Timer tick, asking Buildbucket for the build status
[22:17:28.213] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:28.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:34:0) after 1m0s
[22:18:28.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:34:0)
[22:18:28.276] Timer tick, asking Buildbucket for the build status
[22:18:28.517] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:28.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:35:0) after 1m0s
[22:19:28.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:35:0)
[22:19:28.564] Timer tick, asking Buildbucket for the build status
[22:19:28.685] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:28.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:36:0) after 1m0s
[22:20:28.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:36:0)
[22:20:28.854] Timer tick, asking Buildbucket for the build status
[22:20:29.058] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:29.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:37:0) after 1m0s
[22:21:29.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:37:0)
[22:21:29.224] Timer tick, asking Buildbucket for the build status
[22:21:29.374] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:29.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:38:0) after 1m0s
[22:22:29.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:38:0)
[22:22:29.513] Timer tick, asking Buildbucket for the build status
[22:22:29.613] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:29.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:39:0) after 1m0s
[22:23:29.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:39:0)
[22:23:29.884] Timer tick, asking Buildbucket for the build status
[22:23:29.969] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:29.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:40:0) after 1m0s
[22:24:30.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:40:0)
[22:24:30.141] Timer tick, asking Buildbucket for the build status
[22:24:30.394] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:30.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:41:0) after 1m0s
[22:25:30.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:41:0)
[22:25:30.424] Timer tick, asking Buildbucket for the build status
[22:25:30.680] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:30.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:42:0) after 1m0s
[22:26:30.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:42:0)
[22:26:30.754] Timer tick, asking Buildbucket for the build status
[22:26:31.341] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:31.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:43:0) after 1m0s
[22:27:31.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:43:0)
[22:27:31.374] Timer tick, asking Buildbucket for the build status
[22:27:31.591] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:31.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:44:0) after 1m0s
[22:28:31.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:44:0)
[22:28:31.686] Timer tick, asking Buildbucket for the build status
[22:28:31.800] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:31.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:45:0) after 1m0s
[22:29:31.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:45:0)
[22:29:31.879] Timer tick, asking Buildbucket for the build status
[22:29:32.140] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:32.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:46:0) after 1m0s
[22:30:32.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:46:0)
[22:30:32.174] Timer tick, asking Buildbucket for the build status
[22:30:32.569] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:32.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:47:0) after 1m0s
[22:31:32.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:47:0)
[22:31:32.593] Timer tick, asking Buildbucket for the build status
[22:31:32.711] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:32.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:48:0) after 1m0s
[22:32:32.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:48:0)
[22:32:32.768] Timer tick, asking Buildbucket for the build status
[22:32:33.007] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:33.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:49:0) after 1m0s
[22:33:33.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:49:0)
[22:33:33.081] Timer tick, asking Buildbucket for the build status
[22:33:33.194] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:33.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:50:0) after 1m0s
[22:34:33.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:50:0)
[22:34:33.263] Timer tick, asking Buildbucket for the build status
[22:34:33.410] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:33.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:51:0) after 1m0s
[22:35:33.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:51:0)
[22:35:33.439] Timer tick, asking Buildbucket for the build status
[22:35:33.793] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:33.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:52:0) after 1m0s
[22:36:33.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:52:0)
[22:36:33.834] Timer tick, asking Buildbucket for the build status
[22:36:34.049] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:34.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:53:0) after 1m0s
[22:37:34.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:53:0)
[22:37:34.148] Timer tick, asking Buildbucket for the build status
[22:37:34.722] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:34.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:54:0) after 1m0s
[22:38:34.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:54:0)
[22:38:34.708] Timer tick, asking Buildbucket for the build status
[22:38:35.113] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:35.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:55:0) after 1m0s
[22:39:35.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:55:0)
[22:39:35.179] Timer tick, asking Buildbucket for the build status
[22:39:35.261] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:35.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:56:0) after 1m0s
[22:40:35.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:56:0)
[22:40:35.291] Timer tick, asking Buildbucket for the build status
[22:40:35.417] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:35.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:57:0) after 1m0s
[22:41:35.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:57:0)
[22:41:35.433] Timer tick, asking Buildbucket for the build status
[22:41:35.608] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:35.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:58:0) after 1m0s
[22:42:35.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:58:0)
[22:42:35.681] Timer tick, asking Buildbucket for the build status
[22:42:36.207] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:36.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:59:0) after 1m0s
[22:43:36.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:59:0)
[22:43:36.313] Timer tick, asking Buildbucket for the build status
[22:43:36.427] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:36.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:60:0) after 1m0s
[22:44:36.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:60:0)
[22:44:36.444] Timer tick, asking Buildbucket for the build status
[22:44:36.675] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:36.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:61:0) after 1m0s
[22:45:36.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:61:0)
[22:45:36.717] Timer tick, asking Buildbucket for the build status
[22:45:36.802] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:36.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:62:0) after 1m0s
[22:46:36.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:62:0)
[22:46:36.862] Timer tick, asking Buildbucket for the build status
[22:46:37.245] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:37.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:63:0) after 1m0s
[22:47:37.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:63:0)
[22:47:37.327] Timer tick, asking Buildbucket for the build status
[22:47:37.517] Build 8863710491433463424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:37.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029140556274425760:64:0) after 1m0s
[22:48:24.484] Received PubSub notification, asking Buildbucket for the build status
[22:48:24.584] Build 8863710491433463424: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:48:24.584] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605307702905542",
"created_by": "project:chromium",
"created_ts": "1605303978467191",
"failure_reason": "BUILD_FAILURE",
"id": "8863710491433463424",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"916c884f442e35973d925b676f676194f6f4ae13\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- All/SingleRulesetTest.SessionRules/0\\n\\n- All/SingleRulesetTest.SessionRules/1\"}, \"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\": \"916c884f442e35973d925b676f676194f6f4ae13\", \"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-d467\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15627, \"clang_revision\": \"llvmorg-12-init-11462-g418f18c6-1\", \"got_angle_revision\": \"f46e6201b40bdee3e0b79d5d97660fc302fd76f5\", \"got_dawn_revision\": \"56523dff69837130be6a4a1151dac8922f59df80\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"916c884f442e35973d925b676f676194f6f4ae13\", \"got_revision_cp\": \"refs/heads/master@{#827421}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e4c1a25cc6797a47043fc415c53b8edcd7b3e37e\", \"got_v8_revision\": \"2dbcdc105b963ee2501c82139eef7e0603977ff0\", \"got_v8_revision_cp\": \"refs/heads/8.8.278@{#1}\", \"got_webrtc_revision\": \"c4ad6062fd17ce422cac7a871210218d3c487f15\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32605}\", \"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\": \"916c884f442e35973d925b676f676194f6f4ae13\", \"swarm_hashes_refs/heads/master(at){#827421}_without_patch\": {\"absl_hardening_tests\": \"8fdce121a8cf002264e2fb80e0a20f978241f3b0\", \"accessibility_unittests\": \"ecbd03ee504991c9edc7d566b131d4db2597d317\", \"angle_unittests\": \"793d78553fe5fdc8c3243ff9d981d3d580dfd2b1\", \"app_shell_unittests\": \"14a7f510888175ade2481bddb12855b2b84f9f52\", \"aura_unittests\": \"d8490c8350f2b56aee0909b209fc2340fcf073ba\", \"base_unittests\": \"daa0a319b744e4f68ffa792e75e850d8c75029e3\", \"base_util_unittests\": \"f63d14f9825f8d85fc5bafa3b2815ee991c9c5f8\", \"blink_common_unittests\": \"19db551e076c93d58f736b9a32327842646b11fb\", \"blink_fuzzer_unittests\": \"3b32252c5923041563b6b1b2b2febc44520d0aaf\", \"blink_heap_unittests\": \"fce3b8ca6bed9688ec93860fe1aa4a0cd9cd994e\", \"blink_platform_unittests\": \"2a1a34b4b621117869e3eed915980fcc7c732138\", \"blink_unittests\": \"4bfa9909d330661641aa657b746abf1cb973cd4a\", \"boringssl_crypto_tests\": \"9f74f5080151385732dc8e1e6ad2dfdb35b3e9b2\", \"boringssl_ssl_tests\": \"270fae3ecae4b6ef860a6f0c5ee2364229cb6839\", \"browser_switcher_bho_unittests\": \"b86bdf6957c8e05b73e2a5cdb2604b277bae9877\", \"browser_tests\": \"b9bc50c15c19b12176ca1e2ab8c2793849c876dd\", \"capture_unittests\": \"e9c8dd7bea93786dd1ae337e5d0d1273d40352be\", \"cast_unittests\": \"c4e757a12808adc7e9459472f255dbc01a27eb91\", \"cc_unittests\": \"9cb2322418a90640e47ecd41bc457a51e93b86ae\", \"chrome_app_unittests\": \"94a35fe7254f6bc8255ec3eb31a244ce85111745\", \"chrome_cleaner_unittests\": \"6ff6c6081cc95f3708ae8acae726e55d41ab1c94\", \"chrome_elf_unittests\": \"23a2c31036cf2df0327b8b1bb3d17be1b6d10325\", \"chromedriver_unittests\": \"37f164c003a8bc3509c46894f0ac524a91bbb9c7\", \"components_browsertests\": \"bb416f30b714fd99d3b7675efe14f0fc3a424c25\", \"components_unittests\": \"b5c8c8eb40a7c3344e15423f4ee1dde975b398c6\", \"compositor_unittests\": \"c8bf34374bf187fdb5e83b0982465cca1cd9c95f\", \"content_browsertests\": \"20dcb9f716b178afe8ee20d87b0b37078a955e92\", \"content_unittests\": \"e8169c62697389685ac7988f17b2fcc1a23d6916\", \"courgette_unittests\": \"347b28e52d0ed0cd77cbf6f8eb065b0dee02f23f\", \"crashpad_tests\": \"9e00cdbca469786c10c605e4b7e04afcee40256c\", \"cronet_tests\": \"f6cbc3133be949c2960f68ab43580b5e598782b9\", \"cronet_unittests\": \"5370f1df10cec4cdc93aa9854eff62bce43c32de\", \"crypto_unittests\": \"37a68372ffa2b0ff1012c49dd5050a8bb932aeb2\", \"delayloads_unittests\": \"6dc31cb2c9a4719973a46d29f8a92f8bd96e2adb\", \"device_unittests\": \"2a319fe1babbefb24b5d9d073f29b6b83eaf7623\", \"display_unittests\": \"00c10a5171e545e64acc00901ff317bedc180cda\", \"elevation_service_unittests\": \"4057b1b61a0620e2a23e42c74d75e2720a884595\", \"events_unittests\": \"066e497247e2d7cbfd2f784da616936fc9db9bb3\", \"extensions_browsertests\": \"778a129a300713084df86a9a422f6d6e7710cc93\", \"extensions_unittests\": \"35babf1c338ecf2509c04c654803d174de965f2a\", \"filesystem_service_unittests\": \"54195d8d605c70c63980e52e7f23e68094cc4c93\", \"gcm_unit_tests\": \"2dbde3921af2fbeb0d4584cbffa8a98c13198bf0\", \"gcp_unittests\": \"0f2830577ac19a14115bf0888052f7f2a1e6ca28\", \"gfx_unittests\": \"18185a74408407310d19a33750053538875d27ec\", \"gin_unittests\": \"b087fb9bf6e9de6c5aba617f6d9ffc7cddbb4fcb\", \"google_apis_unittests\": \"b5ce7e20b6e5f78035e5a48493e1b96b368b39e1\", \"gpu_unittests\": \"3c14d0647b4c7eb5f85fc7fd0a6e89d79f5d5d60\", \"gwp_asan_unittests\": \"1330d13404b97104cbddcac1f9d35ac48587cb89\", \"headless_browsertests\": \"9dbbe8cba73164c4173136bbd0bdba130b9ccf8d\", \"headless_unittests\": \"4dd287ae46544efa8383fd17b78ade20cb84dcbd\", \"install_static_unittests\": \"a672a5990e8c2c56143677989b1bce7a5c862dc0\", \"installer_util_unittests\": \"85ef402f85b2921aadbe9964b3fa77cc638b6daf\", \"interactive_ui_tests\": \"f1babc32e4116947c13b393d1ea9693828ac36b2\", \"ipc_tests\": \"2187f1c45948946e6d3d21f27078c31f779dfe6e\", \"jingle_unittests\": \"7279a24a2e5fbae0cb00286f2c2cec23bfce25c0\", \"latency_unittests\": \"5b7c419eebeda78694e4b4b8e6b7d22dde517eed\", \"libjingle_xmpp_unittests\": \"e29d8be7c23262221f8bab32ee80d9699c35bf62\", \"liburlpattern_unittests\": \"44ee0f09cc36b7b70b2b6b7a12b47d06484fccd0\", \"media_blink_unittests\": \"34477d7f08969f8d3d93a31455a97773edc02e24\", \"media_unittests\": \"ea5ec2b127ef6feb6af65a7d16c6ab084e09dacd\", \"message_center_unittests\": \"980a29106f018100aae6dc9efae86719af8d42c0\", \"midi_unittests\": \"71e989efe49d8493f90454c8f49b1708fb154131\", \"mojo_core_unittests\": \"fcc04ed2b3f06c33ef31964f9486ce13ca833e6e\", \"mojo_unittests\": \"d4093f709a515ef8d017c54d0c4e61efa262c85b\", \"nacl_loader_unittests\": \"37b71d6281c04f23769d2c5d73d7babf36dc1b70\", \"native_theme_unittests\": \"5fdd0a78a077c279463eac2d42491065d9e29e42\", \"net_unittests\": \"11ecb6443a7ac8ec334bccf7408a5f7d6ec29c48\", \"notification_helper_unittests\": \"a30a824d077e335be3b528518c184a29b0aef8b0\", \"pdf_unittests\": \"4e6af1d8c40d341b4b7be3b6ab4eba5012018a00\", \"perfetto_unittests\": \"a493374d6cdf9b257f0a9a97df452b9d34b33341\", \"ppapi_unittests\": \"0ab56c253fd3365b5194b3acf3fca533ecd7a3b2\", \"printing_unittests\": \"85d6b28d81d893d393f26ba11a7665409f79865d\", \"remoting_unittests\": \"45e8a1e8d282de546962901b3ef819a153ab6b9f\", \"sbox_integration_tests\": \"0623cef678bbb6d080afb69178df152215b176b9\", \"sbox_unittests\": \"3be2643c596790c32a6f59c4cf2ce7e3e02cc20c\", \"sbox_validation_tests\": \"e1b44069fd4ab27004c3ce6b8b4cb6871c2135e5\", \"service_manager_unittests\": \"bf2fda034ac3a6c2a21265113a9e107e6b97e9d9\", \"services_unittests\": \"2730a83b846681a14bc1b9c5da179f96ec3427ee\", \"setup_unittests\": \"dd7ed26ed15d74ecd3fb78d5fb9991cbb94c8a24\", \"shell_dialogs_unittests\": \"7a7fdd65b1c0c5c70d234c67df584ff3a3bb1723\", \"skia_unittests\": \"3ca21584bf2e50fdf3c18a93eb8a31448d0667eb\", \"snapshot_unittests\": \"550983ea8c1e61f48ba0dc4897022272bc9e16ee\", \"sql_unittests\": \"507b4eaeee417d588366228fb054fce24e891c3c\", \"storage_unittests\": \"b04e23e7d40f1c4c78c6270e3e73b353927e49ef\", \"sync_integration_tests\": \"41f950cd8ba46714d769f4f899f1a65cd932e3e0\", \"traffic_annotation_auditor_unittests\": \"560be3c2684569908b6723013c1263569246bf4f\", \"ui_base_unittests\": \"e8d34a0bff244bc0c73bf7e869f71a8a46270914\", \"ui_touch_selection_unittests\": \"44179d955ae39c5b1cf50aa6a6e0d43e596e6a23\", \"unit_tests\": \"b6a2855edcc7ce5317b020114a104434cbc6f1b2\", \"updater_tests\": \"6363aad48bfc761a1324aedf765d8285db6474aa\", \"url_unittests\": \"8a71edbb575e5714c496945b0cacfd13b8af8653\", \"views_unittests\": \"34572fb4785468dbba4e710ea26a0587b0aacdb1\", \"viz_unittests\": \"4b1df9f49540ecf199816434076555992339903f\", \"vr_common_unittests\": \"a07bf7f487d5d1dd9a30a1a706ee60d68905e80c\", \"vr_pixeltests\": \"e0130907eaec18238e483c4a02642ab6da1dd40c\", \"weblayer_browsertests\": \"242cf292355d7d1b52461ae479a50b605b857ac2\", \"weblayer_unittests\": \"919d854ae36f261d4089c106597380c7739ce283\", \"wm_unittests\": \"4d58f1eb727d9dbcdc0d9803efd5cac6b9c0b139\", \"wtf_unittests\": \"c221092a760242813cd7722432537dcd4506609c\", \"zlib_unittests\": \"a5af3f3414411e2974932e8bb5f8d197c4be7fb8\", \"zucchini_unittests\": \"56d1ac0748e1bb5cc711cfae73d606e7e234faa4\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_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-d467\"], \"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\": [\"5626-39642e9\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- All/SingleRulesetTest.SessionRules/0\\n\\n- All/SingleRulesetTest.SessionRules/1\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605303983592491",
"status": "COMPLETED",
"status_changed_ts": "1605303984291526",
"tags": [
"build_address:luci.chromium.ci/win-asan/15627",
"builder:win-asan",
"buildset:commit/git/916c884f442e35973d925b676f676194f6f4ae13",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/916c884f442e35973d925b676f676194f6f4ae13",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029140556274425760",
"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/8863710491433463424/+/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:4fdc6456592f6a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605307703763450",
"url": "https://ci.chromium.org/b/8863710491433463424",
"utcnow_ts": "1605307704575589"
}
}
[22:48:24.584] Invocation finished in 1h2m10.117226324s with status FAILED