[GH-ISSUE #658] 多次重启 frpc 后出现 start error: custom listener for [cc.1] is repeated #514

Closed
opened 2026-05-05 12:20:08 -06:00 by gitea-mirror · 8 comments
Owner

Originally created by @Hirozy on GitHub (Mar 8, 2018).
Original GitHub issue: https://github.com/fatedier/frp/issues/658

Issue is only used for submiting bug report and documents typo. If there are same issues or answers can be found in documents, we will close it directly.
(为了节约时间,提高处理问题的效率,不按照格式填写的 issue 将会直接关闭。)

Use the commands below to provide key information from your environment:
You do NOT have to include this information if this is a FEATURE REQUEST

What version of frp are you using (./frpc -v or ./frps -v)?
0.16.0

What operating system and processor architecture are you using (go env)?
Linux and macOS

Configures you used:

Server

[common]
bind_addr = [::]
bind_port = $

vhost_http_port = $
vhost_https_port = $


dashboard_port = $

dashboard_user = $
dashboard_pwd = $

log_file = /tmp/frps/frps.log

log_level = debug

log_max_days = 15

privilege_token = $

...

Client on Linux

log_level = debug
...

[Lol_SSH]
type = stcp
local_ip = 192.168.1.1
local_port = 22
use_compression = false
use_encryption = true
sk = 

[LEDE_Http_Proxy]
type = stcp
local_ip = 192.168.1.1
local_port = 22
use_compression = false
use_encryption = true
sk = 

[Titan1_SSH]
type = stcp
local_ip = 192.168.1.1
local_port = 22
use_compression = false
use_encryption = true
sk = 
...

Client on macOS

log_level = debug
...
[Lol_SSH_visitor]
role = visitor
type = stcp
local_ip = 127.0.0.1
...

[LEDE_Http_Proxy__visitor]
role = visitor
type = stcp
local_ip = 127.0.0.1
...

[Titan1_SSH_visitor]
role = visitor
type = stcp
local_ip = 127.0.0.1
...

Steps to reproduce the issue:
1.
2.
3.

Describe the results you received:
Error on Server

2018/03/08 20:41:12 [I] [service.go:262] client login info: ip [*] version [0.16.0] hostname [] os [linux] arch [amd64]
2018/03/08 20:41:12 [W] [control.go:321] [aaaaaaa] new proxy [cc.Lol_SSH] error: custom listener for [cc.Lol_SSH] is repeated
2018/03/08 20:41:12 [W] [control.go:321] [aaaaaaa] new proxy [cc.LEDE_Http_Proxy] error: custom listener for [cc.LEDE_Http_Proxy] is repeated
2018/03/08 20:41:12 [D] [control.go:130] [aaaaaaa] new work connection registered
2018/03/08 20:41:12 [I] [proxy.go:379] [aaaaaaa] [cc.Titan1_SSH] stcp proxy custom listen success
2018/03/08 20:41:12 [I] [control.go:324] [aaaaaaa] new proxy [cc.Titan1_SSH] success
2018/03/08 20:41:12 [D] [control.go:130] [aaaaaaa] new work connection registered
2018/03/08 20:41:12 [D] [control.go:130] [aaaaaaa] new work connection registered
2018/03/08 20:41:12 [D] [control.go:130] [aaaaaaa] new work connection registered
2018/03/08 20:41:12 [D] [control.go:130] [aaaaaaa] new work connection registered
2018/03/08 20:41:12 [I] [control.go:330] [aaaaaaa] close proxy [cc.Lol_SSH] success
2018/03/08 20:41:12 [I] [control.go:330] [aaaaaaa] close proxy [cc.LEDE_Http_Proxy] success
2018/03/08 20:41:42 [D] [control.go:333] [aaaaaaa] receive heartbeat

Error on Client on Linux

2018/03/08 20:41:13 [I] [proxy_manager.go:298] proxy removed: []
2018/03/08 20:41:13 [I] [proxy_manager.go:308] proxy added: [cc.Lol_SSH cc.LEDE_Http_Proxy cc.Titan1_SSH]
2018/03/08 20:41:13 [I] [proxy_manager.go:331] visitor removed: []
2018/03/08 20:41:13 [I] [proxy_manager.go:340] visitor added: []
2018/03/08 20:41:13 [I] [control.go:240] [aaaaaaaa] login to server success, get run id [aaaaaaaa], server udp port [3892]
2018/03/08 20:41:13 [I] [service.go:49] admin server listen on 127.0.0.1:7400
2018/03/08 20:41:13 [W] [control.go:163] [aaaaaaaa] [cc.Lol_SSH] start error: custom listener for [cc.Lol_SSH] is repeated
2018/03/08 20:41:13 [W] [control.go:163] [aaaaaaaa] [cc.LEDE_Http_Proxy] start error: custom listener for [cc.LEDE_Http_Proxy] is repeated
2018/03/08 20:41:13 [I] [control.go:165] [aaaaaaaa] [cc.Titan1_SSH] start proxy success
2018/03/08 20:41:43 [D] [control.go:332] [aaaaaaaa] send heartbeat to server
2018/03/08 20:41:43 [D] [control.go:353] [aaaaaaaa] receive heartbeat from server

Describe the results you expected:

Additional information you deem important (e.g. issue happens only occasionally):
简单测试了一下 0.15.1 ,没有上述问题

Can you point out what caused this issue (optional)

Originally created by @Hirozy on GitHub (Mar 8, 2018). Original GitHub issue: https://github.com/fatedier/frp/issues/658 Issue is only used for submiting bug report and documents typo. If there are same issues or answers can be found in documents, we will close it directly. (为了节约时间,提高处理问题的效率,不按照格式填写的 issue 将会直接关闭。) Use the commands below to provide key information from your environment: You do NOT have to include this information if this is a FEATURE REQUEST **What version of frp are you using (./frpc -v or ./frps -v)?** 0.16.0 **What operating system and processor architecture are you using (`go env`)?** Linux and macOS **Configures you used:** Server ``` [common] bind_addr = [::] bind_port = $ vhost_http_port = $ vhost_https_port = $ dashboard_port = $ dashboard_user = $ dashboard_pwd = $ log_file = /tmp/frps/frps.log log_level = debug log_max_days = 15 privilege_token = $ ... ``` Client on Linux ``` log_level = debug ... [Lol_SSH] type = stcp local_ip = 192.168.1.1 local_port = 22 use_compression = false use_encryption = true sk = [LEDE_Http_Proxy] type = stcp local_ip = 192.168.1.1 local_port = 22 use_compression = false use_encryption = true sk = [Titan1_SSH] type = stcp local_ip = 192.168.1.1 local_port = 22 use_compression = false use_encryption = true sk = ... ``` Client on macOS ``` log_level = debug ... [Lol_SSH_visitor] role = visitor type = stcp local_ip = 127.0.0.1 ... [LEDE_Http_Proxy__visitor] role = visitor type = stcp local_ip = 127.0.0.1 ... [Titan1_SSH_visitor] role = visitor type = stcp local_ip = 127.0.0.1 ... ``` **Steps to reproduce the issue:** 1. 2. 3. **Describe the results you received:** Error on Server ``` 2018/03/08 20:41:12 [I] [service.go:262] client login info: ip [*] version [0.16.0] hostname [] os [linux] arch [amd64] 2018/03/08 20:41:12 [W] [control.go:321] [aaaaaaa] new proxy [cc.Lol_SSH] error: custom listener for [cc.Lol_SSH] is repeated 2018/03/08 20:41:12 [W] [control.go:321] [aaaaaaa] new proxy [cc.LEDE_Http_Proxy] error: custom listener for [cc.LEDE_Http_Proxy] is repeated 2018/03/08 20:41:12 [D] [control.go:130] [aaaaaaa] new work connection registered 2018/03/08 20:41:12 [I] [proxy.go:379] [aaaaaaa] [cc.Titan1_SSH] stcp proxy custom listen success 2018/03/08 20:41:12 [I] [control.go:324] [aaaaaaa] new proxy [cc.Titan1_SSH] success 2018/03/08 20:41:12 [D] [control.go:130] [aaaaaaa] new work connection registered 2018/03/08 20:41:12 [D] [control.go:130] [aaaaaaa] new work connection registered 2018/03/08 20:41:12 [D] [control.go:130] [aaaaaaa] new work connection registered 2018/03/08 20:41:12 [D] [control.go:130] [aaaaaaa] new work connection registered 2018/03/08 20:41:12 [I] [control.go:330] [aaaaaaa] close proxy [cc.Lol_SSH] success 2018/03/08 20:41:12 [I] [control.go:330] [aaaaaaa] close proxy [cc.LEDE_Http_Proxy] success 2018/03/08 20:41:42 [D] [control.go:333] [aaaaaaa] receive heartbeat ``` Error on Client on Linux ``` 2018/03/08 20:41:13 [I] [proxy_manager.go:298] proxy removed: [] 2018/03/08 20:41:13 [I] [proxy_manager.go:308] proxy added: [cc.Lol_SSH cc.LEDE_Http_Proxy cc.Titan1_SSH] 2018/03/08 20:41:13 [I] [proxy_manager.go:331] visitor removed: [] 2018/03/08 20:41:13 [I] [proxy_manager.go:340] visitor added: [] 2018/03/08 20:41:13 [I] [control.go:240] [aaaaaaaa] login to server success, get run id [aaaaaaaa], server udp port [3892] 2018/03/08 20:41:13 [I] [service.go:49] admin server listen on 127.0.0.1:7400 2018/03/08 20:41:13 [W] [control.go:163] [aaaaaaaa] [cc.Lol_SSH] start error: custom listener for [cc.Lol_SSH] is repeated 2018/03/08 20:41:13 [W] [control.go:163] [aaaaaaaa] [cc.LEDE_Http_Proxy] start error: custom listener for [cc.LEDE_Http_Proxy] is repeated 2018/03/08 20:41:13 [I] [control.go:165] [aaaaaaaa] [cc.Titan1_SSH] start proxy success 2018/03/08 20:41:43 [D] [control.go:332] [aaaaaaaa] send heartbeat to server 2018/03/08 20:41:43 [D] [control.go:353] [aaaaaaaa] receive heartbeat from server ``` **Describe the results you expected:** **Additional information you deem important (e.g. issue happens only occasionally):** 简单测试了一下 0.15.1 ,没有上述问题 **Can you point out what caused this issue (optional)**
gitea-mirror 2026-05-05 12:20:08 -06:00
Author
Owner

@fatedier commented on GitHub (Mar 14, 2018):

本地没有复现。
服务端日志里停止服务的顺序应该是:

read error: broken pipe
control writer is closing
[Lol_SSH] proxy closing
client exit success
[Lol_SSH] listener is closed

之后重新启动服务的日志顺序:

client login info: xxxxx
[Lol_SSH] stcp proxy custom listen success
new proxy [Lol_SSH] success

可以抓一下这部分完整的日志,主要是服务端程序,从停止到重启的这部分,检查一下和我列的顺序有没有什么不同,然后贴出来。理论上这个 proxy 会被 close,之后就可以被重新注册了。

<!-- gh-comment-id:373058588 --> @fatedier commented on GitHub (Mar 14, 2018): 本地没有复现。 服务端日志里停止服务的顺序应该是: ``` read error: broken pipe control writer is closing [Lol_SSH] proxy closing client exit success [Lol_SSH] listener is closed ``` 之后重新启动服务的日志顺序: ``` client login info: xxxxx [Lol_SSH] stcp proxy custom listen success new proxy [Lol_SSH] success ``` 可以抓一下这部分完整的日志,主要是服务端程序,从停止到重启的这部分,检查一下和我列的顺序有没有什么不同,然后贴出来。理论上这个 proxy 会被 close,之后就可以被重新注册了。
Author
Owner

@Hirozy commented on GitHub (Mar 19, 2018):

感谢回答,不好意思,本地同样没能再次复现,推测当时运行的环境有关。下面是当时的完整日志,再次表示感谢!

2018/03/08 20:40:27 [D] [control.go:333] [facc31e74000562d] receive heartbeat
2018/03/08 20:40:33 [D] [control.go:333] [0b751c5f86d2c8be] receive heartbeat
2018/03/08 20:40:57 [D] [control.go:333] [facc31e74000562d] receive heartbeat
2018/03/08 20:41:03 [W] [control.go:321] [0b751c5f86d2c8be] new proxy [cc.Remote_Desk_Lab] error: custom listener for [cc.Remote_Desk_Lab] is repeated
2018/03/08 20:41:03 [W] [control.go:321] [0b751c5f86d2c8be] new proxy [cc.Lab_SSH] error: custom listener for [cc.Lab_SSH] is repeated
2018/03/08 20:41:03 [W] [control.go:321] [0b751c5f86d2c8be] new proxy [cc.LEDE_Lab_SSH] error: custom listener for [cc.LEDE_Lab_SSH] is repeated
2018/03/08 20:41:03 [W] [control.go:321] [0b751c5f86d2c8be] new proxy [cc.LEDE_Http_Proxy] error: custom listener for [cc.LEDE_Http_Proxy] is repeated
2018/03/08 20:41:03 [D] [control.go:333] [0b751c5f86d2c8be] receive heartbeat
2018/03/08 20:41:03 [I] [control.go:330] [0b751c5f86d2c8be] close proxy [cc.Remote_Desk_Lab] success
2018/03/08 20:41:03 [I] [control.go:330] [0b751c5f86d2c8be] close proxy [cc.Lab_SSH] success
2018/03/08 20:41:03 [I] [control.go:330] [0b751c5f86d2c8be] close proxy [cc.LEDE_Lab_SSH] success
2018/03/08 20:41:03 [I] [control.go:330] [0b751c5f86d2c8be] close proxy [cc.LEDE_Http_Proxy] success
2018/03/08 20:41:10 [W] [control.go:241] [0b751c5f86d2c8be] read error: broken pipe
2018/03/08 20:41:10 [I] [control.go:213] [0b751c5f86d2c8be] control writer is closing
2018/03/08 20:41:10 [I] [proxy.go:71] [0b751c5f86d2c8be] [cc.Titan1_SSH] proxy closing
2018/03/08 20:41:10 [I] [proxy.go:71] [0b751c5f86d2c8be] [cc.Titan2_SSH] proxy closing
2018/03/08 20:41:10 [I] [control.go:282] [0b751c5f86d2c8be] client exit success
2018/03/08 20:41:10 [I] [proxy.go:117] [0b751c5f86d2c8be] [cc.Titan2_SSH] listener is closed
2018/03/08 20:41:10 [W] [service.go:247] Accept new mux stream error: broken pipe
2018/03/08 20:41:10 [I] [proxy.go:117] [0b751c5f86d2c8be] [cc.Titan1_SSH] listener is closed
2018/03/08 20:41:12 [I] [service.go:262] client login info: ip [*] version [0.16.0] hostname [] os [linux] arch [amd64]
2018/03/08 20:41:12 [W] [control.go:321] [f324c48b77f65e26] new proxy [cc.Lab_SSH] error: custom listener for [cc.Lab_SSH] is repeated
2018/03/08 20:41:12 [W] [control.go:321] [f324c48b77f65e26] new proxy [cc.LEDE_Lab_SSH] error: custom listener for [cc.LEDE_Lab_SSH] is repeated
2018/03/08 20:41:12 [W] [control.go:321] [f324c48b77f65e26] new proxy [cc.LEDE_Http_Proxy] error: custom listener for [cc.LEDE_Http_Proxy] is repeated
2018/03/08 20:41:12 [D] [control.go:130] [f324c48b77f65e26] new work connection registered
2018/03/08 20:41:12 [I] [proxy.go:379] [f324c48b77f65e26] [cc.Titan1_SSH] stcp proxy custom listen success
2018/03/08 20:41:12 [I] [control.go:324] [f324c48b77f65e26] new proxy [cc.Titan1_SSH] success
2018/03/08 20:41:12 [I] [proxy.go:379] [f324c48b77f65e26] [cc.Titan2_SSH] stcp proxy custom listen success
2018/03/08 20:41:12 [I] [control.go:324] [f324c48b77f65e26] new proxy [cc.Titan2_SSH] success
2018/03/08 20:41:12 [W] [control.go:321] [f324c48b77f65e26] new proxy [cc.Remote_Desk_Lab] error: custom listener for [cc.Remote_Desk_Lab] is repeated
2018/03/08 20:41:12 [D] [control.go:130] [f324c48b77f65e26] new work connection registered
2018/03/08 20:41:12 [D] [control.go:130] [f324c48b77f65e26] new work connection registered
2018/03/08 20:41:12 [D] [control.go:130] [f324c48b77f65e26] new work connection registered
2018/03/08 20:41:12 [D] [control.go:130] [f324c48b77f65e26] new work connection registered
2018/03/08 20:41:12 [I] [control.go:330] [f324c48b77f65e26] close proxy [cc.Lab_SSH] success
2018/03/08 20:41:12 [I] [control.go:330] [f324c48b77f65e26] close proxy [cc.LEDE_Lab_SSH] success
2018/03/08 20:41:12 [I] [control.go:330] [f324c48b77f65e26] close proxy [cc.LEDE_Http_Proxy] success
2018/03/08 20:41:12 [I] [control.go:330] [f324c48b77f65e26] close proxy [cc.Remote_Desk_Lab] success

下面是从服务端开启时的日志

2018/03/08 19:29:47 [I] [service.go:96] frps tcp listen on [::]:xxx
2018/03/08 19:29:47 [I] [service.go:125] http service listen on [::]:xxxx
2018/03/08 19:29:47 [I] [service.go:141] https service listen on [::]:xxx
2018/03/08 19:29:47 [I] [service.go:154] nat hole udp service listen on [::]:xxxx
2018/03/08 19:29:47 [I] [service.go:164] Dashboard listen on [::]:xxxx
2018/03/08 19:29:47 [I] [main.go:112] Start frps success
2018/03/08 19:29:47 [I] [main.go:114] PrivilegeMode is enabled, you should pay more attention to security issues
2018/03/08 19:29:48 [I] [service.go:262] client login info: ip [**] version [0.16.0] hostname [] os [linux] arch [amd64]
2018/03/08 19:29:48 [I] [proxy.go:379] [facc31e74000562d] [cc.LEDE_Lab_SSH] stcp proxy custom listen success
2018/03/08 19:29:48 [I] [control.go:324] [facc31e74000562d] new proxy [cc.LEDE_Lab_SSH] success
2018/03/08 19:29:48 [I] [proxy.go:379] [facc31e74000562d] [cc.LEDE_Http_Proxy] stcp proxy custom listen success
2018/03/08 19:29:48 [I] [control.go:324] [facc31e74000562d] new proxy [cc.LEDE_Http_Proxy] success
2018/03/08 19:29:48 [I] [proxy.go:379] [facc31e74000562d] [cc.Titan_SSH] stcp proxy custom listen success
2018/03/08 19:29:48 [I] [control.go:324] [facc31e74000562d] new proxy [cc.Titan_SSH] success
2018/03/08 19:29:48 [I] [proxy.go:379] [facc31e74000562d] [cc.Remote_Desk_Lab] stcp proxy custom listen success
2018/03/08 19:29:48 [I] [control.go:324] [facc31e74000562d] new proxy [cc.Remote_Desk_Lab] success
2018/03/08 19:29:48 [I] [proxy.go:379] [facc31e74000562d] [cc.Lab_SSH] stcp proxy custom listen success
2018/03/08 19:29:48 [I] [control.go:324] [facc31e74000562d] new proxy [cc.Lab_SSH] success
2018/03/08 19:32:52 [I] [service.go:262] client login info: ip [**] version [0.16.0] hostname [] os [linux] arch [amd64]
2018/03/08 19:32:52 [I] [proxy.go:379] [a8dd87b2860d1a9e] [cc.Titan2_SSH] stcp proxy custom listen success
2018/03/08 19:32:52 [I] [control.go:324] [a8dd87b2860d1a9e] new proxy [cc.Titan2_SSH] success
2018/03/08 19:32:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.Remote_Desk_Lab] error: custom listener for [cc.Remote_Desk_Lab] is repeated
2018/03/08 19:32:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.Lab_SSH] error: custom listener for [cc.Lab_SSH] is repeated
2018/03/08 19:32:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.LEDE_Lab_SSH] error: custom listener for [cc.LEDE_Lab_SSH] is repeated
2018/03/08 19:32:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.LEDE_Http_Proxy] error: custom listener for [cc.LEDE_Http_Proxy] is repeated
2018/03/08 19:32:52 [I] [proxy.go:379] [a8dd87b2860d1a9e] [cc.Titan1_SSH] stcp proxy custom listen success
2018/03/08 19:32:52 [I] [control.go:324] [a8dd87b2860d1a9e] new proxy [cc.Titan1_SSH] success
2018/03/08 19:32:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.Remote_Desk_Lab] success
2018/03/08 19:32:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.Lab_SSH] success
2018/03/08 19:32:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.LEDE_Lab_SSH] success
2018/03/08 19:32:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.LEDE_Http_Proxy] success
2018/03/08 19:33:02 [I] [service.go:262] client login info: ip [**] version [0.16.0] hostname [] os [darwin] arch [amd64]
2018/03/08 19:33:09 [I] [proxy.go:85] [facc31e74000562d] [cc.Lab_SSH] get a new work connection: [**]
2018/03/08 19:33:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.Remote_Desk_Lab] error: custom listener for [cc.Remote_Desk_Lab] is repeated
2018/03/08 19:33:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.Lab_SSH] error: custom listener for [cc.Lab_SSH] is repeated
2018/03/08 19:33:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.LEDE_Lab_SSH] error: custom listener for [cc.LEDE_Lab_SSH] is repeated
2018/03/08 19:33:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.LEDE_Http_Proxy] error: custom listener for [cc.LEDE_Http_Proxy] is repeated
2018/03/08 19:33:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.Remote_Desk_Lab] success
2018/03/08 19:33:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.Lab_SSH] success
2018/03/08 19:33:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.LEDE_Lab_SSH] success
2018/03/08 19:33:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.LEDE_Http_Proxy] success
2018/03/08 19:34:01 [W] [control.go:241] [a8dd87b2860d1a9e] read error: broken pipe
2018/03/08 19:34:01 [I] [control.go:213] [a8dd87b2860d1a9e] control writer is closing
2018/03/08 19:34:01 [I] [proxy.go:71] [a8dd87b2860d1a9e] [cc.Titan2_SSH] proxy closing
2018/03/08 19:34:01 [I] [proxy.go:71] [a8dd87b2860d1a9e] [cc.Titan1_SSH] proxy closing
2018/03/08 19:34:01 [I] [control.go:282] [a8dd87b2860d1a9e] client exit success
2018/03/08 19:34:01 [I] [proxy.go:117] [a8dd87b2860d1a9e] [cc.Titan1_SSH] listener is closed
2018/03/08 19:34:01 [W] [service.go:247] Accept new mux stream error: broken pipe
2018/03/08 19:34:01 [I] [proxy.go:117] [a8dd87b2860d1a9e] [cc.Titan2_SSH] listener is closed
2018/03/08 19:36:32 [W] [service.go:247] Accept new mux stream error: broken pipe
2018/03/08 19:36:32 [W] [control.go:241] [5898fce16f6f7a83] read error: broken pipe
2018/03/08 19:36:32 [I] [control.go:213] [5898fce16f6f7a83] control writer is closing
2018/03/08 19:36:32 [I] [control.go:282] [5898fce16f6f7a83] client exit success
2018/03/08 19:36:42 [I] [service.go:262] client login info: ip [**] version [0.16.0] hostname [] os [linux] arch [amd64]
2018/03/08 19:36:42 [W] [control.go:321] [d53116d4c0d0490e] new proxy [cc.LEDE_Lab_SSH] error: custom listener for [cc.LEDE_Lab_SSH] is repeated
2018/03/08 19:36:42 [W] [control.go:321] [d53116d4c0d0490e] new proxy [cc.LEDE_Http_Proxy] error: custom listener for [cc.LEDE_Http_Proxy] is repeated
2018/03/08 19:36:42 [I] [proxy.go:379] [d53116d4c0d0490e] [cc.Titan1_SSH] stcp proxy custom listen success
2018/03/08 19:36:42 [I] [control.go:324] [d53116d4c0d0490e] new proxy [cc.Titan1_SSH] success
2018/03/08 19:36:42 [I] [proxy.go:379] [d53116d4c0d0490e] [cc.Titan2_SSH] stcp proxy custom listen success
2018/03/08 19:36:42 [I] [control.go:324] [d53116d4c0d0490e] new proxy [cc.Titan2_SSH] success
2018/03/08 19:36:42 [W] [control.go:321] [d53116d4c0d0490e] new proxy [cc.Remote_Desk_Lab] error: custom listener for [cc.Remote_Desk_Lab] is repeated
2018/03/08 19:36:42 [W] [control.go:321] [d53116d4c0d0490e] new proxy [cc.Lab_SSH] error: custom listener for [cc.Lab_SSH] is repeated
2018/03/08 19:36:42 [I] [control.go:330] [d53116d4c0d0490e] close proxy [cc.LEDE_Lab_SSH] success
2018/03/08 19:36:42 [I] [control.go:330] [d53116d4c0d0490e] close proxy [cc.LEDE_Http_Proxy] success
2018/03/08 19:36:42 [I] [control.go:330] [d53116d4c0d0490e] close proxy [cc.Remote_Desk_Lab] success
2018/03/08 19:36:42 [I] [control.go:330] [d53116d4c0d0490e] close proxy [cc.Lab_SSH] success
<!-- gh-comment-id:374140384 --> @Hirozy commented on GitHub (Mar 19, 2018): 感谢回答,不好意思,本地同样没能再次复现,推测当时运行的环境有关。下面是当时的完整日志,再次表示感谢! ``` 2018/03/08 20:40:27 [D] [control.go:333] [facc31e74000562d] receive heartbeat 2018/03/08 20:40:33 [D] [control.go:333] [0b751c5f86d2c8be] receive heartbeat 2018/03/08 20:40:57 [D] [control.go:333] [facc31e74000562d] receive heartbeat 2018/03/08 20:41:03 [W] [control.go:321] [0b751c5f86d2c8be] new proxy [cc.Remote_Desk_Lab] error: custom listener for [cc.Remote_Desk_Lab] is repeated 2018/03/08 20:41:03 [W] [control.go:321] [0b751c5f86d2c8be] new proxy [cc.Lab_SSH] error: custom listener for [cc.Lab_SSH] is repeated 2018/03/08 20:41:03 [W] [control.go:321] [0b751c5f86d2c8be] new proxy [cc.LEDE_Lab_SSH] error: custom listener for [cc.LEDE_Lab_SSH] is repeated 2018/03/08 20:41:03 [W] [control.go:321] [0b751c5f86d2c8be] new proxy [cc.LEDE_Http_Proxy] error: custom listener for [cc.LEDE_Http_Proxy] is repeated 2018/03/08 20:41:03 [D] [control.go:333] [0b751c5f86d2c8be] receive heartbeat 2018/03/08 20:41:03 [I] [control.go:330] [0b751c5f86d2c8be] close proxy [cc.Remote_Desk_Lab] success 2018/03/08 20:41:03 [I] [control.go:330] [0b751c5f86d2c8be] close proxy [cc.Lab_SSH] success 2018/03/08 20:41:03 [I] [control.go:330] [0b751c5f86d2c8be] close proxy [cc.LEDE_Lab_SSH] success 2018/03/08 20:41:03 [I] [control.go:330] [0b751c5f86d2c8be] close proxy [cc.LEDE_Http_Proxy] success 2018/03/08 20:41:10 [W] [control.go:241] [0b751c5f86d2c8be] read error: broken pipe 2018/03/08 20:41:10 [I] [control.go:213] [0b751c5f86d2c8be] control writer is closing 2018/03/08 20:41:10 [I] [proxy.go:71] [0b751c5f86d2c8be] [cc.Titan1_SSH] proxy closing 2018/03/08 20:41:10 [I] [proxy.go:71] [0b751c5f86d2c8be] [cc.Titan2_SSH] proxy closing 2018/03/08 20:41:10 [I] [control.go:282] [0b751c5f86d2c8be] client exit success 2018/03/08 20:41:10 [I] [proxy.go:117] [0b751c5f86d2c8be] [cc.Titan2_SSH] listener is closed 2018/03/08 20:41:10 [W] [service.go:247] Accept new mux stream error: broken pipe 2018/03/08 20:41:10 [I] [proxy.go:117] [0b751c5f86d2c8be] [cc.Titan1_SSH] listener is closed 2018/03/08 20:41:12 [I] [service.go:262] client login info: ip [*] version [0.16.0] hostname [] os [linux] arch [amd64] 2018/03/08 20:41:12 [W] [control.go:321] [f324c48b77f65e26] new proxy [cc.Lab_SSH] error: custom listener for [cc.Lab_SSH] is repeated 2018/03/08 20:41:12 [W] [control.go:321] [f324c48b77f65e26] new proxy [cc.LEDE_Lab_SSH] error: custom listener for [cc.LEDE_Lab_SSH] is repeated 2018/03/08 20:41:12 [W] [control.go:321] [f324c48b77f65e26] new proxy [cc.LEDE_Http_Proxy] error: custom listener for [cc.LEDE_Http_Proxy] is repeated 2018/03/08 20:41:12 [D] [control.go:130] [f324c48b77f65e26] new work connection registered 2018/03/08 20:41:12 [I] [proxy.go:379] [f324c48b77f65e26] [cc.Titan1_SSH] stcp proxy custom listen success 2018/03/08 20:41:12 [I] [control.go:324] [f324c48b77f65e26] new proxy [cc.Titan1_SSH] success 2018/03/08 20:41:12 [I] [proxy.go:379] [f324c48b77f65e26] [cc.Titan2_SSH] stcp proxy custom listen success 2018/03/08 20:41:12 [I] [control.go:324] [f324c48b77f65e26] new proxy [cc.Titan2_SSH] success 2018/03/08 20:41:12 [W] [control.go:321] [f324c48b77f65e26] new proxy [cc.Remote_Desk_Lab] error: custom listener for [cc.Remote_Desk_Lab] is repeated 2018/03/08 20:41:12 [D] [control.go:130] [f324c48b77f65e26] new work connection registered 2018/03/08 20:41:12 [D] [control.go:130] [f324c48b77f65e26] new work connection registered 2018/03/08 20:41:12 [D] [control.go:130] [f324c48b77f65e26] new work connection registered 2018/03/08 20:41:12 [D] [control.go:130] [f324c48b77f65e26] new work connection registered 2018/03/08 20:41:12 [I] [control.go:330] [f324c48b77f65e26] close proxy [cc.Lab_SSH] success 2018/03/08 20:41:12 [I] [control.go:330] [f324c48b77f65e26] close proxy [cc.LEDE_Lab_SSH] success 2018/03/08 20:41:12 [I] [control.go:330] [f324c48b77f65e26] close proxy [cc.LEDE_Http_Proxy] success 2018/03/08 20:41:12 [I] [control.go:330] [f324c48b77f65e26] close proxy [cc.Remote_Desk_Lab] success ``` 下面是从服务端开启时的日志 ``` 2018/03/08 19:29:47 [I] [service.go:96] frps tcp listen on [::]:xxx 2018/03/08 19:29:47 [I] [service.go:125] http service listen on [::]:xxxx 2018/03/08 19:29:47 [I] [service.go:141] https service listen on [::]:xxx 2018/03/08 19:29:47 [I] [service.go:154] nat hole udp service listen on [::]:xxxx 2018/03/08 19:29:47 [I] [service.go:164] Dashboard listen on [::]:xxxx 2018/03/08 19:29:47 [I] [main.go:112] Start frps success 2018/03/08 19:29:47 [I] [main.go:114] PrivilegeMode is enabled, you should pay more attention to security issues 2018/03/08 19:29:48 [I] [service.go:262] client login info: ip [**] version [0.16.0] hostname [] os [linux] arch [amd64] 2018/03/08 19:29:48 [I] [proxy.go:379] [facc31e74000562d] [cc.LEDE_Lab_SSH] stcp proxy custom listen success 2018/03/08 19:29:48 [I] [control.go:324] [facc31e74000562d] new proxy [cc.LEDE_Lab_SSH] success 2018/03/08 19:29:48 [I] [proxy.go:379] [facc31e74000562d] [cc.LEDE_Http_Proxy] stcp proxy custom listen success 2018/03/08 19:29:48 [I] [control.go:324] [facc31e74000562d] new proxy [cc.LEDE_Http_Proxy] success 2018/03/08 19:29:48 [I] [proxy.go:379] [facc31e74000562d] [cc.Titan_SSH] stcp proxy custom listen success 2018/03/08 19:29:48 [I] [control.go:324] [facc31e74000562d] new proxy [cc.Titan_SSH] success 2018/03/08 19:29:48 [I] [proxy.go:379] [facc31e74000562d] [cc.Remote_Desk_Lab] stcp proxy custom listen success 2018/03/08 19:29:48 [I] [control.go:324] [facc31e74000562d] new proxy [cc.Remote_Desk_Lab] success 2018/03/08 19:29:48 [I] [proxy.go:379] [facc31e74000562d] [cc.Lab_SSH] stcp proxy custom listen success 2018/03/08 19:29:48 [I] [control.go:324] [facc31e74000562d] new proxy [cc.Lab_SSH] success 2018/03/08 19:32:52 [I] [service.go:262] client login info: ip [**] version [0.16.0] hostname [] os [linux] arch [amd64] 2018/03/08 19:32:52 [I] [proxy.go:379] [a8dd87b2860d1a9e] [cc.Titan2_SSH] stcp proxy custom listen success 2018/03/08 19:32:52 [I] [control.go:324] [a8dd87b2860d1a9e] new proxy [cc.Titan2_SSH] success 2018/03/08 19:32:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.Remote_Desk_Lab] error: custom listener for [cc.Remote_Desk_Lab] is repeated 2018/03/08 19:32:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.Lab_SSH] error: custom listener for [cc.Lab_SSH] is repeated 2018/03/08 19:32:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.LEDE_Lab_SSH] error: custom listener for [cc.LEDE_Lab_SSH] is repeated 2018/03/08 19:32:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.LEDE_Http_Proxy] error: custom listener for [cc.LEDE_Http_Proxy] is repeated 2018/03/08 19:32:52 [I] [proxy.go:379] [a8dd87b2860d1a9e] [cc.Titan1_SSH] stcp proxy custom listen success 2018/03/08 19:32:52 [I] [control.go:324] [a8dd87b2860d1a9e] new proxy [cc.Titan1_SSH] success 2018/03/08 19:32:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.Remote_Desk_Lab] success 2018/03/08 19:32:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.Lab_SSH] success 2018/03/08 19:32:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.LEDE_Lab_SSH] success 2018/03/08 19:32:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.LEDE_Http_Proxy] success 2018/03/08 19:33:02 [I] [service.go:262] client login info: ip [**] version [0.16.0] hostname [] os [darwin] arch [amd64] 2018/03/08 19:33:09 [I] [proxy.go:85] [facc31e74000562d] [cc.Lab_SSH] get a new work connection: [**] 2018/03/08 19:33:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.Remote_Desk_Lab] error: custom listener for [cc.Remote_Desk_Lab] is repeated 2018/03/08 19:33:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.Lab_SSH] error: custom listener for [cc.Lab_SSH] is repeated 2018/03/08 19:33:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.LEDE_Lab_SSH] error: custom listener for [cc.LEDE_Lab_SSH] is repeated 2018/03/08 19:33:52 [W] [control.go:321] [a8dd87b2860d1a9e] new proxy [cc.LEDE_Http_Proxy] error: custom listener for [cc.LEDE_Http_Proxy] is repeated 2018/03/08 19:33:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.Remote_Desk_Lab] success 2018/03/08 19:33:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.Lab_SSH] success 2018/03/08 19:33:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.LEDE_Lab_SSH] success 2018/03/08 19:33:52 [I] [control.go:330] [a8dd87b2860d1a9e] close proxy [cc.LEDE_Http_Proxy] success 2018/03/08 19:34:01 [W] [control.go:241] [a8dd87b2860d1a9e] read error: broken pipe 2018/03/08 19:34:01 [I] [control.go:213] [a8dd87b2860d1a9e] control writer is closing 2018/03/08 19:34:01 [I] [proxy.go:71] [a8dd87b2860d1a9e] [cc.Titan2_SSH] proxy closing 2018/03/08 19:34:01 [I] [proxy.go:71] [a8dd87b2860d1a9e] [cc.Titan1_SSH] proxy closing 2018/03/08 19:34:01 [I] [control.go:282] [a8dd87b2860d1a9e] client exit success 2018/03/08 19:34:01 [I] [proxy.go:117] [a8dd87b2860d1a9e] [cc.Titan1_SSH] listener is closed 2018/03/08 19:34:01 [W] [service.go:247] Accept new mux stream error: broken pipe 2018/03/08 19:34:01 [I] [proxy.go:117] [a8dd87b2860d1a9e] [cc.Titan2_SSH] listener is closed 2018/03/08 19:36:32 [W] [service.go:247] Accept new mux stream error: broken pipe 2018/03/08 19:36:32 [W] [control.go:241] [5898fce16f6f7a83] read error: broken pipe 2018/03/08 19:36:32 [I] [control.go:213] [5898fce16f6f7a83] control writer is closing 2018/03/08 19:36:32 [I] [control.go:282] [5898fce16f6f7a83] client exit success 2018/03/08 19:36:42 [I] [service.go:262] client login info: ip [**] version [0.16.0] hostname [] os [linux] arch [amd64] 2018/03/08 19:36:42 [W] [control.go:321] [d53116d4c0d0490e] new proxy [cc.LEDE_Lab_SSH] error: custom listener for [cc.LEDE_Lab_SSH] is repeated 2018/03/08 19:36:42 [W] [control.go:321] [d53116d4c0d0490e] new proxy [cc.LEDE_Http_Proxy] error: custom listener for [cc.LEDE_Http_Proxy] is repeated 2018/03/08 19:36:42 [I] [proxy.go:379] [d53116d4c0d0490e] [cc.Titan1_SSH] stcp proxy custom listen success 2018/03/08 19:36:42 [I] [control.go:324] [d53116d4c0d0490e] new proxy [cc.Titan1_SSH] success 2018/03/08 19:36:42 [I] [proxy.go:379] [d53116d4c0d0490e] [cc.Titan2_SSH] stcp proxy custom listen success 2018/03/08 19:36:42 [I] [control.go:324] [d53116d4c0d0490e] new proxy [cc.Titan2_SSH] success 2018/03/08 19:36:42 [W] [control.go:321] [d53116d4c0d0490e] new proxy [cc.Remote_Desk_Lab] error: custom listener for [cc.Remote_Desk_Lab] is repeated 2018/03/08 19:36:42 [W] [control.go:321] [d53116d4c0d0490e] new proxy [cc.Lab_SSH] error: custom listener for [cc.Lab_SSH] is repeated 2018/03/08 19:36:42 [I] [control.go:330] [d53116d4c0d0490e] close proxy [cc.LEDE_Lab_SSH] success 2018/03/08 19:36:42 [I] [control.go:330] [d53116d4c0d0490e] close proxy [cc.LEDE_Http_Proxy] success 2018/03/08 19:36:42 [I] [control.go:330] [d53116d4c0d0490e] close proxy [cc.Remote_Desk_Lab] success 2018/03/08 19:36:42 [I] [control.go:330] [d53116d4c0d0490e] close proxy [cc.Lab_SSH] success ```
Author
Owner

@fatedier commented on GitHub (Mar 20, 2018):

从服务端的日志来看,是前一个 frpc 还没有关闭的情况下,就启动了新的 frpc,至于原因不是很清楚。重启时最好等前一个进程完全退出后,再启动新的。

<!-- gh-comment-id:374527963 --> @fatedier commented on GitHub (Mar 20, 2018): 从服务端的日志来看,是前一个 frpc 还没有关闭的情况下,就启动了新的 frpc,至于原因不是很清楚。重启时最好等前一个进程完全退出后,再启动新的。
Author
Owner

@Hirozy commented on GitHub (Mar 20, 2018):

我大概也猜到这点了,手动给 frp 的 restart 加了几秒的延时,暂时没发现问题。
PS: 我这里不能复现,应当就是因为在 restart 上加了延时

<!-- gh-comment-id:374528853 --> @Hirozy commented on GitHub (Mar 20, 2018): 我大概也猜到这点了,手动给 frp 的 restart 加了几秒的延时,暂时没发现问题。 PS: 我这里不能复现,应当就是因为在 restart 上加了延时
Author
Owner

@fatedier commented on GitHub (Mar 20, 2018):

这个和你加延时应该关系不大,可以看 facc31e74000562d 这个 runId 对应的日志,这个是最先连接上来的 client,到最后都没有它关闭的相关日志,这里可能存在着某种特殊情况,导致你当时没有正常关闭那个进程?

<!-- gh-comment-id:374530490 --> @fatedier commented on GitHub (Mar 20, 2018): 这个和你加延时应该关系不大,可以看 facc31e74000562d 这个 runId 对应的日志,这个是最先连接上来的 client,到最后都没有它关闭的相关日志,这里可能存在着某种特殊情况,导致你当时没有正常关闭那个进程?
Author
Owner

@Hirozy commented on GitHub (Mar 20, 2018):

我这里总使用了三个 Client, A, B, C ,其中一个 A 配置全为 visitor,用来连接 B, C,并且 A 一直保持连接 ,是这个特殊情况?

<!-- gh-comment-id:374532370 --> @Hirozy commented on GitHub (Mar 20, 2018): 我这里总使用了三个 Client, `A, B, C` ,其中一个 `A` 配置全为 visitor,用来连接 `B, C`,并且 `A` 一直保持连接 ,是这个特殊情况?
Author
Owner

@fatedier commented on GitHub (Mar 20, 2018):

没法猜测,如果暂时没有问题, 就不细究了,等能复现的时候再排查下原因。

<!-- gh-comment-id:374539267 --> @fatedier commented on GitHub (Mar 20, 2018): 没法猜测,如果暂时没有问题, 就不细究了,等能复现的时候再排查下原因。
Author
Owner

@Geker commented on GitHub (Jul 17, 2018):

你是不是使用kcp了。我注释掉kcp协议,貌似没这个问题了

<!-- gh-comment-id:405521615 --> @Geker commented on GitHub (Jul 17, 2018): 你是不是使用kcp了。我注释掉kcp协议,貌似没这个问题了
Sign in to join this conversation.
No milestone
No project
No assignees
1 participant
Notifications
Due date
The due date is invalid or out of range. Please use the format "yyyy-mm-dd".

No due date set.

Dependencies

No dependencies set.

Reference: github-starred/frp#514
No description provided.