[06:51:15.100] New invocation is queued and will start shortly
[06:51:16.176] Starting the invocation (attempt 1)
[06:51:16.208] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:51:16.208] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:51:16.208] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018597019088000496",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"185ceda258c2fe6d2530182b174cec35d3ebfc1f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018597019088000496",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/185ceda258c2fe6d2530182b174cec35d3ebfc1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/185ceda258c2fe6d2530182b174cec35d3ebfc1f",
"gitiles_ref:refs/heads/master"
]
}
[06:51:16.770] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615359076290789",
"id": "8853166957178743552",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"185ceda258c2fe6d2530182b174cec35d3ebfc1f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615359076661889",
"tags": [
"build_address:luci.chromium.ci/win-asan/17734",
"builder:win-asan",
"buildset:commit/git/185ceda258c2fe6d2530182b174cec35d3ebfc1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/185ceda258c2fe6d2530182b174cec35d3ebfc1f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018597019088000496",
"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/8853166957178743552/+/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": "1615359076661996",
"url": "https://ci.chromium.org/b/8853166957178743552",
"utcnow_ts": "1615359076764453"
}
}
[06:51:16.770] Task URL: https://ci.chromium.org/b/8853166957178743552
[06:51:16.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:2:0) after 1m0s
[06:51:20.284] Received PubSub notification, asking Buildbucket for the build status
[06:51:20.456] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:16.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:2:0)
[06:52:16.921] Timer tick, asking Buildbucket for the build status
[06:52:17.035] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:17.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:4:0) after 1m0s
[06:53:17.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:4:0)
[06:53:17.106] Timer tick, asking Buildbucket for the build status
[06:53:17.164] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:17.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:5:0) after 1m0s
[06:54:17.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:5:0)
[06:54:17.351] Timer tick, asking Buildbucket for the build status
[06:54:17.627] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:17.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:6:0) after 1m0s
[06:55:17.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:6:0)
[06:55:17.669] Timer tick, asking Buildbucket for the build status
[06:55:17.836] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:17.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:7:0) after 1m0s
[06:56:17.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:7:0)
[06:56:17.942] Timer tick, asking Buildbucket for the build status
[06:56:18.031] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:18.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:8:0) after 1m0s
[06:57:18.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:8:0)
[06:57:18.070] Timer tick, asking Buildbucket for the build status
[06:57:18.299] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:18.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:9:0) after 1m0s
[06:58:18.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:9:0)
[06:58:18.383] Timer tick, asking Buildbucket for the build status
[06:58:18.499] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:18.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:10:0) after 1m0s
[06:59:18.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:10:0)
[06:59:18.635] Timer tick, asking Buildbucket for the build status
[06:59:18.946] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:18.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:11:0) after 1m0s
[07:00:19.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:11:0)
[07:00:19.003] Timer tick, asking Buildbucket for the build status
[07:00:19.241] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:19.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:12:0) after 1m0s
[07:01:19.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:12:0)
[07:01:19.326] Timer tick, asking Buildbucket for the build status
[07:01:19.416] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:19.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:13:0) after 1m0s
[07:02:19.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:13:0)
[07:02:19.502] Timer tick, asking Buildbucket for the build status
[07:02:19.576] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:19.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:14:0) after 1m0s
[07:03:19.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:14:0)
[07:03:19.750] Timer tick, asking Buildbucket for the build status
[07:03:19.812] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:19.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:15:0) after 1m0s
[07:04:19.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:15:0)
[07:04:19.869] Timer tick, asking Buildbucket for the build status
[07:04:20.143] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:20.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:16:0) after 1m0s
[07:05:20.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:16:0)
[07:05:20.252] Timer tick, asking Buildbucket for the build status
[07:05:20.441] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:20.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:17:0) after 1m0s
[07:06:20.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:17:0)
[07:06:20.511] Timer tick, asking Buildbucket for the build status
[07:06:20.588] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:20.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:18:0) after 1m0s
[07:07:20.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:18:0)
[07:07:20.632] Timer tick, asking Buildbucket for the build status
[07:07:20.698] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:20.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:19:0) after 1m0s
[07:08:22.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:19:0)
[07:08:22.469] Timer tick, asking Buildbucket for the build status
[07:08:22.538] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:22.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:20:0) after 1m0s
[07:09:22.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:20:0)
[07:09:22.634] Timer tick, asking Buildbucket for the build status
[07:09:22.702] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:22.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:21:0) after 1m0s
[07:10:22.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:21:0)
[07:10:22.732] Timer tick, asking Buildbucket for the build status
[07:10:22.798] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:22.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:22:0) after 1m0s
[07:11:22.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:22:0)
[07:11:22.905] Timer tick, asking Buildbucket for the build status
[07:11:22.997] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:22.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:23:0) after 1m0s
[07:12:23.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:23:0)
[07:12:23.110] Timer tick, asking Buildbucket for the build status
[07:12:23.189] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:23.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:24:0) after 1m0s
[07:13:23.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:24:0)
[07:13:23.336] Timer tick, asking Buildbucket for the build status
[07:13:23.422] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:23.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:25:0) after 1m0s
[07:14:23.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:25:0)
[07:14:23.569] Timer tick, asking Buildbucket for the build status
[07:14:23.651] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:23.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:26:0) after 1m0s
[07:15:23.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:26:0)
[07:15:23.761] Timer tick, asking Buildbucket for the build status
[07:15:23.830] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:23.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:27:0) after 1m0s
[07:16:24.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:27:0)
[07:16:24.014] Timer tick, asking Buildbucket for the build status
[07:16:24.102] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:24.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:28:0) after 1m0s
[07:17:24.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:28:0)
[07:17:24.211] Timer tick, asking Buildbucket for the build status
[07:17:24.283] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:24.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:29:0) after 1m0s
[07:18:24.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:29:0)
[07:18:24.370] Timer tick, asking Buildbucket for the build status
[07:18:24.602] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:24.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:30:0) after 1m0s
[07:19:24.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:30:0)
[07:19:24.723] Timer tick, asking Buildbucket for the build status
[07:19:24.793] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:24.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:31:0) after 1m0s
[07:20:24.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:31:0)
[07:20:24.834] Timer tick, asking Buildbucket for the build status
[07:20:25.415] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:25.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:32:0) after 1m0s
[07:21:25.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:32:0)
[07:21:25.503] Timer tick, asking Buildbucket for the build status
[07:21:25.663] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:25.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:33:0) after 1m0s
[07:22:25.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:33:0)
[07:22:25.730] Timer tick, asking Buildbucket for the build status
[07:22:25.806] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:25.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:34:0) after 1m0s
[07:23:25.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:34:0)
[07:23:25.867] Timer tick, asking Buildbucket for the build status
[07:23:25.962] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:25.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:35:0) after 1m0s
[07:24:26.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:35:0)
[07:24:26.061] Timer tick, asking Buildbucket for the build status
[07:24:26.136] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:26.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:36:0) after 1m0s
[07:25:26.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:36:0)
[07:25:26.203] Timer tick, asking Buildbucket for the build status
[07:25:26.284] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:26.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:37:0) after 1m0s
[07:26:26.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:37:0)
[07:26:26.357] Timer tick, asking Buildbucket for the build status
[07:26:26.504] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:26.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:38:0) after 1m0s
[07:27:26.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:38:0)
[07:27:26.589] Timer tick, asking Buildbucket for the build status
[07:27:26.703] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:26.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:39:0) after 1m0s
[07:28:26.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:39:0)
[07:28:26.721] Timer tick, asking Buildbucket for the build status
[07:28:27.208] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:27.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:40:0) after 1m0s
[07:29:27.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:40:0)
[07:29:27.227] Timer tick, asking Buildbucket for the build status
[07:29:27.470] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:27.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:41:0) after 1m0s
[07:30:27.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:41:0)
[07:30:27.541] Timer tick, asking Buildbucket for the build status
[07:30:27.614] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:27.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:42:0) after 1m0s
[07:31:27.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:42:0)
[07:31:27.692] Timer tick, asking Buildbucket for the build status
[07:31:27.781] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:27.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:43:0) after 1m0s
[07:32:27.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:43:0)
[07:32:27.837] Timer tick, asking Buildbucket for the build status
[07:32:27.922] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:27.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:44:0) after 1m0s
[07:33:27.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:44:0)
[07:33:27.928] Timer tick, asking Buildbucket for the build status
[07:33:28.103] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:28.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:45:0) after 1m0s
[07:34:28.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:45:0)
[07:34:28.106] Timer tick, asking Buildbucket for the build status
[07:34:28.430] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:28.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:46:0) after 1m0s
[07:35:28.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:46:0)
[07:35:28.507] Timer tick, asking Buildbucket for the build status
[07:35:28.855] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:28.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:47:0) after 1m0s
[07:36:28.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:47:0)
[07:36:28.876] Timer tick, asking Buildbucket for the build status
[07:36:29.310] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:29.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:48:0) after 1m0s
[07:37:29.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:48:0)
[07:37:29.326] Timer tick, asking Buildbucket for the build status
[07:37:29.405] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:29.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:49:0) after 1m0s
[07:38:29.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:49:0)
[07:38:29.422] Timer tick, asking Buildbucket for the build status
[07:38:29.535] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:29.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:50:0) after 1m0s
[07:39:29.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:50:0)
[07:39:29.612] Timer tick, asking Buildbucket for the build status
[07:39:29.753] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:29.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:51:0) after 1m0s
[07:40:29.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:51:0)
[07:40:29.771] Timer tick, asking Buildbucket for the build status
[07:40:29.840] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:29.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:52:0) after 1m0s
[07:41:29.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:52:0)
[07:41:29.934] Timer tick, asking Buildbucket for the build status
[07:41:30.027] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:30.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:53:0) after 1m0s
[07:42:30.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:53:0)
[07:42:30.037] Timer tick, asking Buildbucket for the build status
[07:42:30.308] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:30.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:54:0) after 1m0s
[07:43:30.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:54:0)
[07:43:30.338] Timer tick, asking Buildbucket for the build status
[07:43:30.538] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:30.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:55:0) after 1m0s
[07:44:30.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:55:0)
[07:44:30.523] Timer tick, asking Buildbucket for the build status
[07:44:30.598] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:30.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:56:0) after 1m0s
[07:45:30.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:56:0)
[07:45:30.621] Timer tick, asking Buildbucket for the build status
[07:45:30.718] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:30.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:57:0) after 1m0s
[07:46:30.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:57:0)
[07:46:30.726] Timer tick, asking Buildbucket for the build status
[07:46:30.855] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:30.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:58:0) after 1m0s
[07:47:30.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:58:0)
[07:47:30.874] Timer tick, asking Buildbucket for the build status
[07:47:30.954] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:30.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:59:0) after 1m0s
[07:48:30.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:59:0)
[07:48:30.998] Timer tick, asking Buildbucket for the build status
[07:48:31.152] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:31.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:60:0) after 1m0s
[07:49:31.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:60:0)
[07:49:31.169] Timer tick, asking Buildbucket for the build status
[07:49:31.419] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:31.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:61:0) after 1m0s
[07:50:31.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:61:0)
[07:50:31.970] Timer tick, asking Buildbucket for the build status
[07:50:32.050] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:32.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:62:0) after 1m0s
[07:51:32.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:62:0)
[07:51:32.112] Timer tick, asking Buildbucket for the build status
[07:51:32.275] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:32.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:63:0) after 1m0s
[07:52:32.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:63:0)
[07:52:32.341] Timer tick, asking Buildbucket for the build status
[07:52:32.632] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:32.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:64:0) after 1m0s
[07:53:32.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:64:0)
[07:53:32.661] Timer tick, asking Buildbucket for the build status
[07:53:32.831] Build 8853166957178743552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:32.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018597019088000496:65:0) after 1m0s
[07:54:30.258] Received PubSub notification, asking Buildbucket for the build status
[07:54:30.372] Build 8853166957178743552: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:54:30.372] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615362868392567",
"created_by": "project:chromium",
"created_ts": "1615359076290789",
"id": "8853166957178743552",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"185ceda258c2fe6d2530182b174cec35d3ebfc1f\"}}",
"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\": \"185ceda258c2fe6d2530182b174cec35d3ebfc1f\", \"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-8-2xaw\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17734, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"c054008f9efb2f86a0bd6b8031e8c635c69107e4\", \"got_dawn_revision\": \"6d4617a2d28d8d3d4aa946abdbabb368d025b6b7\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"185ceda258c2fe6d2530182b174cec35d3ebfc1f\", \"got_revision_cp\": \"refs/heads/master@{#861452}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"023914a27b634063766fee96f2e6763513f5b059\", \"got_v8_revision\": \"8a7806552684dbddc1265f43895a37f02a6f3fed\", \"got_v8_revision_cp\": \"refs/heads/9.1.87@{#1}\", \"got_webrtc_revision\": \"92d12707e00837a978485f731d72ef30cf19d2a2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33402}\", \"is_cached\": true, \"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\": \"185ceda258c2fe6d2530182b174cec35d3ebfc1f\", \"swarm_hashes_refs/heads/master(at){#861452}_without_patch\": {\"absl_hardening_tests\": \"b53baa6e3d05570bb503e509d14700bca750e3b2\", \"accessibility_unittests\": \"548f26fff14ed94a24f7fa4d68b856671db5b4dc\", \"angle_unittests\": \"29716532ce5e74cdc8549ab636524efd5180afae\", \"app_shell_unittests\": \"bdf480a56daa6188d6b70af180502008e1af66a6\", \"aura_unittests\": \"67179a10ee321122178a61f46dafb9dbb5c77f21\", \"base_unittests\": \"48805d05ffff1599294650de75701c6ff69c593d\", \"base_util_unittests\": \"027886d946fa74dfcff28718e10d333fe1627cb8\", \"blink_common_unittests\": \"1a40dc5184c14cecded3938935a68c362c7eeac5\", \"blink_fuzzer_unittests\": \"341129329e72111557beecfd6db927240a287b23\", \"blink_heap_unittests\": \"6f899690de0a7c01236d90367643538ecffdc973\", \"blink_platform_unittests\": \"647db773b8b0e51445dd4533adb6f4f1bf544c0d\", \"blink_unittests\": \"231bb9713971d141008ee1feb9f787a0ce47d387\", \"boringssl_crypto_tests\": \"b57f45f99c02293c14b92b75129c4e65ab0a3b83\", \"boringssl_ssl_tests\": \"2f4bdd6e445eadc61d3a148b84076c1a5a0a6153\", \"browser_switcher_bho_unittests\": \"19dc16cffb18af80671b228326ddd07ef4907ab3\", \"browser_tests\": \"a987b160f391f7dec70570b99775645e6607c29f\", \"capture_unittests\": \"3bfd350cb0081540972631721d54d33e16df891e\", \"cast_unittests\": \"7f3d20cb5fadf45c946999195d1a69533f7220ca\", \"cc_unittests\": \"90643fe0ce4039a669f69f212e75165572c0aec0\", \"chrome_app_unittests\": \"1a7d3a082bed7fe71cace933454288f4e70a85e0\", \"chrome_cleaner_unittests\": \"741da065bfa520762ad668bf164dbe6813111a73\", \"chrome_elf_unittests\": \"83309d412eeb9089e679d44bdc42bb8c665107fd\", \"chromedriver_unittests\": \"a7ac67f9e43ecb28921d8752964a89c87ff43aa3\", \"color_unittests\": \"4700a9ce238e4a94004093046af7f112a900f68c\", \"components_browsertests\": \"9c13cab846cb162748d20351954f274094162daf\", \"components_unittests\": \"4b4eb9d879af75e00dc68bee011591922a43a01c\", \"compositor_unittests\": \"d6356587e5fead1e1fa2caeb1ed03e97c5af0fcb\", \"content_browsertests\": \"3c42ff50e3a5cea41da7af8b3251a8b41db02d31\", \"content_unittests\": \"63976cda841557179b32ece1ea24e14a4c759f1a\", \"courgette_unittests\": \"6f5e0a895174125a7df234973cd3afdb745169c6\", \"crashpad_tests\": \"a05f0c285038809732f500a4280c063709937992\", \"cronet_tests\": \"c1b93bd644acdefad391a2f952799f23de5768d5\", \"cronet_unittests\": \"f38f4c885f63afab39e3fb77925af9b68c5adc4d\", \"crypto_unittests\": \"519a241b61bbd2aedfc35680d2a0cb3430d4440a\", \"delayloads_unittests\": \"0b61182bce903fbc33799005a9d1419d6fdaa5d6\", \"device_unittests\": \"7b234436e3becc97c090307a5982d53816a58e36\", \"display_unittests\": \"049cbec9b19a00d3f95c4edb3dfcbef65df356ac\", \"elevation_service_unittests\": \"e29f761c4852d63c00beea2951d2a174df166456\", \"events_unittests\": \"b2057fa0f08d54e19a2da02084cb0d941050549a\", \"extensions_browsertests\": \"4267962c43ac2831574eab212f99f19ba8b3ed6b\", \"extensions_unittests\": \"fc63f51ece39d4377f2b8df9e9f0c0ee2cbbbe72\", \"filesystem_service_unittests\": \"1f5bd0e7708a86d21f7bd3c232c2ee08df608bd9\", \"gcm_unit_tests\": \"bf1e622fa20970cf998b430a71c31621f97ef003\", \"gcp_unittests\": \"8b24c67d3abd30656e9d5006c8317557d4a02f8f\", \"gfx_unittests\": \"c0fbea74695722d499bad9d2ad32f4f6727021be\", \"gin_unittests\": \"da11f6038aad5287541f98ef2bd7106f9e534f60\", \"google_apis_unittests\": \"960d844e94a6eda09a5fc52347f194afaa29f5ca\", \"gpu_unittests\": \"8099cabac1487c609162bdb2af561bbb2f8ebf28\", \"gwp_asan_unittests\": \"02d6262d2ce0effa1e08b06ea69ef35db35af373\", \"headless_browsertests\": \"0c5ae2fd5fb68f5e4fd0f39fbce8ab9df7fed694\", \"headless_unittests\": \"0610afd81b762c3bc58380fc8da54deb21b87689\", \"install_static_unittests\": \"ef379d93084ea0cb985fb21e0d1151ecd8805d93\", \"installer_util_unittests\": \"2e36b7ba5272cce95069ff3f28a70861832fa126\", \"interactive_ui_tests\": \"8b08caab352d59688281c26aed94478f17f869a9\", \"ipc_tests\": \"4f7445b55b367bba2ac21232bdff7aca58284cb0\", \"jingle_unittests\": \"b1c9ec0831db076a08c527cc7dfa3e704deef78d\", \"latency_unittests\": \"dd74afe1293b175503875203b791bbc2431a223d\", \"libjingle_xmpp_unittests\": \"46b8cb58a323fd7fdd9098bcdb379dae500db301\", \"liburlpattern_unittests\": \"f2534aa31b04d7937693de7a893b8b11c8d1c67e\", \"media_blink_unittests\": \"9d6b481fcc47a95b703cb6e5009bb6a72f5f4331\", \"media_unittests\": \"00288b55a8d49d1eeb4666d036e1fbcebe3ab4c2\", \"message_center_unittests\": \"84adc374974075a2d6bde1c15b5da19efa1e7dc2\", \"midi_unittests\": \"85301442243e4e5aa0cc1840cd9d9a09a9009cf7\", \"mojo_core_unittests\": \"fb8e7cb6422485c4416fc05c9b19d4e1801d297a\", \"mojo_unittests\": \"664c1c57a25c3e6dbea9f783f9e5b15d2a815274\", \"nacl_loader_unittests\": \"c7fc5840487304834f94ecf62afaaeb463ff5caa\", \"native_theme_unittests\": \"a70f79e944b104b22ef01ec25326f9cab89d0fe6\", \"net_unittests\": \"1fd14e93aaeb3a81e4ff3937f8f6c34424f37103\", \"notification_helper_unittests\": \"75d60a153d720a8f9a0cafe917dc07d5b564b54a\", \"pdf_unittests\": \"19998b96867e34a92d52a485339d4c26f0cba1a6\", \"perfetto_unittests\": \"e79021a30bbc57fe92cc8ca82f956f069ce4b75c\", \"ppapi_unittests\": \"aa12b8aa884f6a5fb942961d127a048d53ba0e92\", \"printing_unittests\": \"4f41d9f15118e32661f9feadb2000da3dc18037e\", \"remoting_unittests\": \"beba31556e863f5d7a745b1db5fa5cd767d859ba\", \"sbox_integration_tests\": \"4004f4bdf845659fa82b208ed9571751a9ef140d\", \"sbox_unittests\": \"3c5fd66b474741add3dc258172c243b392359bf4\", \"sbox_validation_tests\": \"8594ff9cb9a5452e56101fd77afe5ef8cd56904e\", \"service_manager_unittests\": \"323a33115d425185aeea9ddb161bfadd89c8649e\", \"services_unittests\": \"e4a177bc50fab4548bff6c3417d1db07b481334a\", \"setup_unittests\": \"889c5c046f853c2bc053389d2ad41b8e9cfea194\", \"shell_dialogs_unittests\": \"09526014f35dfdd27417e10ee7ee24909792aa2c\", \"skia_unittests\": \"e60958628b606431f15ad7df2ea335c57ff6d243\", \"snapshot_unittests\": \"2f98c015497c3552216a0096232a8c07a53cc046\", \"sql_unittests\": \"11e482103f8a7b93475ed3b13222d070e57e4ce3\", \"storage_unittests\": \"be529b1862ad42622af0b185a8b413f352494a21\", \"sync_integration_tests\": \"19f817d80cb2eae7b2177698507f4aa0f44c2c74\", \"traffic_annotation_auditor_unittests\": \"159b80015e865a5dd589c6d77a72facb1b6d0628\", \"ui_base_unittests\": \"f4dc2fe944dc6d497d32154e2200dfc12236e406\", \"ui_touch_selection_unittests\": \"fefcb99c24191756fece7878dba00ca8178f0168\", \"unit_tests\": \"341a97d9ae4c49e853a50e05862e16cdb3462fa8\", \"updater_tests\": \"cca24e6e72ce77d1e88d972d00d3a8d07c040976\", \"url_unittests\": \"c58b252cbdc3b17459c61faaefea345b855bad49\", \"views_unittests\": \"3a3d3b69ed74138c29d0a79d1fff8613bdd1a4fd\", \"viz_unittests\": \"38a0dcb6e1135c7469ea47b378e38f82ac4d8cd1\", \"vr_common_unittests\": \"8074094bf784c40dcab06a51cb8a738a6656e25d\", \"vr_pixeltests\": \"373e205dba1b3f1510fde302bba283bf0ad7b63d\", \"weblayer_browsertests\": \"2b84350ecdd90114740a3f6dc8cf705e50034ded\", \"weblayer_unittests\": \"72b3b0bec16dac7fa51ed6c5052c61e090a8e50c\", \"wm_unittests\": \"3ed156c13f8241612c6fa9c7c75b9693e026d8e9\", \"wtf_unittests\": \"9f277069c31b6fb1085eb696da7cb19677fb8d33\", \"zlib_unittests\": \"da52011a5518fc33b062c63de6f9180b91cbebcd\", \"zucchini_unittests\": \"18bdf42f3573055425c8bf2a3ce18cfe063e2c3e\"}}, \"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-8-2xaw\"], \"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\": [\"5746-91ff3bd\"], \"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": "1615359079735500",
"status": "COMPLETED",
"status_changed_ts": "1615362868686827",
"tags": [
"build_address:luci.chromium.ci/win-asan/17734",
"builder:win-asan",
"buildset:commit/git/185ceda258c2fe6d2530182b174cec35d3ebfc1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/185ceda258c2fe6d2530182b174cec35d3ebfc1f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018597019088000496",
"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/8853166957178743552/+/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:5233b8f24c633e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615362868702497",
"url": "https://ci.chromium.org/b/8853166957178743552",
"utcnow_ts": "1615362870356265"
}
}
[07:54:30.372] Invocation finished in 1h3m15.286241562s with status SUCCEEDED