以下比较了登录密码正确和错误时日志的相同和不同之处。正常连接过程的日志:
[INFO] [2012-02-09 13:56:39,067] [com.boco.godu.app.server.TelnetServerHandler,71] - [[73:/10.32.1.6:56183] 管理模式,下发命令:cn id=1830753227] [DEBUG] [2012-02-09 13:56:39,071] [com.boco.godu.common.util.GlobalUtil,549] - [find platform at 0 times] [DEBUG] [2012-02-09 13:56:39,074] [com.boco.godu.common.util.GlobalUtil,549] - [find platform at 0 times] [INFO] [2012-02-09 13:56:39,075] [com.boco.godu.framework.command.CommandOpenne,984] - [NEID:1830753227;Channels:[channelID=52318885;channelName=FCGBSC7-5000端口;priority=0]] [INFO] [2012-02-09 13:56:39,075] [com.boco.godu.framework.command.CommandOpenne,759] - [get channelid:52318885] [INFO] [2012-02-09 13:56:39,076] [com.boco.godu.app.server.TelnetServerHandler,71] - [[73:/10.32.1.6:56183] FOUND ID:52318885] [DEBUG] [2012-02-09 13:56:39,077] [com.boco.godu.common.util.GlobalUtil,549] - [find platform at 0 times] [DEBUG] [2012-02-09 13:56:39,079] [com.boco.godu.app.server.TelnetServerHandler,56] - [[73:/10.32.1.6:56183] ==================PreOccupy the NE=================] [DEBUG] [2012-02-09 13:56:39,079] [com.boco.godu.app.server.TelnetServerHandler,56] - [[73:/10.32.1.6:56183] NEID:1830753227;;channelID=52318885;channelName=FCGBSC7-5000端口;priority=0] [INFO] [2012-02-09 13:56:39,080] [com.boco.godu.app.server.TelnetServerHandler,71] - [[73:/10.32.1.6:56183] 没有配置登录脚本] [INFO] [2012-02-09 13:56:39,080] [com.boco.godu.framework.ConnectorFactory,44] - [connecting ne with protocal:SSH] [INFO] [2012-02-09 13:56:39,081] [com.boco.godu.app.server.TelnetServerHandler,71] - [[73:/10.32.1.6:56183] Begin to Connection NE,10.0.2.47:22..............] [INFO] [2012-02-09 13:56:39,081] [com.boco.godu.app.server.TelnetServerHandler,71] - [[73:/10.32.1.6:56183] 开始连接网元awaitUninterruptibly127.0.0.1:8900] [DEBUG] [2012-02-09 13:56:39,082] [com.boco.godu.adapter.telnet.TelnetConnectorHandler,150] - [create session:10_0_2_47&&1234&&6&&76] [INFO] [2012-02-09 13:56:39,083] [com.boco.godu.adapter.ssh.SshHandler,71] - [[76:/127.0.0.1:8900] SessionOpen,add 10_0_2_47&&1234&&6&&76to sessionMap] [DEBUG] [2012-02-09 13:56:39,086] [org.apache.velocity.runtime.log.Log4JLogChute,168] - [ResourceManager : found com/boco/godu/adapter/proxy/internalPojo.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader] [INFO] [2012-02-09 13:56:39,093] [com.boco.godu.adapter.proxy.ssh.SshProxy,116] - [ssh client try to connect:10.0.2.47@22] [INFO] [2012-02-09 13:56:39,238] [com.boco.godu.adapter.proxy.ssh.SshProxy,136] - [SSH-2.0-OpenSSH_4.3-------------------------------------------------] [INFO] [2012-02-09 13:56:39,242] [com.boco.godu.app.server.TelnetServerHandler,71] - [[73:/10.32.1.6:56183] [OpenNe] Connect 10.0.2.47:22 completed!] [DEBUG] [2012-02-09 13:56:39,244] [com.boco.godu.app.server.TelnetServerHandler,56] - [[73:/10.32.1.6:56183] [OpenNe] Step1, get NE Name] [DEBUG] [2012-02-09 13:56:39,244] [com.boco.godu.app.server.TelnetServerHandler,56] - [[73:/10.32.1.6:56183] [OpenNe] Step 2,Configure timeout] [INFO] [2012-02-09 13:56:39,245] [com.boco.godu.app.server.TelnetServerHandler,71] - [[73:/10.32.1.6:56183] [OpenNe] step 3, Terminal status fine] [DEBUG] [2012-02-09 13:56:39,245] [com.boco.godu.common.util.GlobalUtil,549] - [find platform at 0 times] [INFO] [2012-02-09 13:56:39,247] [com.boco.godu.adapter.manager.ManagerMap,105] - [event:CONNECTION] [INFO] [2012-02-09 13:56:39,248] [com.boco.godu.common.util.GlobalUtil,430] - [get current platform:10_0_2_47&&1234&&0&&73] [DEBUG] [2012-02-09 13:56:39,248] [com.boco.godu.common.util.GlobalUtil,549] - [find platform at 0 times] [INFO] [2012-02-09 13:56:39,249] [com.boco.godu.common.util.GlobalUtil,441] - [delete local or preoccupied ne:10_0_2_47&&1234&&0&&73] [DEBUG] [2012-02-09 13:56:39,250] [com.boco.godu.app.server.TelnetServerHandler,56] - [[73:/10.32.1.6:56183] [OpenNe] step 4, remove the preoccupied ne] [DEBUG] [2012-02-09 13:56:39,250] [com.boco.godu.app.server.TelnetServerHandler,56] - [[73:/10.32.1.6:56183] [OpenNe] step 5, config command status] [DEBUG] [2012-02-09 13:56:39,251] [com.boco.godu.app.server.TelnetServerHandler,56] - [[73:/10.32.1.6:56183] [OpenNe] Step 6,Config timeout] [INFO] [2012-02-09 13:56:39,251] [com.boco.godu.adapter.ssh.SshHandler,71] - [[76:/127.0.0.1:8900] add filter LOGIN_ENDFLAG_FILTER; Class =com.boco.godu.adapter.filter.LoginEndFlagFilter] [INFO] [2012-02-09 13:56:39,252] [com.boco.godu.framework.command.CommandOpenne,1175] - [add connector EndFlag :>|<|#|\\(|%] [DEBUG] [2012-02-09 13:56:39,252] [com.boco.godu.app.server.TelnetServerHandler,56] - [[73:/10.32.1.6:56183] [OpenNe] Step 7,Config login_endflag_filter] [INFO] [2012-02-09 13:56:39,253] [com.boco.godu.adapter.manager.ManagerMap,105] - [event:NESUBSCRIBE] [DEBUG] [2012-02-09 13:56:39,253] [com.boco.godu.app.server.TelnetServerHandler,56] - [[73:/10.32.1.6:56183] [OpenNe] step 8, establish pub/sub relation] [DEBUG] [2012-02-09 13:56:39,253] [com.boco.godu.common.util.IoSessionHelper,72] - [(0x0000004C: nio socket, client, /127.0.0.1:58043 => /127.0.0.1:8900) send 0 to messageReceive for cached bufferas buffer:2Last login: Thu Feb 9 13:55:24 2012 from goduserver ] [INFO] [2012-02-09 13:56:39,254] [com.boco.godu.framework.command.CommandSetEndFlag,137] - [设置命令结束:vendor_id=1;ne_type=200;version=07B] [INFO] [2012-02-09 13:56:39,255] [com.boco.godu.adapter.ssh.SshHandler,71] - [[76:/127.0.0.1:8900] add filter ChineseByteFilter; Class =com.boco.godu.adapter.filter.ChineseByteFilter] [INFO] [2012-02-09 13:56:39,255] [com.boco.godu.adapter.ssh.SshHandler,71] - [[76:/127.0.0.1:8900] add filter ENDFLAGFILTER; Class =com.boco.godu.adapter.filter.CmdOverFilter] [DEBUG] [2012-02-09 13:56:39,255] [com.boco.godu.framework.commandover.CommandOverUtils,118] - [begin verify cmdover status by ne_type=200;version=07B;vendor_id=1] [DEBUG] [2012-02-09 13:56:39,256] [com.boco.godu.framework.commandover.CommandOverUtils,124] - [STARTING COMMAND OVER STATUS=1] [DEBUG] [2012-02-09 13:56:39,257] [com.boco.godu.framework.commandover.CommandOverUtils,131] - [STATUS=1 has empty overStatus] [DEBUG] [2012-02-09 13:56:39,257] [com.boco.godu.framework.commandover.CommandOverUtils,124] - [STARTING COMMAND OVER STATUS=2] [DEBUG] [2012-02-09 13:56:39,257] [com.boco.godu.framework.commandover.CommandOverUtils,131] - [STATUS=2 has empty overStatus] [DEBUG] [2012-02-09 13:56:39,258] [com.boco.godu.framework.commandover.CommandOverUtils,124] - [STARTING COMMAND OVER STATUS=3] [DEBUG] [2012-02-09 13:56:39,258] [com.boco.godu.framework.commandover.CommandOverUtils,131] - [STATUS=3 has empty overStatus] [DEBUG] [2012-02-09 13:56:39,258] [com.boco.godu.framework.commandover.CommandOverUtils,124] - [STARTING COMMAND OVER STATUS=4] [DEBUG] [2012-02-09 13:56:39,259] [com.boco.godu.framework.commandover.CommandOverUtils,129] - [END COMMAND OVER STATUS=4] [DEBUG] [2012-02-09 13:56:39,259] [com.boco.godu.framework.command.CommandSetEndFlag,202] - [命令结束表达式:{}] [DEBUG] [2012-02-09 13:56:39,279] [com.boco.godu.adapter.proxy.ssh.GODUSSHChannelEventListener,34] - [ssh channel receive:[91, 103, 111, 100, 117, 64, 103, 111, 100, 117, 115, 101, 114, 118, 101, 114, 32, 126, 93, 36, 32]as String:[godu@goduserver ~]\) ] [INFO] [2012-02-09 13:56:39,284] [com.boco.godu.adapter.filter.LoginEndFlagFilter,50] - [found login mark] 登录密码错误时连接过程的日志: [INFO] [2012-02-09 14:00:08,073] [com.boco.godu.app.server.TelnetServerHandler,71] - [[73:/10.32.1.6:56183] 管理模式,下发命令:cn id=1830753227] [DEBUG] [2012-02-09 14:00:08,077] [com.boco.godu.common.util.GlobalUtil,549] - [find platform at 0 times] [DEBUG] [2012-02-09 14:00:08,078] [com.boco.godu.common.util.GlobalUtil,549] - [find platform at 0 times] [INFO] [2012-02-09 14:00:08,078] [com.boco.godu.framework.command.CommandOpenne,984] - [NEID:1830753227;Channels:[channelID=52318885;channelName=FCGBSC7-5000端口;priority=0]] [INFO] [2012-02-09 14:00:08,079] [com.boco.godu.framework.command.CommandOpenne,759] - [get channelid:52318885] [INFO] [2012-02-09 14:00:08,079] [com.boco.godu.app.server.TelnetServerHandler,71] - [[73:/10.32.1.6:56183] FOUND ID:52318885] [DEBUG] [2012-02-09 14:00:08,080] [com.boco.godu.common.util.GlobalUtil,549] - [find platform at 0 times] [DEBUG] [2012-02-09 14:00:08,081] [com.boco.godu.app.server.TelnetServerHandler,56] - [[73:/10.32.1.6:56183] ==================PreOccupy the NE=================] [DEBUG] [2012-02-09 14:00:08,082] [com.boco.godu.app.server.TelnetServerHandler,56] - [[73:/10.32.1.6:56183] NEID:1830753227;;channelID=52318885;channelName=FCGBSC7-5000端口;priority=0] [INFO] [2012-02-09 14:00:08,082] [com.boco.godu.app.server.TelnetServerHandler,71] - [[73:/10.32.1.6:56183] 没有配置登录脚本] [INFO] [2012-02-09 14:00:08,083] [com.boco.godu.framework.ConnectorFactory,44] - [connecting ne with protocal:SSH] [INFO] [2012-02-09 14:00:08,083] [com.boco.godu.app.server.TelnetServerHandler,71] - [[73:/10.32.1.6:56183] Begin to Connection NE,10.0.2.47:22..............] [INFO] [2012-02-09 14:00:08,084] [com.boco.godu.app.server.TelnetServerHandler,71] - [[73:/10.32.1.6:56183] 开始连接网元awaitUninterruptibly127.0.0.1:8900] [DEBUG] [2012-02-09 14:00:08,085] [com.boco.godu.adapter.telnet.TelnetConnectorHandler,150] - [create session:10_0_2_47&&1234&&6&&79] [INFO] [2012-02-09 14:00:08,086] [com.boco.godu.adapter.ssh.SshHandler,71] - [[79:/127.0.0.1:8900] SessionOpen,add 10_0_2_47&&1234&&6&&79to sessionMap] [DEBUG] [2012-02-09 14:00:08,088] [org.apache.velocity.runtime.log.Log4JLogChute,168] - [ResourceManager : found com/boco/godu/adapter/proxy/internalPojo.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader] [INFO] [2012-02-09 14:00:08,098] [com.boco.godu.adapter.proxy.ssh.SshProxy,116] - [ssh client try to connect:10.0.2.47@22] [INFO] [2012-02-09 14:00:09,863] [com.boco.godu.adapter.ssh.SshHandler,71] - [[79:/127.0.0.1:8900] 关闭与网元的连接,返回到命令模式] [INFO] [2012-02-09 14:00:09,863] [com.boco.godu.adapter.ssh.SshHandler,71] - [[79:/127.0.0.1:8900] ====================Close NE routing information=======================10_0_2_47&&1234&&6&&79] [DEBUG] [2012-02-09 14:00:09,863] [com.boco.godu.common.util.GlobalUtil,549] - [find platform at 0 times] [WARN] [2012-02-09 14:00:15,595] [com.boco.godu.adapter.proxy.facade.ConnectorMonitorThread,25] - [proxy id=5d1d1a0b-aa1c-4a94-8a1d-3aade8327313 is not alive,destroy it]
二者比较可知,不同之处主要发生在ssh client try to connect:10.0.2.47@22
这行日志后,正常的连接过程后面是:SSH-2.0-OpenSSH_4.3-------------
,
如果密码错误则后面的日志是“ 关闭与网元的连接,返回到命令模式 ”。