[05:14:42.849] New invocation is queued and will start shortly
[05:14:43.885] Starting the invocation (attempt 1)
[05:14:43.917] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:14:43.917] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:14:43.917] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026666222713104352",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0e23d34487731c373484565081c5e514e1893e77\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026666222713104352",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/0e23d34487731c373484565081c5e514e1893e77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0e23d34487731c373484565081c5e514e1893e77",
"gitiles_ref:refs/heads/master"
]
}
[05:14:44.737] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607663684066594",
"id": "8861236160774728432",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0e23d34487731c373484565081c5e514e1893e77\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607663684402068",
"tags": [
"build_address:luci.chromium.ci/win-asan/16153",
"builder:win-asan",
"buildset:commit/git/0e23d34487731c373484565081c5e514e1893e77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0e23d34487731c373484565081c5e514e1893e77",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026666222713104352",
"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/8861236160774728432/+/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": "1607663684402227",
"url": "https://ci.chromium.org/b/8861236160774728432",
"utcnow_ts": "1607663684727605"
}
}
[05:14:44.737] Task URL: https://ci.chromium.org/b/8861236160774728432
[05:14:44.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:2:0) after 1m0s
[05:14:47.226] Received PubSub notification, asking Buildbucket for the build status
[05:14:47.311] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:44.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:2:0)
[05:15:44.712] Timer tick, asking Buildbucket for the build status
[05:15:45.134] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:45.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:4:0) after 1m0s
[05:16:45.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:4:0)
[05:16:45.298] Timer tick, asking Buildbucket for the build status
[05:16:45.542] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:45.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:5:0) after 1m0s
[05:17:45.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:5:0)
[05:17:45.570] Timer tick, asking Buildbucket for the build status
[05:17:46.129] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:46.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:6:0) after 1m0s
[05:18:46.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:6:0)
[05:18:46.147] Timer tick, asking Buildbucket for the build status
[05:18:46.276] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:46.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:7:0) after 1m0s
[05:19:46.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:7:0)
[05:19:46.296] Timer tick, asking Buildbucket for the build status
[05:19:46.423] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:46.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:8:0) after 1m0s
[05:20:46.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:8:0)
[05:20:46.587] Timer tick, asking Buildbucket for the build status
[05:20:46.726] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:46.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:9:0) after 1m0s
[05:21:47.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:9:0)
[05:21:47.113] Timer tick, asking Buildbucket for the build status
[05:21:47.178] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:47.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:10:0) after 1m0s
[05:22:47.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:10:0)
[05:22:47.381] Timer tick, asking Buildbucket for the build status
[05:22:47.455] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:47.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:11:0) after 1m0s
[05:23:47.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:11:0)
[05:23:47.836] Timer tick, asking Buildbucket for the build status
[05:23:47.917] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:47.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:12:0) after 1m0s
[05:24:47.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:12:0)
[05:24:47.980] Timer tick, asking Buildbucket for the build status
[05:24:48.102] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:48.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:13:0) after 1m0s
[05:25:48.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:13:0)
[05:25:48.156] Timer tick, asking Buildbucket for the build status
[05:25:48.251] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:48.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:14:0) after 1m0s
[05:26:48.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:14:0)
[05:26:48.599] Timer tick, asking Buildbucket for the build status
[05:26:48.674] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:48.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:15:0) after 1m0s
[05:27:48.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:15:0)
[05:27:48.835] Timer tick, asking Buildbucket for the build status
[05:27:48.898] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:48.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:16:0) after 1m0s
[05:28:49.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:16:0)
[05:28:49.250] Timer tick, asking Buildbucket for the build status
[05:28:49.689] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:49.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:17:0) after 1m0s
[05:29:49.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:17:0)
[05:29:49.772] Timer tick, asking Buildbucket for the build status
[05:29:49.882] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:49.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:18:0) after 1m0s
[05:30:49.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:18:0)
[05:30:49.983] Timer tick, asking Buildbucket for the build status
[05:30:50.061] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:50.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:19:0) after 1m0s
[05:31:50.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:19:0)
[05:31:50.210] Timer tick, asking Buildbucket for the build status
[05:31:50.333] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:50.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:20:0) after 1m0s
[05:32:50.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:20:0)
[05:32:50.414] Timer tick, asking Buildbucket for the build status
[05:32:50.597] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:50.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:21:0) after 1m0s
[05:33:50.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:21:0)
[05:33:50.696] Timer tick, asking Buildbucket for the build status
[05:33:51.249] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:51.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:22:0) after 1m0s
[05:34:51.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:22:0)
[05:34:51.315] Timer tick, asking Buildbucket for the build status
[05:34:51.712] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:51.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:23:0) after 1m0s
[05:35:51.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:23:0)
[05:35:51.832] Timer tick, asking Buildbucket for the build status
[05:35:51.959] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:51.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:24:0) after 1m0s
[05:36:52.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:24:0)
[05:36:52.005] Timer tick, asking Buildbucket for the build status
[05:36:52.717] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:52.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:25:0) after 1m0s
[05:37:52.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:25:0)
[05:37:52.715] Timer tick, asking Buildbucket for the build status
[05:37:52.878] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:52.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:26:0) after 1m0s
[05:38:52.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:26:0)
[05:38:52.904] Timer tick, asking Buildbucket for the build status
[05:38:53.517] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:53.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:27:0) after 1m0s
[05:39:53.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:27:0)
[05:39:53.544] Timer tick, asking Buildbucket for the build status
[05:39:53.666] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:53.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:28:0) after 1m0s
[05:40:53.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:28:0)
[05:40:53.686] Timer tick, asking Buildbucket for the build status
[05:40:53.869] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:53.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:29:0) after 1m0s
[05:41:53.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:29:0)
[05:41:53.887] Timer tick, asking Buildbucket for the build status
[05:41:54.510] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:54.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:30:0) after 1m0s
[05:42:54.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:30:0)
[05:42:54.522] Timer tick, asking Buildbucket for the build status
[05:42:54.650] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:54.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:31:0) after 1m0s
[05:43:54.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:31:0)
[05:43:54.684] Timer tick, asking Buildbucket for the build status
[05:43:54.770] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:54.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:32:0) after 1m0s
[05:44:54.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:32:0)
[05:44:54.868] Timer tick, asking Buildbucket for the build status
[05:44:55.022] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:55.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:33:0) after 1m0s
[05:45:55.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:33:0)
[05:45:55.086] Timer tick, asking Buildbucket for the build status
[05:45:55.193] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:55.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:34:0) after 1m0s
[05:46:55.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:34:0)
[05:46:55.337] Timer tick, asking Buildbucket for the build status
[05:46:55.420] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:55.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:35:0) after 1m0s
[05:47:55.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:35:0)
[05:47:55.522] Timer tick, asking Buildbucket for the build status
[05:47:55.619] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:55.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:36:0) after 1m0s
[05:48:55.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:36:0)
[05:48:55.665] Timer tick, asking Buildbucket for the build status
[05:48:55.831] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:55.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:37:0) after 1m0s
[05:49:56.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:37:0)
[05:49:56.076] Timer tick, asking Buildbucket for the build status
[05:49:56.205] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:56.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:38:0) after 1m0s
[05:50:56.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:38:0)
[05:50:56.248] Timer tick, asking Buildbucket for the build status
[05:50:56.432] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:56.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:39:0) after 1m0s
[05:51:56.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:39:0)
[05:51:56.489] Timer tick, asking Buildbucket for the build status
[05:51:56.582] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:56.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:40:0) after 1m0s
[05:52:56.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:40:0)
[05:52:56.713] Timer tick, asking Buildbucket for the build status
[05:52:57.085] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:57.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:41:0) after 1m0s
[05:53:57.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:41:0)
[05:53:57.139] Timer tick, asking Buildbucket for the build status
[05:53:57.325] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:57.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:42:0) after 1m0s
[05:54:57.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:42:0)
[05:54:57.415] Timer tick, asking Buildbucket for the build status
[05:54:57.564] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:57.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:43:0) after 1m0s
[05:55:57.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:43:0)
[05:55:57.712] Timer tick, asking Buildbucket for the build status
[05:55:57.893] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:57.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:44:0) after 1m0s
[05:56:57.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:44:0)
[05:56:57.982] Timer tick, asking Buildbucket for the build status
[05:56:58.116] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:58.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:45:0) after 1m0s
[05:57:58.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:45:0)
[05:57:58.118] Timer tick, asking Buildbucket for the build status
[05:57:58.193] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:58.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:46:0) after 1m0s
[05:58:58.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:46:0)
[05:58:58.218] Timer tick, asking Buildbucket for the build status
[05:58:58.430] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:58.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:47:0) after 1m0s
[05:59:58.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:47:0)
[05:59:58.446] Timer tick, asking Buildbucket for the build status
[05:59:58.656] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:58.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:48:0) after 1m0s
[06:00:58.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:48:0)
[06:00:58.646] Timer tick, asking Buildbucket for the build status
[06:00:58.791] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:58.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:49:0) after 1m0s
[06:01:58.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:49:0)
[06:01:58.928] Timer tick, asking Buildbucket for the build status
[06:01:59.071] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:59.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:50:0) after 1m0s
[06:02:59.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:50:0)
[06:02:59.228] Timer tick, asking Buildbucket for the build status
[06:02:59.301] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:59.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:51:0) after 1m0s
[06:03:59.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:51:0)
[06:03:59.406] Timer tick, asking Buildbucket for the build status
[06:03:59.744] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:59.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:52:0) after 1m0s
[06:04:59.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:52:0)
[06:04:59.814] Timer tick, asking Buildbucket for the build status
[06:04:59.968] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:59.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:53:0) after 1m0s
[06:05:59.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:53:0)
[06:05:59.992] Timer tick, asking Buildbucket for the build status
[06:06:00.188] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:00.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:54:0) after 1m0s
[06:07:00.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:54:0)
[06:07:00.225] Timer tick, asking Buildbucket for the build status
[06:07:00.309] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:00.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:55:0) after 1m0s
[06:08:00.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:55:0)
[06:08:00.446] Timer tick, asking Buildbucket for the build status
[06:08:00.520] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:00.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:56:0) after 1m0s
[06:09:01.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:56:0)
[06:09:01.523] Timer tick, asking Buildbucket for the build status
[06:09:01.626] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:01.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:57:0) after 1m0s
[06:10:01.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:57:0)
[06:10:01.699] Timer tick, asking Buildbucket for the build status
[06:10:01.838] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:01.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:58:0) after 1m0s
[06:11:01.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:58:0)
[06:11:01.871] Timer tick, asking Buildbucket for the build status
[06:11:01.949] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:01.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:59:0) after 1m0s
[06:12:02.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:59:0)
[06:12:02.404] Timer tick, asking Buildbucket for the build status
[06:12:02.499] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:02.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:60:0) after 1m0s
[06:13:02.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:60:0)
[06:13:02.557] Timer tick, asking Buildbucket for the build status
[06:13:02.636] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:02.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:61:0) after 1m0s
[06:14:02.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:61:0)
[06:14:02.797] Timer tick, asking Buildbucket for the build status
[06:14:02.929] Build 8861236160774728432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:02.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026666222713104352:62:0) after 1m0s
[06:14:20.760] Received PubSub notification, asking Buildbucket for the build status
[06:14:21.192] Build 8861236160774728432: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:14:21.192] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607667259774604",
"created_by": "project:chromium",
"created_ts": "1607663684066594",
"id": "8861236160774728432",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0e23d34487731c373484565081c5e514e1893e77\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"0e23d34487731c373484565081c5e514e1893e77\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-6-3vmi\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16153, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"9912971cf9b3dc5f9890a2a2435031988624fdc7\", \"got_dawn_revision\": \"6bcd4aceef100810284d61bd9dad0e9bd05e4c92\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"0e23d34487731c373484565081c5e514e1893e77\", \"got_revision_cp\": \"refs/heads/master@{#836000}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"0a64a974755521dbb2b16332002a927fb830a0d4\", \"got_v8_revision\": \"4d774788aff02fc9d9912a098630585025942f16\", \"got_v8_revision_cp\": \"refs/heads/8.9.134@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"0e23d34487731c373484565081c5e514e1893e77\", \"swarm_hashes_refs/heads/master(at){#836000}_without_patch\": {\"absl_hardening_tests\": \"ac0da8a1a0b5fd48fbc853141db8799cb180aeb1\", \"accessibility_unittests\": \"15afad7f982d857b56b90f0178950430c96a4b0a\", \"angle_unittests\": \"f967068ae1b477ec6ef9ea429a138ec3e23ba6e5\", \"app_shell_unittests\": \"dc6dad5a92d48f50d1b38f654a0da03d5de223f1\", \"aura_unittests\": \"15e51bfa5ba213ec6edab52315212a13d86ee233\", \"base_unittests\": \"f336fa415a49875343d487010ca3a1998be20e2f\", \"base_util_unittests\": \"40780d13c25f9dc4f376f3686f694e2668ada610\", \"blink_common_unittests\": \"306a527e12d9e2fcb9dae02fb2422f1e3540b0aa\", \"blink_fuzzer_unittests\": \"68cb22bcda8b6c69a40aef6c31de8718d4883bbc\", \"blink_heap_unittests\": \"5b938caee98dd7c8ffea4c35ef7deeac6353e686\", \"blink_platform_unittests\": \"8d9f0ad92fb86e771822c37f1c2b9a0dd4f761e2\", \"blink_unittests\": \"9c7c460774357f854fa7a6a7b460f6aaf3629229\", \"boringssl_crypto_tests\": \"e91aa0e8104f139ca721d108b6572a9467e5ee2e\", \"boringssl_ssl_tests\": \"4a4fe7fa6de006abf125e557d8e83f3b067620cc\", \"browser_switcher_bho_unittests\": \"865128d89b9b2aa19ec3cb81b020ce3769afe32e\", \"browser_tests\": \"59751c651486eed527d47ee6a19065e9e2b0893a\", \"capture_unittests\": \"0568b2d3d6eef649b5962b19a70ec18ed94a9175\", \"cast_unittests\": \"69f3fe32d6dbe1fa1c0c1f606e1a41168ae54928\", \"cc_unittests\": \"daad99a24b8044409c7feaa70979b5d7db9820cd\", \"chrome_app_unittests\": \"dc1d3bbc78f0407efec1485e46441a635b05e114\", \"chrome_cleaner_unittests\": \"2f0065b10e5f682cba5f597cbda484117e43f091\", \"chrome_elf_unittests\": \"ecc32c455b9f649145e6a5b49dc3f62455cc0d28\", \"chromedriver_unittests\": \"afd354eb559de7cca5534b7f5a4ab8899d119efe\", \"components_browsertests\": \"8be1841ff4b7a3d8451cd47634a7c8bf87af8346\", \"components_unittests\": \"993f8b244d96757a93dee0e5cc6e0b32b5443ffd\", \"compositor_unittests\": \"187a14695895e2047752fc83597cb277b4d4e26c\", \"content_browsertests\": \"117aaba73da2f9fc5cb87356a5b9c7d7e0a5e332\", \"content_unittests\": \"55cbff4538766371e72805fe24c864ebca9f0ed6\", \"courgette_unittests\": \"a99a22510b0d2c8813ccb8724106726c524c7ce1\", \"crashpad_tests\": \"20dd155395ac4ec0457c27ec9b8cab2e3cf0a769\", \"cronet_tests\": \"10e70ebc79d0401393b9c73b407980cf8451a474\", \"cronet_unittests\": \"6d0567401e9ab41c703602e11faf0af90a377e53\", \"crypto_unittests\": \"3a685b19338cd44cbcbbfb6bfcdc636e421959da\", \"delayloads_unittests\": \"1e7e422553d1bddc2bb9c404f2f2c8d4a12679e9\", \"device_unittests\": \"8027dd6b12857535a2e95842188118d7bf3c98f0\", \"display_unittests\": \"612a512ad9f615a44c3ebf2f2ee1fdb4518caafe\", \"elevation_service_unittests\": \"182447b7f1a8a010e63d1eeccbf047e0aa34fbb9\", \"events_unittests\": \"7ebcb9015ed3c1b8b171cfe84a20f852cf8c0a46\", \"extensions_browsertests\": \"fcf0f3397988061f8bfa5c1317cdec9d04951d33\", \"extensions_unittests\": \"ce7444dd954c7877f3ebd6991c7102b2772f62ce\", \"filesystem_service_unittests\": \"e04e373ce212930b69ca3c0c7ca289f4a4f30749\", \"gcm_unit_tests\": \"78d15edeeaaaceac55eb09edc62c41058d156338\", \"gcp_unittests\": \"cec63c0accb8ea3a99148512849a35d99991add2\", \"gfx_unittests\": \"d9a13a33bf023a2101c63ba44ba5756e94f8f14e\", \"gin_unittests\": \"db1a4cf02e8e669482a3c782025a23d522df4082\", \"google_apis_unittests\": \"6c61b66a9cbdf6a36311b5cc0bda8d74710d58da\", \"gpu_unittests\": \"3bdd84cbce076e2372696af507fb0167730f920c\", \"gwp_asan_unittests\": \"877d3ff822500e1f95fa69536143704a00785fb6\", \"headless_browsertests\": \"fd12c11ae14e51214f6113cbb34c127b05df7ea7\", \"headless_unittests\": \"61fd00b466a544e12c2335c24fc1ca0ecaeca1fd\", \"install_static_unittests\": \"1dd6f9372826151d2e6a8b013d9aadc334c04fb7\", \"installer_util_unittests\": \"14faa86242dfac43b2bddd552f915c3808531998\", \"interactive_ui_tests\": \"52c96e73dcaa713430e8cd0e136fdc9af9a3e259\", \"ipc_tests\": \"00809539d84a940a0a3b5a2343c03545e858080b\", \"jingle_unittests\": \"9aa60b06d23dafff2cc77e8a908043a3cbc1fa4f\", \"latency_unittests\": \"d4e5f8ceb53ec1780ec7188b4759603d6f4f769f\", \"libjingle_xmpp_unittests\": \"ddfde2dde79f5c961d569d251558635dd7ee90af\", \"liburlpattern_unittests\": \"904cbcb5be393ba7afc22811df8867801f9bab6e\", \"media_blink_unittests\": \"9911c7f1ec668a265eab50df3ac590fbf50bce69\", \"media_unittests\": \"8c672ba6e0a72cee7050b74239237c1155397413\", \"message_center_unittests\": \"4f79da2fa2d97b62861a771d134943fda78ae3d8\", \"midi_unittests\": \"3e77f5fc767274057c103c807724a556c2fb3725\", \"mojo_core_unittests\": \"649752ac9975e06f0c6bcc40decd82a5cfa46107\", \"mojo_unittests\": \"1b30827292b3670a3b11a308801b5ec4bc03ee62\", \"nacl_loader_unittests\": \"7fca39ecfb26d1ba8470c72771a16e633302878a\", \"native_theme_unittests\": \"580621ea5b0dad805930179ac7b72e83a4312fe9\", \"net_unittests\": \"a61142db8271938186a1019fa28b96f8656fedf1\", \"notification_helper_unittests\": \"70a42b6e870999bc44c77dcf44ee6059dece1c5d\", \"pdf_unittests\": \"b9dd8cb97c5af42694cc2d8330b25b8219d1ac96\", \"perfetto_unittests\": \"8cdc83753cb1103c48691c84aa56a164e96536cf\", \"ppapi_unittests\": \"2615172449522f379a87f331a06c20323f48c834\", \"printing_unittests\": \"74d407475c66f4043e2964f9c97f900638579ee6\", \"remoting_unittests\": \"94df9c8f27ebc6045fdded015d81c392d9f30093\", \"sbox_integration_tests\": \"8440238dd47a87748aa36c6027618aac2c220303\", \"sbox_unittests\": \"d36cf91e472e62cb02c0817a921b0296dd12d8f9\", \"sbox_validation_tests\": \"9e68722927f68734dc6f276673e7ab925730b108\", \"service_manager_unittests\": \"2b9d76b44df31cc9f3aa5c809086b806fe4aabc7\", \"services_unittests\": \"eb21d9c6b7b7d92c0ddaf710587addbca249e96a\", \"setup_unittests\": \"80cc0fdeceb945f2a4091211a5a0d9ce403db6f5\", \"shell_dialogs_unittests\": \"68108019426df952f818190a0d5cf3f348b36730\", \"skia_unittests\": \"fc247b18d408c181f593547307205229ab2981f4\", \"snapshot_unittests\": \"960be718da875980bfe664e8e385035fb65668ac\", \"sql_unittests\": \"f81ea13312dcc99be9059cb182538082730feb2f\", \"storage_unittests\": \"438aee17120bcfeec8b429348c406f7f9cbfe5ea\", \"sync_integration_tests\": \"698d93cf4b245053bad58c13f3d1205669fa0e5c\", \"traffic_annotation_auditor_unittests\": \"ed3c46fadd69c04345a6efd1d2e9392ced7009a7\", \"ui_base_unittests\": \"5ea710dc060fa4b5084e173a56189ab847900a4a\", \"ui_touch_selection_unittests\": \"13215f577e5e6d7613ce9cb273dbdc975d11eab8\", \"unit_tests\": \"da5a7c13518221790ebc35554af70ca580fc91d4\", \"updater_tests\": \"acb6d8f7a51208b7e28cf3ae598ff878d1dc15e4\", \"url_unittests\": \"21d0b52a7eb1996d7ab59a274b7a71002bfcfde5\", \"views_unittests\": \"501a91a9837cfc3ee2037a26842b56c5708cfacb\", \"viz_unittests\": \"fc6e2e9fd9b5f2815746b09ae8f3de187819ba34\", \"vr_common_unittests\": \"2eb4f712f5674eb0939f26f1a1d6ef123541a916\", \"vr_pixeltests\": \"86a78ba6689e7e9b858a287bbbc380f7650e234d\", \"weblayer_browsertests\": \"2b2c016fc8abd08935a9dd1e3a80c5e2440d990f\", \"weblayer_unittests\": \"f754614b4e1d2dfbed7d50b1609084d44741a02b\", \"wm_unittests\": \"bbf457b22f21e90b3e5d5f14a8bda8a14ccd2ee7\", \"wtf_unittests\": \"6389865c936b7abdc4a2097b722bfbb9b8ff2bae\", \"zlib_unittests\": \"b8a58854e775488c2279e403f3c396cbdbcd7321\", \"zucchini_unittests\": \"6727065c145283f45941c92dbc1d1eb2cd2166be\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-3vmi\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607663686740222",
"status": "COMPLETED",
"status_changed_ts": "1607667260255585",
"tags": [
"build_address:luci.chromium.ci/win-asan/16153",
"builder:win-asan",
"buildset:commit/git/0e23d34487731c373484565081c5e514e1893e77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0e23d34487731c373484565081c5e514e1893e77",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026666222713104352",
"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/8861236160774728432/+/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:50690a94e45a6e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607667260269936",
"url": "https://ci.chromium.org/b/8861236160774728432",
"utcnow_ts": "1607667261168884"
}
}
[06:14:21.192] Invocation finished in 59m38.358381298s with status SUCCEEDED