[14:38:59.184] New invocation is queued and will start shortly
[14:39:00.432] Starting the invocation (attempt 1)
[14:39:00.477] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:39:00.477] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:39:00.477] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9052541454166574736",
"parameters_json": "{\"builder_name\":\"ToTWinASanLibfuzzer\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b7fa39c11ec286451be4da4749f7e85ff3b57c8f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTWinASanLibfuzzer",
"scheduler_invocation_id:9052541454166574736",
"scheduler_job_id:chromium/ToTWinASanLibfuzzer",
"user_agent:luci-scheduler",
"buildset:commit/git/b7fa39c11ec286451be4da4749f7e85ff3b57c8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b7fa39c11ec286451be4da4749f7e85ff3b57c8f",
"gitiles_ref:refs/heads/master"
]
}
[14:39:01.565] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582987140659135",
"id": "8887111391954363584",
"parameters_json": "{\"builder_name\": \"ToTWinASanLibfuzzer\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b7fa39c11ec286451be4da4749f7e85ff3b57c8f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582987141389043",
"tags": [
"build_address:luci.chromium.ci/ToTWinASanLibfuzzer/5027",
"builder:ToTWinASanLibfuzzer",
"buildset:commit/git/b7fa39c11ec286451be4da4749f7e85ff3b57c8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b7fa39c11ec286451be4da4749f7e85ff3b57c8f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052541454166574736",
"scheduler_job_id:chromium/ToTWinASanLibfuzzer",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8887111391954363584/+/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": "1582987141389151",
"url": "https://ci.chromium.org/b/8887111391954363584",
"utcnow_ts": "1582987141556302"
}
}
[14:39:01.565] Task URL: https://ci.chromium.org/b/8887111391954363584
[14:39:01.565] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:2:0) after 1m0s
[14:40:01.633] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:2:0)
[14:40:01.633] Timer tick, asking Buildbucket for the build status
[14:40:01.779] Build 8887111391954363584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:40:01.779] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:3:0) after 1m0s
[14:41:01.819] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:3:0)
[14:41:01.819] Timer tick, asking Buildbucket for the build status
[14:41:01.986] Build 8887111391954363584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:41:01.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:4:0) after 1m0s
[14:42:02.004] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:4:0)
[14:42:02.004] Timer tick, asking Buildbucket for the build status
[14:42:02.119] Build 8887111391954363584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:42:02.119] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:5:0) after 1m0s
[14:43:02.152] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:5:0)
[14:43:02.152] Timer tick, asking Buildbucket for the build status
[14:43:02.285] Build 8887111391954363584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:43:02.285] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:6:0) after 1m0s
[14:43:04.015] Received PubSub notification, asking Buildbucket for the build status
[14:43:04.085] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:02.329] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:6:0)
[14:44:02.329] Timer tick, asking Buildbucket for the build status
[14:44:02.415] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:02.415] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:8:0) after 1m0s
[14:45:19.440] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:8:0)
[14:45:19.440] Timer tick, asking Buildbucket for the build status
[14:45:19.669] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:19.669] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:9:0) after 1m0s
[14:46:19.806] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:9:0)
[14:46:19.806] Timer tick, asking Buildbucket for the build status
[14:46:19.964] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:19.964] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:10:0) after 1m0s
[14:47:20.218] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:10:0)
[14:47:20.218] Timer tick, asking Buildbucket for the build status
[14:47:20.457] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:20.457] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:11:0) after 1m0s
[14:48:24.082] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:11:0)
[14:48:24.082] Timer tick, asking Buildbucket for the build status
[14:48:24.372] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:24.372] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:12:0) after 1m0s
[14:49:24.589] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:12:0)
[14:49:24.589] Timer tick, asking Buildbucket for the build status
[14:49:24.752] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:24.752] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:13:0) after 1m0s
[14:50:24.923] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:13:0)
[14:50:24.923] Timer tick, asking Buildbucket for the build status
[14:50:25.035] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:25.035] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:14:0) after 1m0s
[14:51:25.172] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:14:0)
[14:51:25.172] Timer tick, asking Buildbucket for the build status
[14:51:25.314] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:25.314] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:15:0) after 1m0s
[14:52:25.371] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:15:0)
[14:52:25.371] Timer tick, asking Buildbucket for the build status
[14:52:25.464] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:25.464] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:16:0) after 1m0s
[14:53:25.655] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:16:0)
[14:53:25.655] Timer tick, asking Buildbucket for the build status
[14:53:25.874] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:25.874] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:17:0) after 1m0s
[14:54:25.988] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:17:0)
[14:54:25.988] Timer tick, asking Buildbucket for the build status
[14:54:26.098] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:26.098] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:18:0) after 1m0s
[14:55:26.229] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:18:0)
[14:55:26.229] Timer tick, asking Buildbucket for the build status
[14:55:26.339] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:26.340] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:19:0) after 1m0s
[14:56:26.381] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:19:0)
[14:56:26.381] Timer tick, asking Buildbucket for the build status
[14:56:26.487] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:26.487] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:20:0) after 1m0s
[14:57:26.488] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:20:0)
[14:57:26.488] Timer tick, asking Buildbucket for the build status
[14:57:26.578] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:26.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:21:0) after 1m0s
[14:58:26.605] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:21:0)
[14:58:26.605] Timer tick, asking Buildbucket for the build status
[14:58:26.728] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:26.728] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:22:0) after 1m0s
[14:59:26.858] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:22:0)
[14:59:26.858] Timer tick, asking Buildbucket for the build status
[14:59:26.950] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:26.950] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:23:0) after 1m0s
[15:00:26.980] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:23:0)
[15:00:26.980] Timer tick, asking Buildbucket for the build status
[15:00:27.099] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:27.099] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:24:0) after 1m0s
[15:01:27.167] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:24:0)
[15:01:27.167] Timer tick, asking Buildbucket for the build status
[15:01:27.441] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:27.441] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:25:0) after 1m0s
[15:02:27.552] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:25:0)
[15:02:27.552] Timer tick, asking Buildbucket for the build status
[15:02:27.632] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:27.632] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:26:0) after 1m0s
[15:03:27.713] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:26:0)
[15:03:27.713] Timer tick, asking Buildbucket for the build status
[15:03:28.943] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:28.943] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:27:0) after 1m0s
[15:04:29.058] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:27:0)
[15:04:29.058] Timer tick, asking Buildbucket for the build status
[15:04:29.357] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:29.358] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:28:0) after 1m0s
[15:05:29.404] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:28:0)
[15:05:29.404] Timer tick, asking Buildbucket for the build status
[15:05:29.548] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:29.548] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:29:0) after 1m0s
[15:06:29.594] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:29:0)
[15:06:29.594] Timer tick, asking Buildbucket for the build status
[15:06:29.718] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:29.718] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:30:0) after 1m0s
[15:07:29.841] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:30:0)
[15:07:29.841] Timer tick, asking Buildbucket for the build status
[15:07:29.956] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:29.956] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:31:0) after 1m0s
[15:08:30.145] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:31:0)
[15:08:30.145] Timer tick, asking Buildbucket for the build status
[15:08:31.751] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:31.751] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:32:0) after 1m0s
[15:09:31.770] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:32:0)
[15:09:31.770] Timer tick, asking Buildbucket for the build status
[15:09:32.408] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:32.408] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:33:0) after 1m0s
[15:10:32.526] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:33:0)
[15:10:32.526] Timer tick, asking Buildbucket for the build status
[15:10:32.699] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:32.699] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:34:0) after 1m0s
[15:11:32.755] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:34:0)
[15:11:32.755] Timer tick, asking Buildbucket for the build status
[15:11:33.248] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:33.248] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:35:0) after 1m0s
[15:12:33.317] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:35:0)
[15:12:33.317] Timer tick, asking Buildbucket for the build status
[15:12:33.602] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:33.602] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:36:0) after 1m0s
[15:13:33.625] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:36:0)
[15:13:33.625] Timer tick, asking Buildbucket for the build status
[15:13:34.116] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:34.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:37:0) after 1m0s
[15:14:34.191] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:37:0)
[15:14:34.191] Timer tick, asking Buildbucket for the build status
[15:14:34.296] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:34.296] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:38:0) after 1m0s
[15:15:34.339] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:38:0)
[15:15:34.339] Timer tick, asking Buildbucket for the build status
[15:15:34.449] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:34.449] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:39:0) after 1m0s
[15:16:34.547] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:39:0)
[15:16:34.547] Timer tick, asking Buildbucket for the build status
[15:16:34.667] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:34.667] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:40:0) after 1m0s
[15:17:34.686] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:40:0)
[15:17:34.686] Timer tick, asking Buildbucket for the build status
[15:17:34.774] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:34.774] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:41:0) after 1m0s
[15:18:34.841] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:41:0)
[15:18:34.841] Timer tick, asking Buildbucket for the build status
[15:18:34.945] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:34.945] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:42:0) after 1m0s
[15:19:35.016] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:42:0)
[15:19:35.016] Timer tick, asking Buildbucket for the build status
[15:19:35.242] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:35.242] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:43:0) after 1m0s
[15:20:35.323] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:43:0)
[15:20:35.323] Timer tick, asking Buildbucket for the build status
[15:20:35.501] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:35.501] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:44:0) after 1m0s
[15:21:35.568] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:44:0)
[15:21:35.568] Timer tick, asking Buildbucket for the build status
[15:21:35.947] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:35.947] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:45:0) after 1m0s
[15:22:36.073] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:45:0)
[15:22:36.073] Timer tick, asking Buildbucket for the build status
[15:22:36.246] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:36.247] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:46:0) after 1m0s
[15:23:36.288] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:46:0)
[15:23:36.288] Timer tick, asking Buildbucket for the build status
[15:23:36.851] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:36.851] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:47:0) after 1m0s
[15:24:36.852] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:47:0)
[15:24:36.852] Timer tick, asking Buildbucket for the build status
[15:24:36.974] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:36.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:48:0) after 1m0s
[15:25:37.024] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:48:0)
[15:25:37.024] Timer tick, asking Buildbucket for the build status
[15:25:37.372] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:37.372] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:49:0) after 1m0s
[15:26:37.430] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:49:0)
[15:26:37.430] Timer tick, asking Buildbucket for the build status
[15:26:37.664] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:37.664] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:50:0) after 1m0s
[15:27:37.965] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:50:0)
[15:27:37.965] Timer tick, asking Buildbucket for the build status
[15:27:38.288] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:38.288] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:51:0) after 1m0s
[15:28:38.444] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:51:0)
[15:28:38.444] Timer tick, asking Buildbucket for the build status
[15:28:38.544] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:38.544] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:52:0) after 1m0s
[15:29:38.712] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:52:0)
[15:29:38.712] Timer tick, asking Buildbucket for the build status
[15:29:38.882] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:38.882] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:53:0) after 1m0s
[15:30:38.952] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:53:0)
[15:30:38.953] Timer tick, asking Buildbucket for the build status
[15:30:39.068] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:39.068] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:54:0) after 1m0s
[15:31:39.081] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:54:0)
[15:31:39.081] Timer tick, asking Buildbucket for the build status
[15:31:39.214] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:39.214] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:55:0) after 1m0s
[15:32:39.274] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:55:0)
[15:32:39.274] Timer tick, asking Buildbucket for the build status
[15:32:39.369] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:39.369] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:56:0) after 1m0s
[15:33:39.490] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:56:0)
[15:33:39.490] Timer tick, asking Buildbucket for the build status
[15:33:39.615] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:39.615] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:57:0) after 1m0s
[15:34:39.661] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:57:0)
[15:34:39.661] Timer tick, asking Buildbucket for the build status
[15:34:39.745] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:39.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:58:0) after 1m0s
[15:35:39.830] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:58:0)
[15:35:39.830] Timer tick, asking Buildbucket for the build status
[15:35:39.953] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:39.953] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:59:0) after 1m0s
[15:36:40.030] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:59:0)
[15:36:40.030] Timer tick, asking Buildbucket for the build status
[15:36:40.282] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:40.282] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:60:0) after 1m0s
[15:37:40.897] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:60:0)
[15:37:40.897] Timer tick, asking Buildbucket for the build status
[15:37:41.024] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:41.024] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:61:0) after 1m0s
[15:38:41.356] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:61:0)
[15:38:41.357] Timer tick, asking Buildbucket for the build status
[15:38:41.476] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:41.476] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:62:0) after 1m0s
[15:39:41.625] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:62:0)
[15:39:41.625] Timer tick, asking Buildbucket for the build status
[15:39:41.851] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:41.851] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:63:0) after 1m0s
[15:40:41.874] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:63:0)
[15:40:41.874] Timer tick, asking Buildbucket for the build status
[15:40:41.974] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:41.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:64:0) after 1m0s
[15:41:41.997] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:64:0)
[15:41:41.997] Timer tick, asking Buildbucket for the build status
[15:41:42.164] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:42.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:65:0) after 1m0s
[15:42:42.204] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:65:0)
[15:42:42.204] Timer tick, asking Buildbucket for the build status
[15:42:42.568] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:42.568] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:66:0) after 1m0s
[15:43:42.616] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:66:0)
[15:43:42.616] Timer tick, asking Buildbucket for the build status
[15:43:42.754] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:42.754] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:67:0) after 1m0s
[15:44:42.789] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:67:0)
[15:44:42.789] Timer tick, asking Buildbucket for the build status
[15:44:42.888] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:42.888] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:68:0) after 1m0s
[15:45:42.955] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:68:0)
[15:45:42.955] Timer tick, asking Buildbucket for the build status
[15:45:43.047] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:43.047] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:69:0) after 1m0s
[15:46:43.107] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:69:0)
[15:46:43.107] Timer tick, asking Buildbucket for the build status
[15:46:43.233] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:43.233] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:70:0) after 1m0s
[15:47:43.305] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:70:0)
[15:47:43.305] Timer tick, asking Buildbucket for the build status
[15:47:44.278] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:44.278] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:71:0) after 1m0s
[15:48:44.268] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:71:0)
[15:48:44.268] Timer tick, asking Buildbucket for the build status
[15:48:44.592] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:44.592] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:72:0) after 1m0s
[15:49:44.619] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:72:0)
[15:49:44.619] Timer tick, asking Buildbucket for the build status
[15:49:44.740] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:44.740] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:73:0) after 1m0s
[15:50:44.779] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:73:0)
[15:50:44.779] Timer tick, asking Buildbucket for the build status
[15:50:44.947] Build 8887111391954363584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:44.947] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9052541454166574736:74:0) after 1m0s
[15:51:01.063] Received PubSub notification, asking Buildbucket for the build status
[15:51:01.870] Build 8887111391954363584: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:51:01.870] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582991460594059",
"created_by": "project:chromium",
"created_ts": "1582987140659135",
"failure_reason": "BUILD_FAILURE",
"id": "8887111391954363584",
"parameters_json": "{\"builder_name\": \"ToTWinASanLibfuzzer\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b7fa39c11ec286451be4da4749f7e85ff3b57c8f\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[4235/60008] CXX win_clang_x86/obj/buildtools/third_party/libc++/libc++/string.obj\\nFAILED: win_clang_x86/obj/buildtools/third_party/libc++/libc++/string.obj\\n..\\\\..\\\\third_party\\\\llvm-build\\\\Release+Asserts\\\\bin\\\\clang-cl.exe /nologo /showIncludes -imsvc..\\\\..\\\\thir...(too long)\\nfatal error: error in backend: invalid symbol redefinition\\nStack dump:\\n0.\\tProgram arguments: ..\\\\..\\\\third_party\\\\llvm-build\\\\Release+Asserts\\\\bin\\\\clang-cl.exe /nologo /showInc...(too long)\\n1.\\t\u003ceof\u003e parser at end of file\\n2.\\tCode generation\\n3.\\tRunning pass 'Function Pass Manager' on module '../../buildtools/third_party/libc++/trunk/src/string.cpp'.\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}, \"properties\": {\"$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-0-d438\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTWinASanLibfuzzer\", \"buildnumber\": 5027, \"got_angle_revision\": \"daf6e47111c3fa7223d512de695874c8f1c6a77e\", \"got_clang_revision\": \"n344041-93184a8e-0\", \"got_dawn_revision\": \"3ded65e8072e0cdccd0d0edebccffde37d4428b0\", \"got_nacl_revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"got_revision\": \"b7fa39c11ec286451be4da4749f7e85ff3b57c8f\", \"got_revision_cp\": \"refs/heads/master@{#745767}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"416d374522757fc042282b3ae61c53f5510421fc\", \"got_v8_revision\": \"2ee2cb7970b9573d042574ad48ff40b187d41eba\", \"got_v8_revision_cp\": \"refs/heads/8.2.221@{#1}\", \"got_webrtc_revision\": \"a08b2384a3245082cd40e8682676252ebebb1fe8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30652}\", \"mastername\": \"chromium.clang\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b7fa39c11ec286451be4da4749f7e85ff3b57c8f\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91c730c3e6ead475130e2ae78bc3f03e09cb9dcb91c3469d4450b29020110401_v2\", \"git\", \"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-0-d438\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4840-d3c86f9\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[4235/60008] CXX win_clang_x86/obj/buildtools/third_party/libc++/libc++/string.obj\\nFAILED: win_clang_x86/obj/buildtools/third_party/libc++/libc++/string.obj\\n..\\\\..\\\\third_party\\\\llvm-build\\\\Release+Asserts\\\\bin\\\\clang-cl.exe /nologo /showIncludes -imsvc..\\\\..\\\\thir...(too long)\\nfatal error: error in backend: invalid symbol redefinition\\nStack dump:\\n0.\\tProgram arguments: ..\\\\..\\\\third_party\\\\llvm-build\\\\Release+Asserts\\\\bin\\\\clang-cl.exe /nologo /showInc...(too long)\\n1.\\t\u003ceof\u003e parser at end of file\\n2.\\tCode generation\\n3.\\tRunning pass 'Function Pass Manager' on module '../../buildtools/third_party/libc++/trunk/src/string.cpp'.\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582987383405792",
"status": "COMPLETED",
"status_changed_ts": "1582987383674348",
"tags": [
"build_address:luci.chromium.ci/ToTWinASanLibfuzzer/5027",
"builder:ToTWinASanLibfuzzer",
"buildset:commit/git/b7fa39c11ec286451be4da4749f7e85ff3b57c8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b7fa39c11ec286451be4da4749f7e85ff3b57c8f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052541454166574736",
"scheduler_job_id:chromium/ToTWinASanLibfuzzer",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8887111391954363584/+/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:4aaa346a160db610",
"user_agent:luci-scheduler"
],
"updated_ts": "1582991460651779",
"url": "https://ci.chromium.org/b/8887111391954363584",
"utcnow_ts": "1582991461858617"
}
}
[15:51:01.870] Invocation finished in 1h12m2.704326269s with status FAILED