[19:21:10.295] New invocation is queued and will start shortly
[19:21:11.456] Starting the invocation (attempt 1)
[19:21:11.524] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:21:11.524] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:21:11.524] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9062851754778914992",
"parameters_json": "{\"builder_name\":\"ToTAndroidOfficial\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c98caa3824a9c75a878fb0fbcdd40871d0ab654e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTAndroidOfficial",
"scheduler_invocation_id:9062851754778914992",
"scheduler_job_id:chromium/ToTAndroidOfficial",
"user_agent:luci-scheduler",
"buildset:commit/git/c98caa3824a9c75a878fb0fbcdd40871d0ab654e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c98caa3824a9c75a878fb0fbcdd40871d0ab654e",
"gitiles_ref:refs/heads/master"
]
}
[19:21:13.564] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1573154471811686",
"id": "8897421692524692400",
"parameters_json": "{\"builder_name\": \"ToTAndroidOfficial\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c98caa3824a9c75a878fb0fbcdd40871d0ab654e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1573154472430786",
"tags": [
"build_address:luci.chromium.ci/ToTAndroidOfficial/5327",
"builder:ToTAndroidOfficial",
"buildset:commit/git/c98caa3824a9c75a878fb0fbcdd40871d0ab654e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c98caa3824a9c75a878fb0fbcdd40871d0ab654e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9062851754778914992",
"scheduler_job_id:chromium/ToTAndroidOfficial",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8897421692524692400/+/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": "1573154472430925",
"url": "https://ci.chromium.org/b/8897421692524692400",
"utcnow_ts": "1573154473538622"
}
}
[19:21:13.564] Task URL: https://ci.chromium.org/b/8897421692524692400
[19:21:13.565] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:2:0) after 1m0s
[19:22:13.782] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:2:0)
[19:22:13.782] Timer tick, asking Buildbucket for the build status
[19:22:15.414] Build 8897421692524692400: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:22:15.414] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:3:0) after 1m0s
[19:22:20.601] Received PubSub notification, asking Buildbucket for the build status
[19:22:20.925] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:15.604] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:3:0)
[19:23:15.604] Timer tick, asking Buildbucket for the build status
[19:23:15.848] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:15.848] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:5:0) after 1m0s
[19:24:15.979] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:5:0)
[19:24:15.979] Timer tick, asking Buildbucket for the build status
[19:24:18.149] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:18.149] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:6:0) after 1m0s
[19:25:18.316] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:6:0)
[19:25:18.316] Timer tick, asking Buildbucket for the build status
[19:25:19.189] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:19.189] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:7:0) after 1m0s
[19:26:19.261] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:7:0)
[19:26:19.261] Timer tick, asking Buildbucket for the build status
[19:26:19.706] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:19.706] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:8:0) after 1m0s
[19:27:19.989] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:8:0)
[19:27:19.989] Timer tick, asking Buildbucket for the build status
[19:27:22.317] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:22.317] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:9:0) after 1m0s
[19:28:22.732] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:9:0)
[19:28:22.732] Timer tick, asking Buildbucket for the build status
[19:28:24.000] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:24.000] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:10:0) after 1m0s
[19:29:24.165] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:10:0)
[19:29:24.165] Timer tick, asking Buildbucket for the build status
[19:29:26.901] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:26.901] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:11:0) after 1m0s
[19:30:26.929] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:11:0)
[19:30:26.929] Timer tick, asking Buildbucket for the build status
[19:30:28.789] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:28.789] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:12:0) after 1m0s
[19:31:28.895] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:12:0)
[19:31:28.895] Timer tick, asking Buildbucket for the build status
[19:31:29.323] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:29.323] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:13:0) after 1m0s
[19:32:29.333] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:13:0)
[19:32:29.333] Timer tick, asking Buildbucket for the build status
[19:32:29.883] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:29.883] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:14:0) after 1m0s
[19:33:29.940] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:14:0)
[19:33:29.940] Timer tick, asking Buildbucket for the build status
[19:33:31.131] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:31.131] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:15:0) after 1m0s
[19:34:31.156] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:15:0)
[19:34:31.156] Timer tick, asking Buildbucket for the build status
[19:34:31.508] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:31.508] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:16:0) after 1m0s
[19:35:31.649] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:16:0)
[19:35:31.649] Timer tick, asking Buildbucket for the build status
[19:35:32.399] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:32.399] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:17:0) after 1m0s
[19:36:32.498] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:17:0)
[19:36:32.498] Timer tick, asking Buildbucket for the build status
[19:36:35.934] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:35.934] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:18:0) after 1m0s
[19:37:36.141] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:18:0)
[19:37:36.141] Timer tick, asking Buildbucket for the build status
[19:37:36.322] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:36.322] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:19:0) after 1m0s
[19:38:36.589] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:19:0)
[19:38:36.589] Timer tick, asking Buildbucket for the build status
[19:38:36.787] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:36.787] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:20:0) after 1m0s
[19:39:37.164] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:20:0)
[19:39:37.164] Timer tick, asking Buildbucket for the build status
[19:39:37.616] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:37.616] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:21:0) after 1m0s
[19:40:38.405] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:21:0)
[19:40:38.405] Timer tick, asking Buildbucket for the build status
[19:40:38.762] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:38.762] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:22:0) after 1m0s
[19:41:39.058] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:22:0)
[19:41:39.058] Timer tick, asking Buildbucket for the build status
[19:41:39.297] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:39.297] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:23:0) after 1m0s
[19:42:39.535] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:23:0)
[19:42:39.535] Timer tick, asking Buildbucket for the build status
[19:42:40.639] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:40.639] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:24:0) after 1m0s
[19:43:40.929] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:24:0)
[19:43:40.929] Timer tick, asking Buildbucket for the build status
[19:43:41.186] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:41.186] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:25:0) after 1m0s
[19:44:41.321] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:25:0)
[19:44:41.321] Timer tick, asking Buildbucket for the build status
[19:44:42.260] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:42.260] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:26:0) after 1m0s
[19:45:42.363] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:26:0)
[19:45:42.363] Timer tick, asking Buildbucket for the build status
[19:45:43.654] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:43.654] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:27:0) after 1m0s
[19:46:43.749] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:27:0)
[19:46:43.750] Timer tick, asking Buildbucket for the build status
[19:46:43.971] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:43.971] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:28:0) after 1m0s
[19:47:44.197] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:28:0)
[19:47:44.197] Timer tick, asking Buildbucket for the build status
[19:47:44.729] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:44.729] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:29:0) after 1m0s
[19:48:44.756] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:29:0)
[19:48:44.756] Timer tick, asking Buildbucket for the build status
[19:48:45.198] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:45.198] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:30:0) after 1m0s
[19:49:45.325] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:30:0)
[19:49:45.325] Timer tick, asking Buildbucket for the build status
[19:49:46.063] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:46.063] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:31:0) after 1m0s
[19:50:46.057] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:31:0)
[19:50:46.057] Timer tick, asking Buildbucket for the build status
[19:50:46.286] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:46.286] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:32:0) after 1m0s
[19:51:46.268] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:32:0)
[19:51:46.268] Timer tick, asking Buildbucket for the build status
[19:51:46.481] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:46.481] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:33:0) after 1m0s
[19:52:46.557] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:33:0)
[19:52:46.557] Timer tick, asking Buildbucket for the build status
[19:52:46.777] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:46.777] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:34:0) after 1m0s
[19:53:46.895] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:34:0)
[19:53:46.895] Timer tick, asking Buildbucket for the build status
[19:53:47.055] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:47.055] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:35:0) after 1m0s
[19:54:47.183] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:35:0)
[19:54:47.183] Timer tick, asking Buildbucket for the build status
[19:54:49.119] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:49.119] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:36:0) after 1m0s
[19:55:49.140] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:36:0)
[19:55:49.140] Timer tick, asking Buildbucket for the build status
[19:55:52.315] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:52.315] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:37:0) after 1m0s
[19:56:52.417] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:37:0)
[19:56:52.417] Timer tick, asking Buildbucket for the build status
[19:56:52.590] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:52.590] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:38:0) after 1m0s
[19:57:52.736] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:38:0)
[19:57:52.736] Timer tick, asking Buildbucket for the build status
[19:57:53.036] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:53.036] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:39:0) after 1m0s
[19:58:53.163] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:39:0)
[19:58:53.163] Timer tick, asking Buildbucket for the build status
[19:58:56.328] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:56.328] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:40:0) after 1m0s
[19:59:56.409] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:40:0)
[19:59:56.409] Timer tick, asking Buildbucket for the build status
[19:59:56.636] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:56.636] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:41:0) after 1m0s
[20:00:56.653] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:41:0)
[20:00:56.653] Timer tick, asking Buildbucket for the build status
[20:00:56.888] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:56.888] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:42:0) after 1m0s
[20:01:56.907] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:42:0)
[20:01:56.907] Timer tick, asking Buildbucket for the build status
[20:01:57.140] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:57.140] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:43:0) after 1m0s
[20:02:57.291] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:43:0)
[20:02:57.291] Timer tick, asking Buildbucket for the build status
[20:02:57.764] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:57.764] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:44:0) after 1m0s
[20:03:57.906] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:44:0)
[20:03:57.906] Timer tick, asking Buildbucket for the build status
[20:03:58.122] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:58.122] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:45:0) after 1m0s
[20:04:58.254] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:45:0)
[20:04:58.254] Timer tick, asking Buildbucket for the build status
[20:04:58.433] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:58.433] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:46:0) after 1m0s
[20:05:58.474] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:46:0)
[20:05:58.474] Timer tick, asking Buildbucket for the build status
[20:06:04.061] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:04.062] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:47:0) after 1m0s
[20:07:04.112] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:47:0)
[20:07:04.112] Timer tick, asking Buildbucket for the build status
[20:07:04.393] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:04.393] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:48:0) after 1m0s
[20:08:04.474] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:48:0)
[20:08:04.474] Timer tick, asking Buildbucket for the build status
[20:08:04.603] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:04.603] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:49:0) after 1m0s
[20:09:05.051] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:49:0)
[20:09:05.051] Timer tick, asking Buildbucket for the build status
[20:09:06.256] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:06.256] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:50:0) after 1m0s
[20:10:06.510] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:50:0)
[20:10:06.510] Timer tick, asking Buildbucket for the build status
[20:10:09.242] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:09.242] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:51:0) after 1m0s
[20:11:09.338] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:51:0)
[20:11:09.338] Timer tick, asking Buildbucket for the build status
[20:11:10.002] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:10.002] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:52:0) after 1m0s
[20:12:10.055] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:52:0)
[20:12:10.055] Timer tick, asking Buildbucket for the build status
[20:12:11.108] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:11.108] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:53:0) after 1m0s
[20:13:11.257] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:53:0)
[20:13:11.257] Timer tick, asking Buildbucket for the build status
[20:13:11.600] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:11.600] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:54:0) after 1m0s
[20:14:11.822] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:54:0)
[20:14:11.822] Timer tick, asking Buildbucket for the build status
[20:14:13.439] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:13.439] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:55:0) after 1m0s
[20:15:13.604] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:55:0)
[20:15:13.604] Timer tick, asking Buildbucket for the build status
[20:15:13.950] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:13.950] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:56:0) after 1m0s
[20:16:14.041] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:56:0)
[20:16:14.041] Timer tick, asking Buildbucket for the build status
[20:16:15.668] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:15.668] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:57:0) after 1m0s
[20:17:15.743] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:57:0)
[20:17:15.743] Timer tick, asking Buildbucket for the build status
[20:17:16.470] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:16.470] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:58:0) after 1m0s
[20:18:16.578] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:58:0)
[20:18:16.578] Timer tick, asking Buildbucket for the build status
[20:18:19.206] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:19.206] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:59:0) after 1m0s
[20:19:19.316] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:59:0)
[20:19:19.316] Timer tick, asking Buildbucket for the build status
[20:19:19.846] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:19.847] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:60:0) after 1m0s
[20:20:20.084] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:60:0)
[20:20:20.084] Timer tick, asking Buildbucket for the build status
[20:20:20.432] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:20.432] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:61:0) after 1m0s
[20:21:20.533] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:61:0)
[20:21:20.533] Timer tick, asking Buildbucket for the build status
[20:21:20.945] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:20.945] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:62:0) after 1m0s
[20:22:21.091] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:62:0)
[20:22:21.091] Timer tick, asking Buildbucket for the build status
[20:22:21.422] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:21.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:63:0) after 1m0s
[20:23:21.660] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:63:0)
[20:23:21.660] Timer tick, asking Buildbucket for the build status
[20:23:21.990] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:21.990] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:64:0) after 1m0s
[20:24:21.991] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:64:0)
[20:24:21.991] Timer tick, asking Buildbucket for the build status
[20:24:22.422] Build 8897421692524692400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:22.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9062851754778914992:65:0) after 1m0s
[20:25:11.806] Received PubSub notification, asking Buildbucket for the build status
[20:25:12.031] Build 8897421692524692400: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:25:12.031] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1573158311047979",
"created_by": "project:chromium",
"created_ts": "1573154471811686",
"failure_reason": "BUILD_FAILURE",
"id": "8897421692524692400",
"parameters_json": "{\"builder_name\": \"ToTAndroidOfficial\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c98caa3824a9c75a878fb0fbcdd40871d0ab654e\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[18743/58420] SOLINK ./libbase_util_unittests__library.so\\nFAILED: libbase_util_unittests__library.so libbase_util_unittests__library.so.TOC lib.unstripped/libbase_util_unittests__library.so lib.unstripped/libbase_util_unittests__library.so.map.gz\\npython \\\"../../build/toolchain/gcc_solink_wrapper.py\\\" --readelf=\\\"../../third_party/android_ndk/toolch...(too long)\\n'+soft-float-abi' is not a recognized feature for this target (ignoring feature)\\n'+soft-float-abi' is not a recognized feature for this target (ignoring feature)\\n'+soft-float-abi' is not a recognized feature for this target (ignoring feature)\\n'+soft-float-abi' is not a recognized feature for this target (ignoring feature)\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-32-4-fl5a\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTAndroidOfficial\", \"buildnumber\": 5327, \"got_angle_revision\": \"652dbfc63e70f7af1b4d3d9beff5ae69b1699c14\", \"got_clang_revision\": \"n331340-a087b78b-0\", \"got_dawn_revision\": \"c3284fa40ec6b12731ed66c2f2a9256ae3fa692e\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"c98caa3824a9c75a878fb0fbcdd40871d0ab654e\", \"got_revision_cp\": \"refs/heads/master@{#713527}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"27a3d31d7a9d65bb49b83da404cc4830eb3ce06e\", \"got_v8_revision\": \"500af51db17fa412e5913c8f81c0d729ad5668e4\", \"got_v8_revision_cp\": \"refs/heads/8.0.195@{#1}\", \"got_webrtc_revision\": \"e9f663c8cb8c08501472e2fef8c84dfa98f52b16\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29726}\", \"mastername\": \"chromium.clang\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c98caa3824a9c75a878fb0fbcdd40871d0ab654e\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_32f037bbb88023afe0dab3b292a7a92da0ac70796876f4ab4f7ae1596c401abf_v2\", \"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-32-4-fl5a\"], \"image\": [\"chrome-xenial-19102400-d35fedf5a0d\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4621-f8a5b1c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}, \"ui\": {\"info\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[18743/58420] SOLINK ./libbase_util_unittests__library.so\\nFAILED: libbase_util_unittests__library.so libbase_util_unittests__library.so.TOC lib.unstripped/libbase_util_unittests__library.so lib.unstripped/libbase_util_unittests__library.so.map.gz\\npython \\\"../../build/toolchain/gcc_solink_wrapper.py\\\" --readelf=\\\"../../third_party/android_ndk/toolch...(too long)\\n'+soft-float-abi' is not a recognized feature for this target (ignoring feature)\\n'+soft-float-abi' is not a recognized feature for this target (ignoring feature)\\n'+soft-float-abi' is not a recognized feature for this target (ignoring feature)\\n'+soft-float-abi' is not a recognized feature for this target (ignoring feature)\\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": "1573154536546762",
"status": "COMPLETED",
"status_changed_ts": "1573154539710541",
"tags": [
"build_address:luci.chromium.ci/ToTAndroidOfficial/5327",
"builder:ToTAndroidOfficial",
"buildset:commit/git/c98caa3824a9c75a878fb0fbcdd40871d0ab654e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c98caa3824a9c75a878fb0fbcdd40871d0ab654e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9062851754778914992",
"scheduler_job_id:chromium/ToTAndroidOfficial",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8897421692524692400/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4860222cd7f70210",
"user_agent:luci-scheduler"
],
"updated_ts": "1573158311116110",
"url": "https://ci.chromium.org/b/8897421692524692400",
"utcnow_ts": "1573158312005773"
}
}
[20:25:12.031] Invocation finished in 1h4m1.753330143s with status FAILED