Dubbo應(yīng)用啟動(dòng)注冊(cè)ZK獲取IP慢的原因之一

突然有一天，我的idea啟動(dòng)應(yīng)用慢了，要3分鐘才能啟動(dòng)完畢。

主要看到的log中有

No valid ip found from environment, try to find valid host from DNS.

看了一下是主要是dubbo注冊(cè)的時(shí)候慢，我一度以為公司的對(duì)我的ip有做限制了，因?yàn)槠渌藛?dòng)又很快。

1：第一次思路是我自己下載了zk運(yùn)行起來注冊(cè)到本地來看看，也是照樣慢。

2：idea慢，我設(shè)置了idea的啟動(dòng)最大內(nèi)存為2G，這之前我沒設(shè)置過，后發(fā)現(xiàn)也無效。

3：我一度以為我的IP vpn做了轉(zhuǎn)發(fā)引起的，或者電腦安裝了什么軟件導(dǎo)致慢了。最后發(fā)現(xiàn)也不是。

            [TID: N/A|] o.a.d.r.z.ZookeeperRegistry              :  [DUBBO] Register: dubbo://127.0.0.1:20880/com.xxx.api.server.sys.XxxFacade?application=mydemo-server&deprecated=false&dubbo=2.0.2&release=2.7.4.1&timeout=60000&timestamp=1628244643743, dubbo version: 2.7.4.1, current host: 127.0.0.1
2021-08-06 18:10:43.983  INFO [           main]
            [TID: N/A|] o.a.d.r.z.ZookeeperRegistry              :  [DUBBO] Subscribe: provider://127.0.0.1:20880/com.xxx.api.server.sys.XxxFacade?anyhost=true&application=mydemo-server&bean.name=ServiceBean:com.xxx.api.server.sys.XxxFacade&bind.ip=127.0.0.1&bind.port=20880&category=configurators&check=false&delay=0&deprecated=false&dubbo=2.0.2&dynamic=true&generic=false&interface=com.xxx.api.server.sys.XxxFacade&methods=listNewOrganizations,listTreeCmdbOrg,listTCOrg&pid=24334&qos.enable=false&release=2.7.4.1&side=provider&timeout=60000&timestamp=1628244643743, dubbo version: 2.7.4.1, current host: 127.0.0.1
2021-08-06 18:10:44.098  INFO [           main]
            [TID: N/A|] o.a.d.r.z.ZookeeperRegistry              :  [DUBBO] Notify urls for subscribe url provider://127.0.0.1:20880/com.xxx.api.server.sys.XxxFacade?anyhost=true&application=mydemo-server&bean.name=ServiceBean:com.xxx.api.server.sys.XxxFacade&bind.ip=127.0.0.1&bind.port=20880&category=configurators&check=false&delay=0&deprecated=false&dubbo=2.0.2&dynamic=true&generic=false&interface=com.xxx.api.server.sys.XxxFacade&methods=listNewOrganizations,listTreeCmdbOrg,listTCOrg&pid=24334&qos.enable=false&release=2.7.4.1&side=provider&timeout=60000&timestamp=1628244643743, urls: [override://10.216.7.243:20880/com.xxx.api.server.sys.XxxFacade?category=configurators&disabled=true&dynamic=false&enabled=true], dubbo version: 2.7.4.1, current host: 127.0.0.1
2021-08-06 18:10:44.099  INFO [           main]
            [TID: N/A|] o.a.d.c.AbstractConfig                   :  [DUBBO] The service ready on spring started. service: com.xxx.api.facade.DailyFacade, dubbo version: 2.7.4.1, current host: 127.0.0.1
2021-08-06 18:10:44.099  INFO [Report-thread-1]
            [TID: N/A|] o.a.d.m.s.z.ZookeeperMetadataReport      :  [DUBBO] store provider metadata. Identifier : org.apache.dubbo.metadata.identifier.MetadataIdentifier@3e661e1b; definition: FullServiceDefinition{parameters={side=provider, release=2.7.4.1, methods=listNewOrganizations,listTreeCmdbOrg,listTCOrg, deprecated=false, dubbo=2.0.2, interface=com.xxx.api.server.sys.XxxFacade, qos.enable=false, timeout=60000, generic=false, delay=0, application=mydemo-server, dynamic=true, bean.name=ServiceBean:com.xxx.api.server.sys.XxxFacade, anyhost=true}} ServiceDefinition [canonicalName=com.xxx.api.server.sys.XxxFacade, codeSource=file:/Users/cookie.joo/IdeaProjects/mydemo/mydemo-api/target/classes/, methods=[MethodDefinition [name=listNewOrganizations, parameterTypes=[], returnType=com.xxx.common.entity.RpcResult], MethodDefinition [name=listTreeCmdbOrg, parameterTypes=[], returnType=com.xxx.common.entity.RpcResult], MethodDefinition [name=listTCOrg, parameterTypes=[], returnType=com.xxx.common.entity.RpcResult]]], dubbo version: 2.7.4.1, current host: 127.0.0.1
2021-08-06 18:10:44.108  INFO [           main]
            [TID: N/A|] o.a.d.c.AbstractConfig                   :  [DUBBO] No valid ip found from environment, try to find valid host from DNS., dubbo version: 2.7.4.1, current host: 127.0.0.1
2021-08-06 18:10:44.169  INFO [           main]
            [TID: N/A|] o.a.d.c.AbstractConfig                   :  [DUBBO] Export dubbo service com.xxx.api.facade.DailyFacade to local registry url : injvm://127.0.0.1/com.xxx.api.facade.DailyFacade?anyhost=true&application=mydemo-server&bean.name=ServiceBean:com.xxx.api.facade.DailyFacade&bind.ip=127.0.0.1&bind.port=20880&delay=0&deprecated=false&dubbo=2.0.2&dynamic=true&generic=false&interface=com.xxx.api.facade.DailyFacade&methods=jiraRequestCheck,checkRdRequest,jiraSearch,remind&pid=24334&qos.enable=false&release=2.7.4.1&side=provider&timeout=60000&timestamp=1628244644105, dubbo version: 2.7.4.1, current host: 127.0.0.1
2021-08-06 18:10:44.169  INFO [           main]
            [TID: N/A|] o.a.d.c.AbstractConfig                   :  [DUBBO] There's no valid monitor config found, if you want to open monitor statistics for Dubbo, please make sure your monitor is configured properly., dubbo version: 2.7.4.1, current host: 127.0.0.1
2021-08-06 18:10:44.169  INFO [           main]

我繼續(xù)看日志中的類

主要是很多次 [DUBBO] Register: 出現(xiàn)這一句的時(shí)候會(huì)卡一下

繼續(xù)出現(xiàn)下一個(gè)接口注冊(cè)又卡一下，我大概已經(jīng)猜測就是ip尋址的問題了

最后我從代碼中看到了org.apache.dubbo.common.utils.NetUtils#getLocalHost這個(gè)方法

斷點(diǎn)后確實(shí)是這個(gè)方法有點(diǎn)卡頓，差不多幾秒才有反應(yīng)的。

我搜了一些文章確實(shí)是反應(yīng)了這個(gè)getHostAddress的問題，要設(shè)置hostname和127.0.0.1的映射關(guān)系

我就設(shè)置了hosts，然后啟動(dòng)確實(shí)好了，恢復(fù)到幾十秒內(nèi)啟動(dòng)完畢。（hostname是機(jī)器名）

其中的問題是getHostAddress是使用native本地方法來獲取的，如果沒有配置好映射會(huì)出現(xiàn)調(diào)用方法卡頓的問題。

class Inet4AddressImpl implements InetAddressImpl {
    public native String getLocalHostName() throws UnknownHostException;

如果啟動(dòng)項(xiàng)目要這么久，開發(fā)代碼到測試階段一定會(huì)很痛苦，這個(gè)問題不解決，以后工作效率肯定會(huì)奔潰。

注意：

注冊(cè)接口到zk時(shí)打印日志會(huì)有個(gè)空隙，并不是平滑的日志打印那種感覺，這就是問題的現(xiàn)象。

總結(jié)

以上為個(gè)人經(jīng)驗(yàn)，希望能給大家一個(gè)參考，也希望大家多多支持腳本之家。

最新評(píng)論