[04:57:08.959] New invocation is queued and will start shortly
[04:57:10.025] Starting the invocation (attempt 1)
[04:57:10.074] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:57:10.082] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:57:10.082] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029566430726241680",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"fb44fe7bc082243c858dbfa3bbe15f4db0c1649e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029566430726241680",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/fb44fe7bc082243c858dbfa3bbe15f4db0c1649e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fb44fe7bc082243c858dbfa3bbe15f4db0c1649e",
"gitiles_ref:refs/heads/master"
]
}
[04:57:10.830] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604897830180380",
"id": "8864136368778934592",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fb44fe7bc082243c858dbfa3bbe15f4db0c1649e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604897830592409",
"tags": [
"build_address:luci.chromium.ci/win-asan/15545",
"builder:win-asan",
"buildset:commit/git/fb44fe7bc082243c858dbfa3bbe15f4db0c1649e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fb44fe7bc082243c858dbfa3bbe15f4db0c1649e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029566430726241680",
"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/8864136368778934592/+/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": "1604897830592527",
"url": "https://ci.chromium.org/b/8864136368778934592",
"utcnow_ts": "1604897830823360"
}
}
[04:57:10.830] Task URL: https://ci.chromium.org/b/8864136368778934592
[04:57:10.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:2:0) after 1m0s
[04:57:13.468] Received PubSub notification, asking Buildbucket for the build status
[04:57:13.556] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:10.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:2:0)
[04:58:10.980] Timer tick, asking Buildbucket for the build status
[04:58:11.087] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:11.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:4:0) after 1m0s
[04:59:11.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:4:0)
[04:59:11.158] Timer tick, asking Buildbucket for the build status
[04:59:11.264] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:11.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:5:0) after 1m0s
[05:00:11.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:5:0)
[05:00:11.359] Timer tick, asking Buildbucket for the build status
[05:00:11.818] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:11.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:6:0) after 1m0s
[05:01:11.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:6:0)
[05:01:11.820] Timer tick, asking Buildbucket for the build status
[05:01:11.926] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:11.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:7:0) after 1m0s
[05:02:11.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:7:0)
[05:02:11.983] Timer tick, asking Buildbucket for the build status
[05:02:12.200] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:12.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:8:0) after 1m0s
[05:03:12.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:8:0)
[05:03:12.305] Timer tick, asking Buildbucket for the build status
[05:03:13.141] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:13.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:9:0) after 1m0s
[05:04:13.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:9:0)
[05:04:13.250] Timer tick, asking Buildbucket for the build status
[05:04:14.602] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:14.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:10:0) after 1m0s
[05:05:14.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:10:0)
[05:05:14.618] Timer tick, asking Buildbucket for the build status
[05:05:16.616] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:16.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:11:0) after 1m0s
[05:06:16.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:11:0)
[05:06:16.654] Timer tick, asking Buildbucket for the build status
[05:06:16.743] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:16.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:12:0) after 1m0s
[05:07:16.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:12:0)
[05:07:16.827] Timer tick, asking Buildbucket for the build status
[05:07:16.987] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:16.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:13:0) after 1m0s
[05:08:17.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:13:0)
[05:08:17.143] Timer tick, asking Buildbucket for the build status
[05:08:17.266] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:17.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:14:0) after 1m0s
[05:09:17.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:14:0)
[05:09:17.320] Timer tick, asking Buildbucket for the build status
[05:09:17.747] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:17.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:15:0) after 1m0s
[05:10:17.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:15:0)
[05:10:17.822] Timer tick, asking Buildbucket for the build status
[05:10:18.218] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:18.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:16:0) after 1m0s
[05:11:18.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:16:0)
[05:11:18.257] Timer tick, asking Buildbucket for the build status
[05:11:18.400] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:18.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:17:0) after 1m0s
[05:12:18.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:17:0)
[05:12:18.495] Timer tick, asking Buildbucket for the build status
[05:12:18.658] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:18.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:18:0) after 1m0s
[05:13:18.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:18:0)
[05:13:18.867] Timer tick, asking Buildbucket for the build status
[05:13:18.964] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:18.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:19:0) after 1m0s
[05:14:19.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:19:0)
[05:14:19.140] Timer tick, asking Buildbucket for the build status
[05:14:19.268] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:19.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:20:0) after 1m0s
[05:15:19.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:20:0)
[05:15:19.431] Timer tick, asking Buildbucket for the build status
[05:15:19.580] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:19.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:21:0) after 1m0s
[05:16:19.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:21:0)
[05:16:19.756] Timer tick, asking Buildbucket for the build status
[05:16:19.964] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:19.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:22:0) after 1m0s
[05:17:20.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:22:0)
[05:17:20.005] Timer tick, asking Buildbucket for the build status
[05:17:20.155] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:20.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:23:0) after 1m0s
[05:18:20.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:23:0)
[05:18:20.321] Timer tick, asking Buildbucket for the build status
[05:18:20.443] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:20.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:24:0) after 1m0s
[05:19:20.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:24:0)
[05:19:20.574] Timer tick, asking Buildbucket for the build status
[05:19:22.325] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:22.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:25:0) after 1m0s
[05:20:22.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:25:0)
[05:20:22.570] Timer tick, asking Buildbucket for the build status
[05:20:22.753] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:22.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:26:0) after 1m0s
[05:21:22.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:26:0)
[05:21:22.828] Timer tick, asking Buildbucket for the build status
[05:21:23.023] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:23.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:27:0) after 1m0s
[05:22:23.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:27:0)
[05:22:23.098] Timer tick, asking Buildbucket for the build status
[05:22:23.784] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:23.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:28:0) after 1m0s
[05:23:23.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:28:0)
[05:23:23.933] Timer tick, asking Buildbucket for the build status
[05:23:24.060] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:24.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:29:0) after 1m0s
[05:24:24.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:29:0)
[05:24:24.142] Timer tick, asking Buildbucket for the build status
[05:24:24.218] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:24.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:30:0) after 1m0s
[05:25:24.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:30:0)
[05:25:24.232] Timer tick, asking Buildbucket for the build status
[05:25:25.417] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:25.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:31:0) after 1m0s
[05:26:25.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:31:0)
[05:26:25.448] Timer tick, asking Buildbucket for the build status
[05:26:25.549] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:25.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:32:0) after 1m0s
[05:27:25.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:32:0)
[05:27:25.617] Timer tick, asking Buildbucket for the build status
[05:27:25.720] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:25.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:33:0) after 1m0s
[05:28:25.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:33:0)
[05:28:25.761] Timer tick, asking Buildbucket for the build status
[05:28:25.874] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:25.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:34:0) after 1m0s
[05:29:26.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:34:0)
[05:29:26.027] Timer tick, asking Buildbucket for the build status
[05:29:26.113] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:26.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:35:0) after 1m0s
[05:30:26.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:35:0)
[05:30:26.117] Timer tick, asking Buildbucket for the build status
[05:30:26.192] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:26.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:36:0) after 1m0s
[05:31:26.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:36:0)
[05:31:26.279] Timer tick, asking Buildbucket for the build status
[05:31:26.343] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:26.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:37:0) after 1m0s
[05:32:26.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:37:0)
[05:32:26.438] Timer tick, asking Buildbucket for the build status
[05:32:26.713] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:26.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:38:0) after 1m0s
[05:33:26.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:38:0)
[05:33:26.766] Timer tick, asking Buildbucket for the build status
[05:33:26.837] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:26.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:39:0) after 1m0s
[05:34:26.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:39:0)
[05:34:26.860] Timer tick, asking Buildbucket for the build status
[05:34:27.286] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:27.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:40:0) after 1m0s
[05:35:27.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:40:0)
[05:35:27.320] Timer tick, asking Buildbucket for the build status
[05:35:27.437] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:27.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:41:0) after 1m0s
[05:36:27.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:41:0)
[05:36:27.504] Timer tick, asking Buildbucket for the build status
[05:36:27.903] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:27.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:42:0) after 1m0s
[05:37:27.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:42:0)
[05:37:27.947] Timer tick, asking Buildbucket for the build status
[05:37:28.072] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:28.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:43:0) after 1m0s
[05:38:28.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:43:0)
[05:38:28.148] Timer tick, asking Buildbucket for the build status
[05:38:28.267] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:28.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:44:0) after 1m0s
[05:39:28.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:44:0)
[05:39:28.392] Timer tick, asking Buildbucket for the build status
[05:39:28.601] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:28.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:45:0) after 1m0s
[05:40:28.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:45:0)
[05:40:28.724] Timer tick, asking Buildbucket for the build status
[05:40:28.843] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:28.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:46:0) after 1m0s
[05:41:28.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:46:0)
[05:41:28.917] Timer tick, asking Buildbucket for the build status
[05:41:29.397] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:29.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:47:0) after 1m0s
[05:42:29.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:47:0)
[05:42:29.434] Timer tick, asking Buildbucket for the build status
[05:42:29.566] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:29.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:48:0) after 1m0s
[05:43:29.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:48:0)
[05:43:29.627] Timer tick, asking Buildbucket for the build status
[05:43:29.695] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:29.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:49:0) after 1m0s
[05:44:29.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:49:0)
[05:44:29.778] Timer tick, asking Buildbucket for the build status
[05:44:29.857] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:29.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:50:0) after 1m0s
[05:45:29.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:50:0)
[05:45:29.927] Timer tick, asking Buildbucket for the build status
[05:45:30.001] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:30.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:51:0) after 1m0s
[05:46:30.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:51:0)
[05:46:30.047] Timer tick, asking Buildbucket for the build status
[05:46:30.140] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:30.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:52:0) after 1m0s
[05:47:30.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:52:0)
[05:47:30.236] Timer tick, asking Buildbucket for the build status
[05:47:30.494] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:30.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:53:0) after 1m0s
[05:48:30.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:53:0)
[05:48:30.594] Timer tick, asking Buildbucket for the build status
[05:48:30.678] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:30.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:54:0) after 1m0s
[05:49:30.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:54:0)
[05:49:30.747] Timer tick, asking Buildbucket for the build status
[05:49:30.993] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:30.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:55:0) after 1m0s
[05:50:31.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:55:0)
[05:50:31.020] Timer tick, asking Buildbucket for the build status
[05:50:31.107] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:31.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:56:0) after 1m0s
[05:51:31.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:56:0)
[05:51:31.187] Timer tick, asking Buildbucket for the build status
[05:51:31.344] Build 8864136368778934592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:31.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029566430726241680:57:0) after 1m0s
[05:52:28.954] Received PubSub notification, asking Buildbucket for the build status
[05:52:29.487] Build 8864136368778934592: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:52:29.487] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604901147791266",
"created_by": "project:chromium",
"created_ts": "1604897830180380",
"id": "8864136368778934592",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fb44fe7bc082243c858dbfa3bbe15f4db0c1649e\"}}",
"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\": \"fb44fe7bc082243c858dbfa3bbe15f4db0c1649e\", \"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-b9eu\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15545, \"clang_revision\": \"llvmorg-12-init-10666-gc9f69ee7-1\", \"got_angle_revision\": \"d4439824c55428a699aa543a2f397d06a00841b6\", \"got_dawn_revision\": \"3e17e33724b4d0b4c4fc1985340882e1f543e3a0\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"fb44fe7bc082243c858dbfa3bbe15f4db0c1649e\", \"got_revision_cp\": \"refs/heads/master@{#825249}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"4ed9d3498dcffa987acba1a8007ff8dec336f263\", \"got_v8_revision\": \"4075f123295bb39d50e472b7af1fc04d4f4fe228\", \"got_v8_revision_cp\": \"refs/heads/8.8.250@{#1}\", \"got_webrtc_revision\": \"9c99b7964fabb16a022293ffbd2804f9b46551f6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32565}\", \"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\": \"fb44fe7bc082243c858dbfa3bbe15f4db0c1649e\", \"swarm_hashes_refs/heads/master(at){#825249}_without_patch\": {\"absl_hardening_tests\": \"5c6242604e6559ea3fa4749103b22d41f09b24d5\", \"accessibility_unittests\": \"6f069456f5dd1cc53ee6fe0eb73920ff0b9af969\", \"angle_unittests\": \"29fbd0146a40c67e8dce25a78dce2a10b2c67cf1\", \"app_shell_unittests\": \"4a8a6541ac1e511e599196c2cad32089edb668a0\", \"aura_unittests\": \"d0c8184ca01a357cef9b247dfc6f3ee62442fb81\", \"base_unittests\": \"745e5bd5e236ac1f16e148a951102592e8023748\", \"base_util_unittests\": \"4ad036e66c30a29e3875903603dc334408ad4bc3\", \"blink_common_unittests\": \"450bf7b3b6d356ab66c02be519e83043b31c0ffe\", \"blink_fuzzer_unittests\": \"41914ef5b2cf8f24f63a3d78a9f98babc277d963\", \"blink_heap_unittests\": \"6eff82ca62371f0e5d249f2d55ef56096cec1420\", \"blink_platform_unittests\": \"8ed779ea8199247cbdfd0bbcf43114ffc1ce44ab\", \"blink_unittests\": \"76aa3cbcfe045edba104d022e0b8bb23b8c044c7\", \"boringssl_crypto_tests\": \"1de0bb1c60aea0bc27c2af2374a6fb48404be5fb\", \"boringssl_ssl_tests\": \"fac48afbe8cb9a81d57042b3d5acd1e78ac982d5\", \"browser_switcher_bho_unittests\": \"7b654a6ea41eceae7dc18589b206a6ab371e287f\", \"browser_tests\": \"6d1bcf311264e91624aa8e52cfdf31f2407ddc77\", \"capture_unittests\": \"1aa516b43f8d0cab483c6b08bde88deb1a2aadde\", \"cast_unittests\": \"cc64f2d86daf424bc1507218f3703eae03c11fe8\", \"cc_unittests\": \"9deae1817a00526388f97d9c03246e82bb1325b7\", \"chrome_app_unittests\": \"255e691b152bbf69bbe7bcdf68e3683e1e6ede3e\", \"chrome_cleaner_unittests\": \"9583caaa69cabe73ea764ce4efdccae965f4206b\", \"chrome_elf_unittests\": \"650daf84dc8c331eed9e124923aecbc720cc2627\", \"chromedriver_unittests\": \"1acb4b4fb4cba925bbb105dbe0185b6dcf4e8757\", \"components_browsertests\": \"fbb042cde4c15b3de84d133fb72f0518a9763bd7\", \"components_unittests\": \"3a76be16bc0ad5b05c99b4d5710c05dfdb710c98\", \"compositor_unittests\": \"b9a24e96c0a94e213ad24d9069aace2072eb02cc\", \"content_browsertests\": \"3d371ae67febc083201b29452e9b0aa5d7686d09\", \"content_unittests\": \"adf336d5884897a1817da30f259280168ff7310f\", \"courgette_unittests\": \"36e948bb2c127c434a39d7bae21733bcb0bd5da7\", \"crashpad_tests\": \"2f9cc54e27313d23e43e0fed8edfc0a76b7ee162\", \"cronet_tests\": \"b1de4c412bc135bdc52ed74b39b4e2d09936bc25\", \"cronet_unittests\": \"8ad2be71d08c7a6219ac1bca8286d395721367f6\", \"crypto_unittests\": \"b0726cd112d95272d690b202eca3553ff2d61f10\", \"delayloads_unittests\": \"5c7bdd97c01e565f2edfda65e164d42089745692\", \"device_unittests\": \"73caa07a4cf5284e0c1ec65ae306a433c1a461f0\", \"display_unittests\": \"401fd88040731a349128bdc214833fd17fcbbaa0\", \"elevation_service_unittests\": \"e2eeafe65dcd1a1bf1764fc4d70a88251a9af843\", \"events_unittests\": \"f43d77095e3662121d31ce3e2590d749b8da3d7f\", \"extensions_browsertests\": \"241ae28958a1f8df151672ee8d6b00731724eaa9\", \"extensions_unittests\": \"a40d28d1c5850e5695e67f578850a4c820afe42f\", \"filesystem_service_unittests\": \"7f49e3cc5fd8430d10755434cdf0c5f9e4978ac3\", \"gcm_unit_tests\": \"7134e12f10c0c79b277c4e6d1e37a42d1e22f5ad\", \"gcp_unittests\": \"3ff2a4ceae7750236c7eb62c25372a65735ae29b\", \"gfx_unittests\": \"32e8c24f3341d3d26f184c318c223e09c9d92ca5\", \"gin_unittests\": \"a1d6f5e5c23d9b0a34197b68aee19ac26d80fe2a\", \"google_apis_unittests\": \"1ba8ea1b33674d02a25ec7ab392b02aec60e6e95\", \"gpu_unittests\": \"fb644e236d9778cf10eefc5bc2f9ae445f6b9766\", \"gwp_asan_unittests\": \"450399906ce921da879b47db1b2f9e09936736ec\", \"headless_browsertests\": \"62013bcaf400a8acb920fa0cea15491b5870330c\", \"headless_unittests\": \"02dee1e4a47e999ecd4ed218b5556e3cefd617c1\", \"install_static_unittests\": \"f2490dcee7ab54108c3b58470fb268f86676597e\", \"installer_util_unittests\": \"ad0776533ac1d73aebe295860f106f0df84d1ca2\", \"interactive_ui_tests\": \"c792348bf7063607ae430128c637ddc2a7effadf\", \"ipc_tests\": \"18f438cf24f6307b9d02d718a4973c2aec543e8e\", \"jingle_unittests\": \"59ba644e3c1080eeeeb0e754414b84ebea1bd8e5\", \"latency_unittests\": \"c7ea2d1ce82cbb1025950912b0bc5766d41f1b86\", \"libjingle_xmpp_unittests\": \"71977fe5520c42ac7e5b8d386ddcebc06cb17876\", \"liburlpattern_unittests\": \"463d7a1a63e0d9fac6b2371a549fc10d9945fa9e\", \"media_blink_unittests\": \"d663bda42d9c64b6f6333c6456eef8b511dc9a34\", \"media_unittests\": \"090ae6a1a589ec605d3a1998c8706e3f2a19af7a\", \"message_center_unittests\": \"127a05c42c7c01781db38891060d9cab0e1fb0fa\", \"midi_unittests\": \"db90299fc42ab9f6a61271fcb8199ba519c0847b\", \"mojo_core_unittests\": \"c7a607b8515865c2361d6ebe37c69a1ccaa662d6\", \"mojo_unittests\": \"c57d17c6ce44ae7f5589b7acf1885b698294a43a\", \"nacl_loader_unittests\": \"d9c765e59df5c9ab546373874e2113079fb23336\", \"native_theme_unittests\": \"5f51251053eb21a375dac2e0f2432c49d68c1693\", \"net_unittests\": \"484ffd46db92fb32a68d4397b114f59afbe6b245\", \"notification_helper_unittests\": \"420892544606a6ace7608eb9f34b793740ed4698\", \"pdf_unittests\": \"6460606d29b5e69c18461e6e7bf2b0c6a4fd02e9\", \"perfetto_unittests\": \"b83d1cc0b371105d107873645d34bbb11a917a98\", \"ppapi_unittests\": \"b93f6618d92130571929cf0e51cc298b49a67055\", \"printing_unittests\": \"ed5981feb0b50a6126b5e50cab421d07c5fecb01\", \"remoting_unittests\": \"6017525cc13be10146d4e0e21b307b6087ba1545\", \"sbox_integration_tests\": \"ff221ec3ec3a516fe97d7143d471c78d0bec9676\", \"sbox_unittests\": \"0fed2aea246ccd96a044af483fbda94c32bd1f50\", \"sbox_validation_tests\": \"0c2c8ab28e34559ca695d67a5f23676cfa7be2b8\", \"service_manager_unittests\": \"cf830b559576aeb7cf976fc82a0c4d0d67e8fe34\", \"services_unittests\": \"22af7082827670f42f86af005e3ab420247b7d6f\", \"setup_unittests\": \"7ff777984e3a124904f4cf8536766a2fd366b6c7\", \"shell_dialogs_unittests\": \"8208331e749b7066827d4f8e6b7e40226de277bf\", \"skia_unittests\": \"f8628a8bc7336868ec4eb98610fd843128dc8d99\", \"snapshot_unittests\": \"0b1f5bf306cce3585d981f416afcb7e204d38901\", \"sql_unittests\": \"a1c9685d2cf5de0d299e01bd3f9fc800437d1c29\", \"storage_unittests\": \"04666cfc3950a75354aeaf8e99f99901d1ee8e73\", \"sync_integration_tests\": \"3640ab42f08c4f44caaf381624cca0df2e51477f\", \"traffic_annotation_auditor_unittests\": \"c99769fdd111302b7dc8742343231c0cffaa458e\", \"ui_base_unittests\": \"0f78d2cf7f2fb891720dd1e3690ca2d5929ffbff\", \"ui_touch_selection_unittests\": \"d58e951e90a15ed142750747f53c41e6d827d192\", \"unit_tests\": \"7202e29574392b33e2e45db8c7e3b92a8b732450\", \"updater_tests\": \"54c30ca6a7d7b7cb9ea446b027c59d92604d3ba9\", \"url_unittests\": \"cac099bb57b889ab143e84b215d3655d686e1ece\", \"views_unittests\": \"c318d20044033f0a6a1f24db7a22d07d7be3d32b\", \"viz_unittests\": \"40d0f3861c55cbfe90ffc92dcf71771b6589ee5c\", \"vr_common_unittests\": \"ce1f5172d10f524b65dd269cd0eea937810917d0\", \"vr_pixeltests\": \"d81b431e3262e7a8486385af6e8aa94adcd57912\", \"weblayer_browsertests\": \"96344c8c241acc004fc6fab130f95b4d13291aa6\", \"weblayer_unittests\": \"35e60a4c02cb31e68e66feec1ef18fb313a1d472\", \"wm_unittests\": \"77bd4db3df113409f6b7e9fd1504e0a18c624828\", \"wtf_unittests\": \"35da222003782918372c555974172ceb59c1fbb0\", \"zlib_unittests\": \"bc7f273c31debe9eb6780e640a2fbb01b16af2c3\", \"zucchini_unittests\": \"5de14c85af81babf49e0bce99fe4377d874c60df\"}}, \"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-b9eu\"], \"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\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604897832873762",
"status": "COMPLETED",
"status_changed_ts": "1604901148225483",
"tags": [
"build_address:luci.chromium.ci/win-asan/15545",
"builder:win-asan",
"buildset:commit/git/fb44fe7bc082243c858dbfa3bbe15f4db0c1649e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fb44fe7bc082243c858dbfa3bbe15f4db0c1649e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029566430726241680",
"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/8864136368778934592/+/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:4fc42f00e8602810",
"user_agent:luci-scheduler"
],
"updated_ts": "1604901148230245",
"url": "https://ci.chromium.org/b/8864136368778934592",
"utcnow_ts": "1604901149415991"
}
}
[05:52:29.487] Invocation finished in 55m20.546658656s with status SUCCEEDED