求助-expect脚本在crontab中执行异常

bikkuri

大家好！
我有一个问题向大家请教。
我写了一个脚本，通过expect连到一些服务器上去执行一条命令，然后对命令的输出进行处理。
这个脚本的expect子程序如下：

1. run_sam_cmd()
   
2. {
   
3. samitem=$1
   
4. ip=$(echo "$sam"|awk -vs=$samitem '{if($1==s)print$2}')
   
5. user=$(echo "$sam"|awk -vs=$samitem '{if($1==s)print$3}')
   
6. pass=$(echo "$sam"|awk -vs=$samitem '{if($1==s)print$4}')
   
7. # cmd='[ $(grep -c samadmin /etc/passwd) -gt 0 ] && su - samadmin -c \"/opt/5620sam/server/nms/bin/nmsserver.bash nms_status\"'
   
8. cmd='if \[ `grep -c samadmin /etc/passwd` -gt 0 \] ; then su - samadmin -c \"/opt/5620sam/server/nms/bin/nmsserver.bash nms_status\"; fi'
   
9. [ -z $user ] && user="root"
   
10. [ -z $pass ] && pass="password"
    
11. sed -i "/$ip/d" ~/.ssh/known_hosts
    
12. expect -c "
    
13. set timeout 1000;
    
14. log_file /var/www/html/lab/sam.log
    
15. spawn /usr/bin/ssh -l $user $ip
    
16. 
    
17. expect {
    
18.        \"yes/no\" {send \"yes\r\"; exp_continue}
    
19.        \"assword\" {send \"$pass\r\";}
    
20.        }
    
21. 
    
22. expect {
    
23.         \"assword\" {send \"$user\r\";exp_continue}
    
24.         \"#\" {send \"\r\";}
    
25.         }
    
26. 
    
27. expect \"#\"
    
28. send \"date\r\"
    
29. 
    
30. expect \"#\"
    
31. send \"$cmd\r\"
    
32. 
    
33. expect \"#\"
    
34. send \"exit\r\r\"
    
35. "
    
36. }

复制代码

这个程序在命令行手工执行是没有问题的，可以得到正常的输出。
但是当我把它放在crontab里每个小时执行一次的时候，发现有的服务器可以正常得到命令的执行结果，但是有的服务器却得不到命令的执行结果。
检查log发现，远程服务器可以正常登录，但是在输入命令的时候，不知道为什么命令输到一半就被截断了，导致命令没有被正确执行。
以下是部分log内容：

1. spawn ssh -l root 135.240.146.161^M
   
2. The authenticity of host '135.240.146.161 (135.240.146.161)' can't be established.^M
   
3. RSA key fingerprint is bb:db:aa:19:04:26:3c:98:7a:16:33:9d:f5:38:5b:27.^M
   
4. Are you sure you want to continue connecting (yes/no)? yes^M
   
5. Warning: Permanently added '135.240.146.161' (RSA) to the list of known hosts.^M^M
   
6. root@135.240.146.161's password: ^M
   
7. Last login: Mon Apr 27 22:03:27 2015 from 135.240.146.162^M^M
   
8. [^[[1;31mroot^[[32m@hp7:^[[33m~^[[m]# ^M
   
9. [^[[1;31mroot^[[32m@hp7:^[[33m~^[[m]# if [ `grep -c samadmin /etc/passwd` -gt 0 ]^M<~^[[m]# if [ `grep -c samadmin /etc/passwd` -gt 0 ]                          ^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H; thspawn ssh -l root 135.240.146.162^M
   
10. The authenticity of host '135.240.146.162 (135.240.146.162)' can't be established.^M
    
11. RSA key fingerprint is 1f:73:58:91:44:db:02:7f:b2:4c:04:b7:a9:9e:fd:9e.^M
    
12. Are you sure you want to continue connecting (yes/no)? yes^M
    
13. Warning: Permanently added '135.240.146.162' (RSA) to the list of known hosts.^M^M
    
14. root@135.240.146.162's password: ^M
    
15. Last login: Mon Apr 27 22:03:29 2015 from 135.240.146.162^M^M
    
16. [^[[1;31mroot^[[32m@hp8:^[[33m~^[[m]# ^M
    
17. [^[[1;31mroot^[[32m@hp8:^[[33m~^[[m]# if [ `grep -c samadmin /etc/passwd` -gt 0 ]^M<~^[[m]# if [ `grep -c samadmin /etc/passwd` -gt 0 ]                          ^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H; t
    

复制代码

大家帮我分析一下为什么会出现这种情况呢？
这一大串的^H是怎么回事啊？
为什么只在crontab里会出现这种情况，在命令行手工执行就没问题呢？
作为对比，以下是在命令行手工执行expect脚本情况下的log文件内容：

1. spawn ssh -l root 135.240.146.161^M
   
2. The authenticity of host '135.240.146.161 (135.240.146.161)' can't be established.^M
   
3. RSA key fingerprint is bb:db:aa:19:04:26:3c:98:7a:16:33:9d:f5:38:5b:27.^M
   
4. Are you sure you want to continue connecting (yes/no)? yes^M
   
5. Warning: Permanently added '135.240.146.161' (RSA) to the list of known hosts.^M^M
   
6. root@135.240.146.161's password: ^M
   
7. Last login: Mon Apr 27 23:03:10 2015 from 135.240.146.162^M^M
   
8. ^[]0;root@hp7:~^G^[[?1034h[^[[1;31mroot^[[32m@hp7:^[[33m~^[[m]# ^M
   
9. ^[]0;root@hp7:~^G[^[[1;31mroot^[[32m@hp7:^[[33m~^[[m]# if [ `grep -c samadmin /etc/passwd` -gt 0 ] ; then su - samadmin -c "/opt/5620sam/server/nms/bin/nmsserver.bash nms_stat ^Mus" ; fi^M
   
10. 5620 Main Server Information^M
    
11. -- Build Information^M
    
12.   -- 5620 SAM Version 12.0.R5.0 - Built on Tue Sep 23 19:06:30 EDT 2014^M
    
13. -- System Information^M
    
14.   -- Host/IP: 135.240.146.161^M
    
15.   -- Startup time: Fri Apr 03 00:07:45 CST 2015^M
    
16.   -- Current time: Mon Apr 27 23:55:07 CST 2015^M
    
17.   -- Up time:      24d 23:47:21.833^M
    
18. ......
    
19. ^[]0;root@hp7:~^G[^[[1;31mroot^[[32m@hp7:^[[33m~^[[m]# spawn ssh -l root 135.240.146.162^M
    
20. The authenticity of host '135.240.146.162 (135.240.146.162)' can't be established.^M
    
21. RSA key fingerprint is 1f:73:58:91:44:db:02:7f:b2:4c:04:b7:a9:9e:fd:9e.^M
    
22. Are you sure you want to continue connecting (yes/no)? yes^M
    
23. Warning: Permanently added '135.240.146.162' (RSA) to the list of known hosts.^M^M
    
24. root@135.240.146.162's password: ^M
    
25. Last login: Mon Apr 27 23:03:12 2015 from 135.240.146.162^M^M
    
26. ^[]0;root@hp8:~^G^[[?1034h[^[[1;31mroot^[[32m@hp8:^[[33m~^[[m]# ^M
    
27. ^[]0;root@hp8:~^G[^[[1;31mroot^[[32m@hp8:^[[33m~^[[m]# if [ `grep -c samadmin /etc/passwd` -gt 0 ] ; then su - samadmin -c "/opt/5620sam/server/nms/bin/nmsserver.bash nms_stat ^Mus" ; fi^M
    
28. su: warning: cannot change directory to /opt/5620sam/auxserver: No such file or directory^M
    
29. -bash: /opt/5620sam/server/nms/bin/nmsserver.bash: No such file or directory^M
    
30. ^[]0;root@hp8:~^G[^[[1;31mroot^[[32m@hp8:^[[33m~^[[m]#
    

复制代码

可以看到在命令行手工执行expect脚本的情况下，命令可以正常被输入，并得到命令执行的结果。
为什么会有这种差异呢？
从两个log的对比中可以看出在手工运行时，命令被完整输入；而在crontab运行时，命令被一大帮不明来历的^H君截断了。

1. 
   
2. [box@ruizi:~/sam]$ grep -A2 date sam_cli.log
   
3. [root@cat1:/]#date^M
   
4. Tue Apr 28 12:07:59 BST 2015^M
   
5. [root@cat1:/]#if [ `grep -c samadmin /etc/passwd` -gt 0 ] ; then su - samadmin -c "/opt/5620sam/server/nms/bin/nmsserver.bash nms_status"; fi^M
   
6. --
   
7. [root@cat2:/]#date^M
   
8. Tue Apr 28 12:23:16 GMT 2015^M
   
9. [root@cat2:/]#if [ `grep -c samadmin /etc/passwd` -gt 0 ] ; then su - samadmin -c "/opt/5620sam/server/nms/bin/nmsserver.bash nms_status"; fi^M
   
10. --
    
11. [root@cat3:/]#date^M
    
12. Tue Apr 28 12:28:29 GMT 2015^M
    
13. [root@cat3:/]#if [ `grep -c samadmin /etc/passwd` -gt 0 ] ; then su - samadmin -c "/opt/5620sam/server/nms/bin/nmsserver.bash nms_status"; fi^M
    
14. --
    
15. [root@cat4:/]#date^M
    
16. Tue Apr 28 12:25:43 GMT 2015^M
    
17. [root@cat4:/]#if [ `grep -c samadmin /etc/passwd` -gt 0 ] ; then su - samadmin -c "/opt/5620sam/server/nms/bin/nmsserver.bash nms_status"; fi^M
    
18. --
    
19. ......
    
20. 
    
21. [box@ruizi:~/sam]$ grep -A2 date sam_crontab.log
    
22. [root@cat1:/]#date^M
    
23. Tue Apr 28 12:18:25 BST 2015^M
    
24. [root@cat1:/]#if [ `grep -c samadmin /etc/passwd` -gt 0 ] ; then su - samadmi^M< samadmin /etc/passwd` -gt 0 ] ; then su - samadmin                         ^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H -c "/opt/5620sam/server/^M< 0 ] ; then su - samadmin -c "/opt/5620sam/server/n                         ^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^Hms/bin/nmsserver.bash nms^M<-c "/opt/5620sam/server/nms/bin/nmsserver.bash nms_                         ^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^Hstatus"; fi^M
    
25. --
    
26. [root@cat2:/]#date^M
    
27. Tue Apr 28 12:33:43 GMT 2015^M
    
28. [root@cat2:/]#if [ `grep -c samadmin /etc/passwd` -gt 0 ] ; then su - samadmi^M< samadmin /etc/passwd` -gt 0 ] ; then su - samadmin                         ^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H -c "/opt/5620sam/server/^M< 0 ] ; then su - samadmin -c "/opt/5620sam/server/n                         ^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^Hms/bin/nmsserver.bash nms^M<-c "/opt/5620sam/server/nms/bin/nmsserver.bash nms_                         ^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^Hstatus"; fi^M
    
29. --
    
30. [root@cat3:/]#date^M
    
31. Tue Apr 28 12:38:55 GMT 2015^M
    
32. [root@cat3:/]#if [ `grep -c samadmin /etc/passwd` -gt 0 ] ; then su - samadmi^M< samadmin /etc/passwd` -gt 0 ] ; then su - samadmin                         ^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H -c "/opt/5620sam/server/^M< 0 ] ; then su - samadmin -c "/opt/5620sam/server/n                         ^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^Hms/bin/nmsserver.bash nms^M<-c "/opt/5620sam/server/nms/bin/nmsserver.bash nms_                         ^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^Hstatus"; fi^M
    
33. --
    
34. [root@cat4:/]#date^M
    
35. Tue Apr 28 12:36:09 GMT 2015^M
    
36. [root@cat4:/]#if [ `grep -c samadmin /etc/passwd` -gt 0 ] ; then su - samadmi^M< samadmin /etc/passwd` -gt 0 ] ; then su - samadmin                         ^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H -c "/opt/5620sam/server/^M< 0 ] ; then su - samadmin -c "/opt/5620sam/server/n                         ^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^Hms/bin/nmsserver.bash nms^M<-c "/opt/5620sam/server/nms/bin/nmsserver.bash nms_                         ^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^H^Hstatus"; fi^M
    
37. --
    
38. ......
    

复制代码

附件是crontab运行时以及手工执行时的expect的完整log，请帮我分析一下。
samlog.zip (14.79 KB, 下载次数: 31)

2015-04-28 20:33 上传

点击文件名下载附件

1. [box@ruizi:~/sam]$ l
   
2. 总用量 124
   
3. -rw-r--r-- 1 box box 73413  4月 28 20:17 sam_cli.log
   
4. -rw-r--r-- 1 box box 50851  4月 28 20:28 sam_crontab.log
   
5. [box@ruizi:~/sam]$ wc *
   
6.   1853  11148  73413 sam_cli.log
   
7.   1223   7456  50851 sam_crontab.log
   
8.   3076  18604 124264 总用量
   
9. [box@ruizi:~/sam]$ file *
   
10. sam_cli.log:     UTF-8 Unicode English text, with CRLF, CR, LF line terminators, with escape sequences
    
11. sam_crontab.log: UTF-8 Unicode English text, with CRLF, CR, LF line terminators, with escape sequences, with overstriking
    
12. [box@ruizi:~/sam]$
    

复制代码

谢谢。

waker

估计还是环境的问题
你可以在命令行和cron分别set对比一下，看看是不是一样，特别是TERM COLUMNS等变量
或者直接在expect里面加 stty -echo 就可以眼不见为净了

bikkuri

等了一天了。。。没有哪位小伙伴帮忙分析一下吗？

Herowinter

不会啊,30分溜走了, 呼叫帽子哥.@Shell_HAT

bikkuri

唉，没人回直接沉了啊。。。
哪位大神指点一下啊。。。
这个问题困扰很长时间了。。。

ly5066113

回复 1# bikkuri


手工可以，crontab 不行，基本就是环境变量的问题。
在执行命令前，先加载用户的环境变量
. /home/username/.bash_profile

bikkuri

谢谢您的指点。
我在脚本的最开始加了source /root/.bash_profile然后再让crontab执行，结果还是和原来一模一样，没有什么变化啊。
还是有大群的^H君出现，截断了要远程执行的命令，有的服务器收到截断的命令也能正常执行出结果，有的服务器就不行，所以我也不确定是^H君惹的祸。
但是为什么在crontab环境执行的时候会出现这样一群不明来历的^H呢？

回复 5# ly5066113


   

bikkuri

谢谢您的指点。
虽然还是不明白是怎么回事，
但是按照您说的方法，
在expect中加上stty -echo以后，
要执行的命令消失了......^H君也消失了......
但是没被显示的命令执行后却正常地得到了输出结果！
我查了一下stty -echo的作用是：设置命令不被显示
为什么命令不被显示就把问题给解决了呢？
为什么在crontab里会出这样的幺蛾子呢？
百思不得其解啊......
不过大神就是大神啊，我费了那么大劲测试各种可能性，分析各种日志，也没找到解决办法，
大神随便支一招，轻描淡写地就把问题给解决了啊......膜拜啊......五体投地啊......
麻烦大神帮忙解释一下呗？

回复 2# waker


   

waker

从你上面的描述我没看出什么地方出错了，只是回显比较乱，你比较下命令行和cron里的环境变量吧，特别是TERM CLUMNS这些和显示相关的环境变量

bikkuri

谢谢您的指点。
以下是命令行和crontab里的环境变量的对比：

1. env output in crontab:
   
2. SHELL=/bin/sh
   
3. USER=root
   
4. PATH=/usr/bin:/bin
   
5. _=/usr/bin/env
   
6. PWD=/root
   
7. HOME=/root
   
8. SHLVL=2
   
9. LOGNAME=root
   
10. 
    
11. env output in cli:
    
12. HOSTNAME=hp8
    
13. TERM=xterm
    
14. SHELL=/bin/bash
    
15. HISTSIZE=1000
    
16. SSH_CLIENT=135.240.144.46 51235 22
    
17. QTDIR=/usr/lib64/qt-3.3
    
18. QTINC=/usr/lib64/qt-3.3/include
    
19. SSH_TTY=/dev/pts/1
    
20. USER=root
    
21. LS_COLORS=rs=0:di=01;34:ln=01;36:mh=00:pi=40;33:so=01;35:do=01;35:bd=40;33;01:cd=40;33;01:or=40;31;01:mi=01;05;37;41:su=37;41:sg=30;43:ca=30;41:tw=30;42:o
    
22. w=34;42:st=37;44:ex=01;32:*.tar=01;31:*.tgz=01;31:*.arj=01;31:*.taz=01;31:*.lzh=01;31:*.lzma=01;31:*.tlz=01;31:*.txz=01;31:*.zip=01;31:*.z=01;31:*.Z=01;31
    
23. :*.dz=01;31:*.gz=01;31:*.lz=01;31:*.xz=01;31:*.bz2=01;31:*.tbz=01;31:*.tbz2=01;31:*.bz=01;31:*.tz=01;31:*.deb=01;31:*.rpm=01;31:*.jar=01;31:*.rar=01;31:*.
    
24. ace=01;31:*.zoo=01;31:*.cpio=01;31:*.7z=01;31:*.rz=01;31:*.jpg=01;35:*.jpeg=01;35:*.gif=01;35:*.bmp=01;35:*.pbm=01;35:*.pgm=01;35:*.ppm=01;35:*.tga=01;35:
    
25. *.xbm=01;35:*.xpm=01;35:*.tif=01;35:*.tiff=01;35:*.png=01;35:*.svg=01;35:*.svgz=01;35:*.mng=01;35:*.pcx=01;35:*.mov=01;35:*.mpg=01;35:*.mpeg=01;35:*.m2v=0
    
26. 1;35:*.mkv=01;35:*.ogm=01;35:*.mp4=01;35:*.m4v=01;35:*.mp4v=01;35:*.vob=01;35:*.qt=01;35:*.nuv=01;35:*.wmv=01;35:*.asf=01;35:*.rm=01;35:*.rmvb=01;35:*.flc
    
27. =01;35:*.avi=01;35:*.fli=01;35:*.flv=01;35:*.gl=01;35:*.dl=01;35:*.xcf=01;35:*.xwd=01;35:*.yuv=01;35:*.cgm=01;35:*.emf=01;35:*.axv=01;35:*.anx=01;35:*.ogv
    
28. =01;35:*.ogx=01;35:*.aac=01;36:*.au=01;36:*.flac=01;36:*.mid=01;36:*.midi=01;36:*.mka=01;36:*.mp3=01;36:*.mpc=01;36:*.ogg=01;36:*.ra=01;36:*.wav=01;36:*.a
    
29. xa=01;36:*.oga=01;36:*.spx=01;36:*.xspf=01;36:
    
30. MAIL=/var/spool/mail/root
    
31. PATH=/usr/lib64/qt-3.3/bin:/usr/local/sbin:/usr/local/bin:/sbin:/bin:/usr/sbin:/usr/bin:/root/bin:/usr/local/python3.4.2/bin:/root/bin:/usr/local/python3.
    
32. 4.2/bin
    
33. PWD=/var/www/html/lab
    
34. LANG=en_US.UTF-8
    
35. KDE_IS_PRELINKED=1
    
36. MODULEPATH=/usr/share/Modules/modulefiles:/etc/modulefiles
    
37. LOADEDMODULES=
    
38. KDEDIRS=/usr
    
39. TZ=Asia/Shanghai
    
40. SSH_ASKPASS=/usr/libexec/openssh/gnome-ssh-askpass
    
41. HISTCONTROL=ignoredups
    
42. SHLVL=1
    
43. HOME=/root
    
44. LOGNAME=root
    
45. QTLIB=/usr/lib64/qt-3.3/lib
    
46. CVS_RSH=ssh
    
47. SSH_CONNECTION=135.240.144.46 51235 135.240.146.162 22
    
48. MODULESHOME=/usr/share/Modules
    
49. LESSOPEN=|/usr/bin/lesspipe.sh %s
    
50. DISPLAY=localhost:10.0
    
51. HISTTIMEFORMAT=%F_%T
    
52. G_BROKEN_FILENAMES=1
    
53. module=() {  eval `/usr/bin/modulecmd bash $*`
    
54. }
    
55. _=/bin/env
    
56. OLDPWD=/root/ver
    

复制代码

那到底是什么导致了^H君的出现呢？
是因为缺少了TERM=xterm吗？

waker 发表于 2015-05-01 17:44
从你上面的描述我没看出什么地方出错了，只是回显比较乱，你比较下命令行和cron里的环境变量吧，特别是TERM ...