[hobbit] problem with lines above 256 char. in log files

Dominique Frise dominique.frise at unil.ch
Wed Dec 3 11:39:03 CET 2008


Henrik Størner wrote:
> In <493641BA.3080508 at unil.ch> Dominique Frise <dominique.frise at unil.ch> writes:
> 
>> Using 4.2.0 and all-in-one-patch.
> 
>> Lines above 256 characters in log files are not parsed correctly and 
>> truncated.
> 
> 
> Could you check if the "client data" reported from the client also has 
> the lines truncated ? I cannot see anything that would cause the data
> to be truncated, so for a start it would be nice to know if it happens
> on the client or on the server.
> 

Here your are...

client gollum.linux linux
[date]
Wed Dec  3 11:36:30 CET 2008
[uname]
Linux gollum 2.6.11.4-21.7-smp x86_64
[osversion]
SuSE Linux 9.3 (x86-64)
[uptime]
  11:36am  up 18 days 23:27,  3 users,  load average: 18.00, 18.01, 18.00
[who]
super    pts/0        Dec  3 08:36 (iris.unil.ch)
bb       pts/1        Dec  3 11:16 (iris.unil.ch)
super    pts/2        Nov 27 10:24 (iris.unil.ch)
[df]
Filesystem         1024-blocks      Used Available Capacity Mounted on
/dev/sda2             70619536   4508880  66110656       7% /
[mount]
/dev/sda2 on / type reiserfs (rw,acl,user_xattr)
proc on /proc type proc (rw)
sysfs on /sys type sysfs (rw)
tmpfs on /dev/shm type tmpfs (rw)
devpts on /dev/pts type devpts (rw,mode=0620,gid=5)
usbfs on /proc/bus/usb type usbfs (rw)
[free]
              total       used       free     shared    buffers     cached
Mem:       8123764    2114432    6009332          0     147872     391028
-/+ buffers/cache:    1575532    6548232
Swap:      1052216          0    1052216
[ifconfig]
eth0      Link encap:Ethernet  HWaddr 00:09:3D:14:98:39
           inet addr:130.223.5.103  Bcast:130.223.5.255  Mask:255.255.255.0
           inet6 addr: fe80::209:3dff:fe14:9839/64 Scope:Link
           UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
           RX packets:3359661 errors:0 dropped:0 overruns:0 frame:0
           TX packets:2726496 errors:0 dropped:0 overruns:0 carrier:0
           collisions:0 txqueuelen:1000
           RX bytes:574154173 (547.5 Mb)  TX bytes:899280806 (857.6 Mb)
           Interrupt:185

lo        Link encap:Local Loopback
           inet addr:127.0.0.1  Mask:255.0.0.0
           inet6 addr: ::1/128 Scope:Host
           UP LOOPBACK RUNNING  MTU:16436  Metric:1
           RX packets:4571321 errors:0 dropped:0 overruns:0 frame:0
           TX packets:4571321 errors:0 dropped:0 overruns:0 carrier:0
           collisions:0 txqueuelen:0
           RX bytes:1287162379 (1227.5 Mb)  TX bytes:1287162379 (1227.5 Mb)

[route]
Kernel IP routing table
Destination     Gateway         Genmask         Flags   MSS Window  irtt 
Iface
130.223.5.0     0.0.0.0         255.255.255.0   U         0 0          0 
eth0
169.254.0.0     0.0.0.0         255.255.0.0     U         0 0          0 
eth0
127.0.0.0       0.0.0.0         255.0.0.0       U         0 0          0 lo
0.0.0.0         130.223.5.1     0.0.0.0         UG        0 0          0 
eth0
[netstat]
Ip:
     7335705 total packets received
     0 forwarded
     0 incoming packets discarded
     7335705 incoming packets delivered
     7290871 requests sent out
Icmp:
     6756 ICMP messages received
     0 input ICMP message failed.
     ICMP input histogram:
         destination unreachable: 1300
         echo requests: 5456
     5457 ICMP messages sent
     0 ICMP messages failed
     ICMP output histogram:
         destination unreachable: 1
         echo replies: 5456
Tcp:
     7988 active connections openings
     2303 passive connection openings
     0 failed connection attempts
     44 connection resets received
     5 connections established
     7132445 segments received
     7264576 segments send out
     171 segments retransmited
     0 bad segments received.
     20 resets sent
Udp:
     21981 packets received
     0 packets to unknown port received.
     0 packet receive errors
     6766 packets sent
TcpExt:
     88 packets pruned from receive queue because of socket buffer overrun
     ArpFilter: 0
     8238 TCP sockets finished time wait in fast timer
     172142 delayed acks sent
     79 delayed acks further delayed because of locked socket
     Quick ack mode was activated 192 times
     759 packets directly queued to recvmsg prequeue.
     10240 packets directly received from backlog
     132240 packets directly received from prequeue
     4275720 packets header predicted
     307 packets header predicted and directly queued to user
     TCPPureAcks: 348241
     TCPHPAcks: 3594182
     TCPRenoRecovery: 0
     TCPSackRecovery: 30
     TCPSACKReneging: 0
     TCPFACKReorder: 0
     TCPSACKReorder: 0
     TCPRenoReorder: 0
     TCPTSReorder: 0
     TCPFullUndo: 0
     TCPPartialUndo: 0
     TCPDSACKUndo: 0
     TCPLossUndo: 0
     TCPLoss: 60
     TCPLostRetransmit: 0
     TCPRenoFailures: 0
     TCPSackFailures: 0
     TCPLossFailures: 0
     TCPFastRetrans: 126
     TCPForwardRetrans: 4
     TCPSlowStartRetrans: 18
     TCPTimeouts: 18
     TCPRenoRecoveryFail: 0
     TCPSackRecoveryFail: 1
     TCPSchedulerFailed: 0
     TCPRcvCollapsed: 2051
     TCPDSACKOldSent: 192
     TCPDSACKOfoSent: 0
     TCPDSACKRecv: 0
     TCPDSACKOfoRecv: 0
     TCPAbortOnSyn: 0
     TCPAbortOnData: 20
     TCPAbortOnClose: 2
     TCPAbortOnMemory: 0
     TCPAbortOnTimeout: 0
     TCPAbortOnLinger: 0
     TCPAbortFailed: 0
     TCPMemoryPressures: 0
[ports]
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address           Foreign Address 
State
tcp        0      0 0.0.0.0:7937            0.0.0.0:* 
LISTEN
tcp        0      0 0.0.0.0:7938            0.0.0.0:* 
LISTEN
tcp        0      0 0.0.0.0:32777           0.0.0.0:* 
LISTEN
tcp        0      0 127.0.0.1:937           0.0.0.0:* 
LISTEN
tcp        0      0 0.0.0.0:8361            0.0.0.0:* 
LISTEN
tcp        0      0 0.0.0.0:32778           0.0.0.0:* 
LISTEN
tcp        0      0 0.0.0.0:111             0.0.0.0:* 
LISTEN
tcp        0      0 130.223.5.103:50001     0.0.0.0:* 
LISTEN
tcp        0      0 0.0.0.0:50004           0.0.0.0:* 
LISTEN
tcp        0      0 0.0.0.0:9110            0.0.0.0:* 
LISTEN
tcp        0      0 0.0.0.0:631             0.0.0.0:* 
LISTEN
tcp        0      0 0.0.0.0:11576           0.0.0.0:* 
LISTEN
tcp        0      0 127.0.0.1:25            0.0.0.0:* 
LISTEN
tcp        0      0 127.0.0.1:6010          0.0.0.0:* 
LISTEN
tcp        0      0 127.0.0.1:6011          0.0.0.0:* 
LISTEN
tcp        0      0 127.0.0.1:6012          0.0.0.0:* 
LISTEN
tcp        0      0 0.0.0.0:11580           0.0.0.0:* 
LISTEN
tcp        0      0 0.0.0.0:11581           0.0.0.0:* 
LISTEN
tcp        0      0 127.0.0.1:6012          127.0.0.1:34115 
ESTABLISHED
tcp        0      0 127.0.0.1:34115         127.0.0.1:6012 
ESTABLISHED
tcp        0      0 :::22                   :::* 
LISTEN
tcp        0      0 ::1:25                  :::* 
LISTEN
tcp        0      0 ::1:6010                :::* 
LISTEN
tcp        0      0 ::1:6011                :::* 
LISTEN
tcp        0      0 ::1:6012                :::* 
LISTEN
tcp        0      0 130.223.5.103:22        130.223.27.23:33119 
ESTABLISHED
tcp        0      0 130.223.5.103:22        130.223.27.23:33115 
ESTABLISHED
tcp        0      0 130.223.5.103:22        130.223.27.23:60681 
ESTABLISHED
[ifstat]
eth0      Link encap:Ethernet  HWaddr 00:09:3D:14:98:39
           inet addr:130.223.5.103  Bcast:130.223.5.255  Mask:255.255.255.0
           inet6 addr: fe80::209:3dff:fe14:9839/64 Scope:Link
           UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
           RX packets:3359661 errors:0 dropped:0 overruns:0 frame:0
           TX packets:2726496 errors:0 dropped:0 overruns:0 carrier:0
           collisions:0 txqueuelen:1000
           RX bytes:574154173 (547.5 Mb)  TX bytes:899280806 (857.6 Mb)
           Interrupt:185

lo        Link encap:Local Loopback
           inet addr:127.0.0.1  Mask:255.0.0.0
           inet6 addr: ::1/128 Scope:Host
           UP LOOPBACK RUNNING  MTU:16436  Metric:1
           RX packets:4571321 errors:0 dropped:0 overruns:0 frame:0
           TX packets:4571321 errors:0 dropped:0 overruns:0 carrier:0
           collisions:0 txqueuelen:0
           RX bytes:1287162379 (1227.5 Mb)  TX bytes:1287162379 (1227.5 Mb)

[ps]
   PID  PPID USER      STARTED S PRI %CPU     TIME %MEM   RSZ    VSZ CMD
     1     0 root       Nov 14 S  23  0.0 00:00:01  0.0   256    724 
init [3]
     2     1 root       Nov 14 S 139  0.0 00:00:00  0.0     0      0 
[migration/0]
     3     1 root       Nov 14 S   5  0.0 00:00:00  0.0     0      0 
[ksoftirqd/0]
     4     1 root       Nov 14 S 139  0.0 00:00:00  0.0     0      0 
[migration/1]
     5     1 root       Nov 14 S   5  0.0 00:00:00  0.0     0      0 
[ksoftirqd/1]
     6     1 root       Nov 14 S  29  0.0 00:00:00  0.0     0      0 
[events/0]
     7     1 root       Nov 14 S  29  0.0 00:00:00  0.0     0      0 
[events/1]
     8     1 root       Nov 14 S  29  0.0 00:00:00  0.0     0      0 
[khelper]
    13     1 root       Nov 14 S  29  0.0 00:00:00  0.0     0      0 
[kthread]
    27    13 root       Nov 14 S  28  0.0 00:00:00  0.0     0      0 
[kacpid]
   130    13 root       Nov 14 S  29  0.0 00:00:00  0.0     0      0 
[kblockd/0]
   131    13 root       Nov 14 S  29  0.0 00:00:00  0.0     0      0 
[kblockd/1]
   180    13 root       Nov 14 S  24  0.0 00:00:00  0.0     0      0 
[pdflush]
   181    13 root       Nov 14 S  24  0.0 00:00:52  0.0     0      0 
[pdflush]
   183     1 root       Nov 14 S  23  0.0 00:00:00  0.0     0      0 
[kswapd0]
   182     1 root       Nov 14 S  24  0.0 00:00:00  0.0     0      0 
[kswapd1]
   184    13 root       Nov 14 S  28  0.0 00:00:00  0.0     0      0 [aio/0]
   185    13 root       Nov 14 S  28  0.0 00:00:00  0.0     0      0 [aio/1]
   773     1 root       Nov 14 S  24  0.0 00:00:00  0.0     0      0 
[kseriod]
   957     1 root       Nov 14 S  20  0.0 00:00:00  0.0     0      0 
[scsi_eh_0]
  1082    13 root       Nov 14 S  29  0.0 00:00:03  0.0     0      0 
[reiserfs/0]
  1083    13 root       Nov 14 S  29  0.0 00:00:02  0.0     0      0 
[reiserfs/1]
  2189     1 root       Nov 14 S  25  0.0 00:00:00  0.0   656   2552 
/sbin/udevd -d
  2238     1 root       Nov 14 S  20  0.0 00:00:00  0.0     0      0 
[khpsbpkt]
  3140     1 root       Nov 14 S  24  0.0 00:00:00  0.0     0      0 [khubd]
  4535     1 root       Nov 14 S  26  0.0 00:00:00  0.0   508   2596 
[hwscand]
  4717     1 root       Nov 14 S  23  0.0 00:00:01  0.0   512   2552 
/usr/sbin/irqbalance
  4994     1 100        Nov 14 S  23  0.0 00:00:00  0.0  1596  14992 
/usr/bin/dbus-daemon-1 --system
  5172     1 root       Nov 14 S  21  0.0 00:00:00  0.0   540   3608 
/sbin/resmgrd
  5683     1 nobody     Nov 14 S  23  0.0 00:00:00  0.0  1172   7272 
/usr/sbin/mdnsd -f /etc/rendezvous.conf -b
  5693     1 root       Nov 14 S  23  0.0 00:00:02  0.0   772   2816 
/sbin/klogd -c 1 -x -x
  5700     1 root       Nov 14 S  23  0.0 00:00:03  0.0  1012   9260 
/sbin/syslog-ng
  5718     1 nobody     Nov 14 S  23  0.0 00:00:00  0.0   552   4636 
/sbin/portmap
  5741     1 root       Nov 14 S  23  0.0 00:00:28  0.0   684   6540 
/opt/gnome/bin/netdaemon
  5851     1 root       Nov 14 S  23  0.0 00:00:00  0.0  2952  58000 
/usr/sbin/nsrexecd
  5917     1 lp         Nov 14 S  23  0.0 00:00:02  0.0  4312  24400 
/usr/sbin/cupsd
  5938     1 root       Nov 14 S  23  0.0 00:00:02  0.0  2336  28624 
/usr/sbin/sshd -o PidFile=/var/run/sshd.init.pid
  5976     1 root       Nov 14 S  23  0.0 00:00:00  0.0   584   2556 
/usr/sbin/acpid -c /etc/acpi/events.ignore
  6031     1 root       Nov 14 S  24  0.0 00:00:15  0.0  1576  15532 
/usr/sbin/powersaved -d -x /usr/lib/powersave/scripts -a resmgr -v 3
  6253     1 ntp        Nov 14 S  23  0.0 00:00:00  0.0  3316   7392 
/usr/sbin/ntpd -p /var/lib/ntp/var/run/ntp/ntpd.pid -u ntp -i /var/lib/ntp
  6255     1 root       Nov 14 S  23  0.0 00:00:04  0.0  1176  24172 
/usr/sbin/nscd
  6273     1 root       Nov 14 S  23  0.0 00:00:00  0.0  1336  11548 
/usr/sbin/fam -t 4 -T 0 -L
  6411     1 root       Nov 14 S  20  0.0 00:00:00  0.0     0      0 
[scsi_eh_1]
  6412     1 root       Nov 14 S  23  0.0 00:00:00  0.0  1852  18496 
/usr/lib/postfix/master
  6424  6412 postfix    Nov 14 S  23  0.0 00:00:00  0.0  1804  17524 
qmgr -l -t fifo -u
  6429     1 root       Nov 14 S  39  0.0 00:00:00  0.0     0      0 
[qla2300_100_dpc]
  6739     1 root       Nov 14 S  20  0.0 00:00:00  0.0     0      0 
[scsi_eh_2]
  6740     1 root       Nov 14 S  39  0.0 00:00:00  0.0     0      0 
[qla2300_101_dpc]
  6756     1 root       Nov 14 S  20  0.0 00:00:00  0.0     0      0 
[scsi_eh_3]
  6757     1 root       Nov 14 S  39  0.0 00:00:00  0.0     0      0 
[qla2300_102_dpc]
  7067     1 root       Nov 14 S  20  0.0 00:00:00  0.0     0      0 
[scsi_eh_4]
  7068     1 root       Nov 14 S  39  0.0 00:00:00  0.0     0      0 
[qla2300_103_dpc]
  7084     1 root       Nov 14 S  20  0.0 00:00:00  0.0     0      0 
[scsi_eh_5]
  7085     1 root       Nov 14 S  39  0.0 00:00:00  0.0     0      0 
[qla2300_104_dpc]
  7238     1 root       Nov 14 S  20  0.0 00:00:00  0.0     0      0 
[scsi_eh_6]
  7239     1 root       Nov 14 S  39  0.0 00:00:00  0.0     0      0 
[qla2300_105_dpc]
  7423     1 root       Nov 14 S  24  0.0 00:00:27  0.0  5308   9920 
/usr/local/vtl/bin/snmpd
  7442     1 root       Nov 14 S  24  0.0 00:00:00  0.0  1324  14696 
/usr/local/vtl/bin/sanidmgr
  7504     1 root       Nov 14 S  24  0.1 00:49:12  0.0     0      0 
[iocore]
  7540     1 root       Nov 14 S  24  0.0 00:00:18  0.0   836   5196 
fsnupd -d 0
  7560     1 root       Nov 14 S  24  0.0 00:00:00  0.0     0      0 
[trudpd]
  7570     1 root       Nov 14 D  24  0.0 00:00:00  0.0     0      0 
[fsnalias]
  7572     1 root       Nov 14 D  24  0.0 00:00:00  0.0     0      0 
[fsnalias]
  7583     1 root       Nov 14 D  20  0.0 00:00:00  0.0     0      0 
[ipstord]
  7604     1 root       Nov 14 S  24  0.0 00:00:00  0.0     0      0 
[fctgtsched]
  7605     1 root       Nov 14 S  39  0.0 00:00:00  0.0     0      0 
[fstgt_disp_rq]
  7620     1 root       Nov 14 D  24  0.0 00:00:00  0.0     0      0 
[vtl_tm]
  7637     1 root       Nov 14 D  24  0.0 00:00:00  0.0     0      0 
[lm_upcall]
  7650     1 root       Nov 14 D  22  0.0 00:00:01  0.0  1300   3648 
tleupcall
  7685     1 root       Nov 14 S  24  0.0 00:00:00  0.0     0      0 
[vtl_pendcmd_chk]
  7687     1 root       Nov 14 S  24  0.0 00:00:00  0.0     0      0 
[vtl_jd]
  7688  7650 root       Nov 14 S  23  0.0 00:00:00  0.0   520   1608 
/usr/local/vtl/bin/mini_el -l /usr/local/vtl/log/
  7690  7650 root       Nov 14 S  24  0.0 00:00:00  0.0   708   1804 
/usr/local/vtl/bin/ssi 7689 50004 23 130.223.5.19 ssiinetport=0 
csihostport=0
  7691     1 root       Nov 14 D  24  0.0 00:00:00  0.0     0      0 
[vtl_rae_275]
  7692     1 root       Nov 14 D  24  0.0 00:00:35  0.0     0      0 
[vtl_tde_276]
  7693     1 root       Nov 14 D  24  0.0 00:00:34  0.0     0      0 
[vtl_tde_277]
  7694     1 root       Nov 14 D  24  0.0 00:01:02  0.0     0      0 
[vtl_tde_278]
  7695     1 root       Nov 14 D  24  0.0 00:00:00  0.0     0      0 
[vtl_rae_327]
  7696     1 root       Nov 14 D  24  0.0 00:00:40  0.0     0      0 
[vtl_tde_328]
  7697     1 root       Nov 14 D  24  0.0 00:00:52  0.0     0      0 
[vtl_tde_329]
  7698     1 root       Nov 14 D  24  0.0 00:00:26  0.0     0      0 
[vtl_tde_454]
  7699     1 root       Nov 14 D  24  0.0 00:01:12  0.0     0      0 
[vtl_tde_455]
  7715     1 root       Nov 14 S  21  0.0 00:00:00  0.0  3812  10584 
/usr/local/vtl/bin/ipstorcomm -d 0
  7716  7715 root       Nov 14 S  24  0.0 00:12:35  0.2 21228  53180 
/usr/local/vtl/bin/ipstorcomm -d 0
  7717  7716 root       Nov 14 S  23  0.0 00:00:00  0.0  3972  10588 
/usr/local/vtl/bin/ipstorcomm -d 0
  7726     1 root       Nov 14 S  24  0.0 00:00:00  0.0   884   3352 
/usr/local/vtl/bin/isclntproxy
  7744     1 root       Nov 14 S  22  0.0 00:17:10  0.0  3368   5976 
/usr/local/vtl/bin//ipstorlog
  7763     1 root       Nov 14 S  23  0.5 02:17:11  0.0  4344   7808 
/usr/local/vtl/bin/ipstorsm
  7776     1 root       Nov 14 S  23  0.0 00:00:00  0.0   672   5908 
/usr/sbin/cron
  7786     1 root       Nov 14 S  23  0.0 00:03:54  0.0  2676  14564 
/usr/sbin/hald --daemon=yes
  7890     1 root       Nov 14 S  22  0.0 00:00:00  0.0   676   2992 
/sbin/mingetty --noclear tty1
  7891     1 root       Nov 14 S  22  0.0 00:00:00  0.0   676   2992 
/sbin/mingetty tty2
  7892     1 root       Nov 14 S  22  0.0 00:00:00  0.0   676   2992 
/sbin/mingetty tty3
  7893     1 root       Nov 14 S  22  0.0 00:00:00  0.0   676   2992 
/sbin/mingetty tty4
  7894     1 root       Nov 14 S  21  0.0 00:00:00  0.0   676   2992 
/sbin/mingetty tty5
  7895     1 root       Nov 14 S  17  0.0 00:00:00  0.0   676   2992 
/sbin/mingetty tty6
  7897     1 root       Nov 14 S  23  0.0 00:00:00  0.0   536   2556 
/sbin/agetty -L 9600 ttyS0 vt102
14372  5938 root       Nov 27 S  23  0.0 00:00:45  0.0  3096  39816 
sshd: super at pts/2
14375 14372 root       Nov 27 S  24  0.0 00:00:00  0.0  2240   9788 -bash
14404 14375 root       Nov 27 S  21  0.0 00:00:00  0.0  1496   9172 
/bin/sh /usr/local/vtlconsole/vtlconsole
14405 14404 root       Nov 27 S  24  0.0 00:05:33  2.8 235496 1294416 
java -classpath 
/usr/local/vtlconsole/xerces.jar:/usr/local/vtlconsole/activation.jar:/usr/local/vtlconsole/mailapi.jar:/usr/local/vtlconsole/smtp.jar:/usr/local/vtlconsole/jcommon-0.7.3.jar:/usr/local/vtlconsole/jfreechart-0.9.7.jar:/usr/local/vtlconsole/IPStorConsole.jar 
com.falconstor.ipsan.console.IPStorConsole
14864     1 root       Nov 27 D  24  0.0 00:00:00  0.0     0      0 
[vtl_rae_649]
14865     1 root       Nov 27 D  24  0.0 00:00:03  0.0     0      0 
[vtl_tde_650]
14866     1 root       Nov 27 D  24  0.0 00:00:02  0.0     0      0 
[vtl_tde_651]
21088  5938 root     08:36:41 S  23  0.0 00:00:00  0.0  2784  39484 
sshd: super at pts/0
21092 21088 root     08:36:46 S  23  0.0 00:00:00  0.0  2260   9788 -bash
23560  6412 postfix  11:11:34 S  23  0.0 00:00:00  0.0  1776  17480 
pickup -l -t fifo -u
23667  5938 root     11:16:57 S  23  0.0 00:00:00  0.0  2776  42540 
sshd: bb [priv]
23669 23667 bb       11:16:58 S  23  0.0 00:00:00  0.0  2916  42672 
sshd: bb at pts/1
23670 23669 bb       11:16:58 S  22  0.0 00:00:00  0.0  2244  10044 -bash
24216     1 bb       11:34:57 S  21  0.0 00:00:00  0.0  1348   8136 sh 
-c vmstat 300 2 
1>/soft/pub/BB/hobbit/client/tmp/hobbit_vmstat.gollum.24196 2>&1; mv 
/soft/pub/BB/hobbit/client/tmp/hobbit_vmstat.gollum.24196 
/soft/pub/BB/hobbit/client/tmp/hobbit_vmstat.gollum
24218 24216 bb       11:34:57 S  21  0.0 00:00:00  0.0   568   2572 
vmstat 300 2
24288     1 bb       11:36:30 S  21  0.0 00:00:00  0.0   508   1448 
/soft/pub/BB/hobbit/client/bin/hobbitlaunch 
--config=/soft/pub/BB/hobbit/client/etc/clientlaunch.cfg 
--log=/soft/pub/BB/hobbit/client/logs/clientlaunch.log 
--pidfile=/soft/pub/BB/hobbit/client/logs/clientlaunch.gollum.pid
24289 24288 bb       11:36:30 S  22  0.0 00:00:00  0.0  1564   9436 
/bin/sh /soft/pub/BB/hobbit/client/bin/hobbitclient.sh
24295 24289 bb       11:36:30 S  22  0.0 00:00:00  0.0  1372   8140 
/bin/sh /soft/pub/BB/hobbit/client/bin/hobbitclient-linux.sh
24313 24295 bb       11:36:30 R  23  0.0 00:00:00  0.0   784   3560 ps 
-Aw -o pid,ppid,user,start,state,pri,pcpu,time,pmem,rsz,vsz,cmd
[top]
top - 11:36:31 up 18 days, 23:27,  3 users,  load average: 18.00, 18.01, 
18.00
Tasks: 116 total,   1 running, 115 sleeping,   0 stopped,   0 zombie
Cpu(s):  0.2% us,  0.4% sy,  0.0% ni, 98.7% id,  0.7% wa,  0.0% hi,  0.0% si
Mem:   8123764k total,  2114496k used,  6009268k free,   147872k buffers
Swap:  1052216k total,        0k used,  1052216k free,   391028k cached

   PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM    TIME+  COMMAND 

24314 bb        15   0  4280 1100  796 R  1.0  0.0   0:00.01 top 

     1 root      16   0   724  256  216 S  0.0  0.0   0:01.60 init 

     2 root      RT   0     0    0    0 S  0.0  0.0   0:00.70 
migration/0
     3 root      34  19     0    0    0 S  0.0  0.0   0:00.00 
ksoftirqd/0
     4 root      RT   0     0    0    0 S  0.0  0.0   0:00.51 
migration/1
     5 root      34  19     0    0    0 S  0.0  0.0   0:00.00 
ksoftirqd/1
     6 root      10  -5     0    0    0 S  0.0  0.0   0:00.00 events/0 

     7 root      10  -5     0    0    0 S  0.0  0.0   0:00.00 events/1 

     8 root      10  -5     0    0    0 S  0.0  0.0   0:00.02 khelper 

    13 root      10  -5     0    0    0 S  0.0  0.0   0:00.00 kthread 

    27 root      11  -5     0    0    0 S  0.0  0.0   0:00.00 kacpid 

   130 root      10  -5     0    0    0 S  0.0  0.0   0:00.07 kblockd/0 

   131 root      10  -5     0    0    0 S  0.0  0.0   0:00.09 kblockd/1 

   180 root      15   0     0    0    0 S  0.0  0.0   0:00.00 pdflush 

   181 root      15   0     0    0    0 S  0.0  0.0   0:52.49 pdflush 

   183 root      16   0     0    0    0 S  0.0  0.0   0:00.00 kswapd0 

   182 root      15   0     0    0    0 S  0.0  0.0   0:00.00 kswapd1 

   184 root      11  -5     0    0    0 S  0.0  0.0   0:00.00 aio/0 

   185 root      11  -5     0    0    0 S  0.0  0.0   0:00.00 aio/1 

   773 root      15   0     0    0    0 S  0.0  0.0   0:00.66 kseriod 

   957 root      19   0     0    0    0 S  0.0  0.0   0:00.00 scsi_eh_0 

  1082 root      10  -5     0    0    0 S  0.0  0.0   0:03.18 reiserfs/0 

  1083 root      10  -5     0    0    0 S  0.0  0.0   0:02.58 reiserfs/1 

  2189 root      14  -4  2552  656  444 S  0.0  0.0   0:00.34 udevd 

  2238 root      19   0     0    0    0 S  0.0  0.0   0:00.00 khpsbpkt 

  3140 root      15   0     0    0    0 S  0.0  0.0   0:00.00 khubd 

  4535 root      13  -2  2596  508  364 S  0.0  0.0   0:00.00 hwscand 

  4717 root      16   0  2552  512  428 S  0.0  0.0   0:01.19 irqbalance 

  4994 messageb  16   0 14992 1596 1328 S  0.0  0.0   0:00.00 
dbus-daemon-1
  5172 root      18   0  3608  540  452 S  0.0  0.0   0:00.00 resmgrd 

  5683 nobody    16   0  7272 1172  760 S  0.0  0.0   0:00.92 mdnsd 

  5693 root      16   0  2816  772  436 S  0.0  0.0   0:02.87 klogd 

  5700 root      16   0  9260 1012  740 S  0.0  0.0   0:03.29 syslog-ng 

  5718 nobody    16   0  4636  552  440 S  0.0  0.0   0:00.00 portmap 

  5741 root      16   0  6540  684  576 S  0.0  0.0   0:28.83 netdaemon 

  5851 root      16   0 58000 2952 1688 S  0.0  0.0   0:00.41 nsrexecd 

  5917 lp        16   0 24400 4312 1596 S  0.0  0.1   0:02.83 cupsd 

  5938 root      16   0 28624 2336 1820 S  0.0  0.0   0:02.23 sshd 

  5976 root      16   0  2556  584  492 S  0.0  0.0   0:00.00 acpid 

  6031 root      15   0 15532 1576 1340 S  0.0  0.0   0:15.90 powersaved 

  6253 ntp       16   0  7392 3316 2396 S  0.0  0.0   0:00.06 ntpd 

  6255 root      16   0 24172 1176  840 S  0.0  0.0   0:04.84 nscd 

  6273 root      16   0 11548 1336 1148 S  0.0  0.0   0:00.00 fam 

  6411 root      19   0     0    0    0 S  0.0  0.0   0:00.00 scsi_eh_1 

  6412 root      16   0 18496 1852 1436 S  0.0  0.0   0:00.35 master 

  6424 postfix   16   0 17524 1804 1412 S  0.0  0.0   0:00.00 qmgr 

  6429 root       0 -20     0    0    0 S  0.0  0.0   0:00.00 
qla2300_100_dpc
  6739 root      19   0     0    0    0 S  0.0  0.0   0:00.00 scsi_eh_2 

  6740 root       0 -20     0    0    0 S  0.0  0.0   0:00.00 
qla2300_101_dpc
  6756 root      19   0     0    0    0 S  0.0  0.0   0:00.00 scsi_eh_3 

  6757 root       0 -20     0    0    0 S  0.0  0.0   0:00.00 
qla2300_102_dpc
  7067 root      19   0     0    0    0 S  0.0  0.0   0:00.00 scsi_eh_4 

  7068 root       0 -20     0    0    0 S  0.0  0.0   0:00.00 
qla2300_103_dpc
  7084 root      19   0     0    0    0 S  0.0  0.0   0:00.00 scsi_eh_5 

  7085 root       0 -20     0    0    0 S  0.0  0.0   0:00.00 
qla2300_104_dpc
  7238 root      19   0     0    0    0 S  0.0  0.0   0:00.00 scsi_eh_6 

  7239 root       0 -20     0    0    0 S  0.0  0.0   0:00.00 
qla2300_105_dpc
  7423 root      15   0  9920 5308 3084 S  0.0  0.1   0:27.18 snmpd 

  7442 root      15   0 14696 1324  924 S  0.0  0.0   0:00.31 sanidmgr 

  7504 root      15   0     0    0    0 S  0.0  0.0  49:12.42 iocore 

  7540 root      15   0  5196  836  728 S  0.0  0.0   0:18.35 fsnupd 

  7560 root      15   0     0    0    0 S  0.0  0.0   0:00.02 trudpd 

  7570 root      15   0     0    0    0 D  0.0  0.0   0:00.00 fsnalias 

  7572 root      15   0     0    0    0 D  0.0  0.0   0:00.00 fsnalias 

  7583 root      19   0     0    0    0 D  0.0  0.0   0:00.00 ipstord 

  7604 root      15   0     0    0    0 S  0.0  0.0   0:00.00 fctgtsched 

  7605 root       0 -20     0    0    0 S  0.0  0.0   0:00.01 
fstgt_disp_rq
  7620 root      15   0     0    0    0 D  0.0  0.0   0:00.01 vtl_tm 

  7637 root      15   0     0    0    0 D  0.0  0.0   0:00.00 lm_upcall 

  7650 root      17   0  3648 1300 1060 D  0.0  0.0   0:01.14 tleupcall 

  7685 root      15   0     0    0    0 S  0.0  0.0   0:00.00 
vtl_pendcmd_chk
  7687 root      15   0     0    0    0 S  0.0  0.0   0:00.00 vtl_jd 

  7688 root      16   0  1608  520  456 S  0.0  0.0   0:00.00 mini_el 

  7690 root      15   0  1804  708  596 S  0.0  0.0   0:00.23 ssi 

  7691 root      15   0     0    0    0 D  0.0  0.0   0:00.36 
vtl_rae_275
  7692 root      15   0     0    0    0 D  0.0  0.0   0:35.93 
vtl_tde_276
  7693 root      15   0     0    0    0 D  0.0  0.0   0:34.62 
vtl_tde_277
  7694 root      15   0     0    0    0 D  0.0  0.0   1:02.91 
vtl_tde_278
  7695 root      15   0     0    0    0 D  0.0  0.0   0:00.33 
vtl_rae_327
  7696 root      15   0     0    0    0 D  0.0  0.0   0:40.10 
vtl_tde_328
  7697 root      15   0     0    0    0 D  0.0  0.0   0:52.96 
vtl_tde_329
  7698 root      15   0     0    0    0 D  0.0  0.0   0:26.62 
vtl_tde_454
  7699 root      15   0     0    0    0 D  0.0  0.0   1:12.28 
vtl_tde_455
  7715 root      18   0 10584 3812 1796 S  0.0  0.0   0:00.00 ipstorcomm 

  7716 root      15   0 53180  20m 4252 S  0.0  0.3  12:35.09 ipstorcomm 

  7717 root      15   0 10588 3972 1940 S  0.0  0.0   0:00.00 ipstorcomm 

  7726 root      15   0  3352  884  764 S  0.0  0.0   0:00.00 
isclntproxy
  7744 root      17   0  5976 3368 1332 S  0.0  0.0  17:10.67 ipstorlog 

  7763 root      16   0  7808 4344 1868 S  0.0  0.1 137:11.10 ipstorsm 

  7776 root      16   0  5908  672  568 S  0.0  0.0   0:00.30 cron 

  7786 root      16   0 14564 2676 1600 S  0.0  0.0   3:54.21 hald 

  7890 root      17   0  2992  676  588 S  0.0  0.0   0:00.02 mingetty 

  7891 root      17   0  2992  676  588 S  0.0  0.0   0:00.03 mingetty 

  7892 root      17   0  2992  676  588 S  0.0  0.0   0:00.03 mingetty 

  7893 root      17   0  2992  676  588 S  0.0  0.0   0:00.03 mingetty 

  7894 root      18   0  2992  676  588 S  0.0  0.0   0:00.03 mingetty 

  7895 root      22   0  2992  676  588 S  0.0  0.0   0:00.02 mingetty 

  7897 root      16   0  2556  536  456 S  0.0  0.0   0:00.00 agetty 

14372 root      16   0 39816 3096 2148 S  0.0  0.0   0:45.72 sshd 

14375 root      15   0  9788 2240 1472 S  0.0  0.0   0:00.04 bash 

14404 root      18   0  9172 1496 1164 S  0.0  0.0   0:00.00 vtlconsole 

14405 root      15   0 1264m 229m  21m S  0.0  2.9   5:33.02 java 

14864 root      15   0     0    0    0 D  0.0  0.0   0:00.09 vtl_rae_649 

14865 root      15   0     0    0    0 D  0.0  0.0   0:03.28 vtl_tde_650 

14866 root      15   0     0    0    0 D  0.0  0.0   0:02.62 vtl_tde_651 

21088 root      16   0 39484 2784 2148 S  0.0  0.0   0:00.13 sshd 

21092 root      16   0  9788 2260 1488 S  0.0  0.0   0:00.06 bash 

23560 postfix   16   0 17480 1776 1396 S  0.0  0.0   0:00.00 pickup 

23667 root      16   0 42540 2776 2156 S  0.0  0.0   0:00.02 sshd 

23669 bb        16   0 42672 2916 2232 S  0.0  0.0   0:00.00 sshd 

23670 bb        17   0 10044 2244 1468 S  0.0  0.0   0:00.06 bash 

24216 bb        18   0  8136 1348 1028 S  0.0  0.0   0:00.00 sh 

24218 bb        18   0  2572  568  472 S  0.0  0.0   0:00.00 vmstat 

24288 bb        18   0  1448  508  420 S  0.0  0.0   0:00.00 
hobbitlaunch
24289 bb        17   0  9436 1564 1204 S  0.0  0.0   0:00.00 
hobbitclient.sh
24295 bb        16   0  8140 1372 1036 S  0.0  0.0   0:00.01 
hobbitclient-li

[msgs:/var/log/messages]
<...SKIPPED...>
CORE1 log_scsi_error, acsl 104 0 2 0, cmd 08 00 02 00 00 00 00 00 00 00 
00 00 target 01 host 00 driver 08 sense F0 00 20 00 01 80 00 16 00 00 00 
03 00 00 00 00 len 131072 ofs 0 type 1 in 1
Dec  2 22:00:56 gollum kernel: FSNALIAS mp_find_storage_lun_by_guid: 
can't find lun for guid fa1c0101-aab4-b794-b411-ef40e75b5f4f
Dec  2 22:00:58 gollum ipstorcomm 
[mgtpipe_exec.c:pipe_thread:944][7716]: Rcv'd mgtpipe cmd: 'tle_expand 
791367 342 276480 10240'
Dec  2 22:00:58 gollum fxutil 
[fxutilex.c:XMLExpandResource:13601][7716]: Expanding stub tape 342
Dec  2 22:00:59 gollum ipstorcomm: 
IPSTOR||1228251659||I||0x00002cef||expanded virtual tape||VIRTUAL TAPE 
id 342||10240
Dec  2 22:00:59 gollum ipstorcomm 
[mgtpipe_exec.c:pipe_thread:944][7716]: Rcv'd mgtpipe cmd: 'tle_vdevop 
791369 342 1'
Dec  2 22:00:59 gollum kernel: FSNALIAS  Can't find this lun , failed 
command<6>FSNALIAS  Illegal request , failed command  for device 
(82df0567-0000-0c43-386b-5ca7736296d1)
Dec  2 22:00:59 gollum kernel: IOCORE1 ipstor_get_umaps, need to 
initialize umap for vdev 342
Dec  2 22:00:59 gollum ipstorcomm 
[mgtpipe_exec.c:pipe_thread:944][7716]: Rcv'd mgtpipe cmd: 'tle_vdevop 
791370 342 0'
Dec  2 22:00:59 gollum ipstorcomm [tape_cmd.c:disableVTape:1750][7716]: 
isTLEBusy() = 0
Dec  2 22:00:59 gollum ipstorcomm 
[mgtpipe_exec.c:pipe_thread:944][7716]: Rcv'd mgtpipe cmd: 'tle_vdevop 
791371 342 1'
Dec  2 22:00:59 gollum kernel: FSNALIAS  Illegal request , failed 
command  for device (82df0567-0000-0c43-386b-5ca7736296d1)
Dec  2 22:00:59 gollum kernel: IOCORE1 ipstor_get_umaps, high resolution 
umap found on disk for vdev 342, 81920 bits (20971520 sectors)
Dec  2 22:00:59 gollum kernel: [vtl_tde_454|7698] TLE_INFO: Data starts 
to write to the tape VID 342 in drive VID 454.
Dec  2 22:00:59 gollum ipstorcomm 
[mgtpipe_exec.c:pipe_thread:944][7716]: Rcv'd mgtpipe cmd: 
'tapedatamodified 342 1228251659'
Dec  2 22:00:59 gollum kernel: [vtl_mvphys|10987] TLE_INFO: Unload: 
attach repo inventory already done on hlib VID 139 STK L700
Dec  2 22:00:59 gollum getupcall 
[acslsparse.c:parseACSLSCommandRequest:36][7650]: parseACSLS: cmd 
request. preq=acd3fc68 cmd=ffff8100
Dec  2 22:00:59 gollum getupcall [acsls_cdkapi.c:QueryServer:105][7650]: 
Calling acs_query_server(243). VTL hostname: gollum, VTL IP: 
130.223.5.103, ACSLS IP: 130.223.5.19 [env:50004:130.223.5.19:ssi=0:csi=0]
Dec  2 22:00:59 gollum getupcall [acsls_cdkapi.c:QueryServer:122][7650]: 
Got Response. seqnum=243 reqid=37775 type=1 status=0 [STATUS_SUCCESS]
Dec  2 22:01:00 gollum getupcall [acsls_cdkapi.c:QueryServer:122][7650]: 
Got Response. seqnum=243 reqid=0 type=4 status=0 [STATUS_SUCCESS]
Dec  2 22:01:00 gollum getupcall [acsls_cdkapi.c:QueryServer:181][7650]: 
IP 130.223.5.103. ACSLS IP 130.223.5.19. State: STATE_RUN Freecells: 1
Dec  2 22:01:00 gollum getupcall 
[acsls_cdkapi.c:ACSLS_CDK_GetLSMList:1173][7650]: Got Response. 
seqnum=244 reqid=37776 type=1 status=0
Dec  2 22:01:00 gollum getupcall 
[acsls_cdkapi.c:ACSLS_CDK_GetLSMList:1173][7650]: Got Response. 
seqnum=244 reqid=0 type=2 status=0
Dec  2 22:01:00 gollum getupcall 
[acsls_cdkapi.c:ACSLS_CDK_GetLSMList:1173][7650]: Got Response. 
seqnum=244 reqid=0 type=4 status=0
Dec  2 22:01:00 gollum getupcall 
[acsls_cdkapi.c:parselsmlistXML:2024][7650]: ACSLS: LSM Info: acs=0 
lsm=0 type=L700 serialnum=MPC74001121 status=normal state=online 
condition=operative doorstatus=door_closed
Dec  2 22:01:00 gollum getupcall 
[acsls_cdkapi.c:parselsmlistXML:2029][7650]: ACSLS: status=0, LSM count=1
Dec  2 22:01:01 gollum getupcall 
[acsls_cdkapi.c:ACSLS_CDK_GetNumberOfDrives:473][7650]: Got Response. 
seqnum=245 reqid=37777 type=1 status=0
Dec  2 22:01:01 gollum getupcall 
[acsls_cdkapi.c:ACSLS_CDK_GetNumberOfDrives:473][7650]: Got Response. 
seqnum=245 reqid=0 type=2 status=0
Dec  2 22:01:01 gollum getupcall 
[acsls_cdkapi.c:ACSLS_CDK_GetNumberOfDrives:473][7650]: Got Response. 
seqnum=245 reqid=0 type=4 status=0
Dec  2 22:01:16 gollum kernel: [vtl_mvphys|10987] TLE_INFO: ACSLS/LS 
MoveMedium: LibVID 139: Move from SrcEle 1000000003 to DestEle 286
Dec  2 22:01:16 gollum getupcall 
[acslsparse.c:parseACSLSCommandRequest:36][7650]: parseACSLS: cmd 
request. preq=443000 cmd=ffffc200
Dec  2 22:01:16 gollum getupcall [acsls_cdkapi.c:QueryServer:105][7650]: 
Calling acs_query_server(246). VTL hostname: gollum, VTL IP: 
130.223.5.103, ACSLS IP: 130.223.5.19 [env:50004:130.223.5.19:ssi=0:csi=0]
Dec  2 22:01:16 gollum getupcall [acsls_cdkapi.c:QueryServer:122][7650]: 
Got Response. seqnum=246 reqid=37778 type=1 status=0 [STATUS_SUCCESS]
Dec  2 22:01:16 gollum getupcall [acsls_cdkapi.c:QueryServer:122][7650]: 
Got Response. seqnum=246 reqid=0 type=4 status=0 [STATUS_SUCCESS]
Dec  2 22:01:16 gollum getupcall [acsls_cdkapi.c:QueryServer:181][7650]: 
IP 130.223.5.103. ACSLS IP 130.223.5.19. State: STATE_RUN Freecells: 1
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:ACSLS_CDK_GetNumberOfDrives:473][7650]: Got Response. 
seqnum=247 reqid=37779 type=1 status=0
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:ACSLS_CDK_GetNumberOfDrives:473][7650]: Got Response. 
seqnum=247 reqid=0 type=2 status=0
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:ACSLS_CDK_GetNumberOfDrives:473][7650]: Got Response. 
seqnum=247 reqid=0 type=4 status=0
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:ACSLS_CDK_GetAllDrives:1010][7650]: Got Response. 
seqnum=248 reqid=37780 type=1 status=0
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:ACSLS_CDK_GetAllDrives:1010][7650]: Got Response. 
seqnum=248 reqid=0 type=2 status=0
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:ACSLS_CDK_GetAllDrives:1010][7650]: Got Response. 
seqnum=248 reqid=0 type=4 status=0
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:parsedriveslistXML:1822][7650]: ACSLS: Drive Info: acs=0 
lsm=0 panel=0 drive=0 type=SDLT-600 status=in_use state=online[2] 
condition=operative[4] lockStatus=0 vol=000592 serialNum=RB0626AMC05159
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:parsedriveslistXML:1822][7650]: ACSLS: Drive Info: acs=0 
lsm=0 panel=0 drive=1 type=SDLT-600 status=in_use state=online[2] 
condition=operative[4] lockStatus=0 vol=000597 serialNum=RB0626AMC05017
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:parsedriveslistXML:1822][7650]: ACSLS: Drive Info: acs=0 
lsm=0 panel=0 drive=2 type=SDLT-600 status=in_use state=online[2] 
condition=operative[4] lockStatus=0 vol=000518 serialNum=QD0632AMC00071
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:parsedriveslistXML:1822][7650]: ACSLS: Drive Info: acs=0 
lsm=0 panel=0 drive=3 type=SDLT-600 status=in_use state=online[2] 
condition=operative[4] lockStatus=0 vol=000731 serialNum=RB0511AMC05424
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:parsedriveslistXML:1822][7650]: ACSLS: Drive Info: acs=0 
lsm=0 panel=0 drive=4 type=SDLT-600 status=in_use state=online[2] 
condition=operative[4] lockStatus=0 vol=000651 serialNum=QD0632AMC00286
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:parsedriveslistXML:1822][7650]: ACSLS: Drive Info: acs=0 
lsm=0 panel=0 drive=5 type=SDLT-600 status=in_use state=online[2] 
condition=operative[4] lockStatus=0 vol=000510 serialNum=QD0634AMC00054
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:parsedriveslistXML:1822][7650]: ACSLS: Drive Info: acs=0 
lsm=0 panel=0 drive=6 type=SDLT-600 status=in_use state=online[2] 
condition=inoperative[-1] lockStatus=0 vol=000567 serialNum=QD0633AMC00069
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:parsedriveslistXML:1822][7650]: ACSLS: Drive Info: acs=0 
lsm=0 panel=0 drive=7 type=SDLT-600 status=in_use state=online[2] 
condition=operative[4] lockStatus=0 vol=000705 serialNum=QD0634AMC00103
Dec  2 22:01:17 gollum getupcall 
[acsls_cdkapi.c:parsedriveslistXML:1827][7650]: ACSLS: status=0, drive 
count=8
Dec  2 22:01:17 gollum getupcall 
[acslsparse.c:parseACSLSCommandRequest:36][7650]: parseACSLS: cmd 
request. preq=acd3f968 cmd=ffff8100
Dec  2 22:01:17 gollum getupcall 
[acslslib.c:VTL_ACSLSDismountVolume:1494][7650]: ACSLS: Dismounting 
volume=000731 from drive=0,0,0,3
Dec  2 22:01:17 gollum getupcall [acsls_cdkapi.c:QueryServer:105][7650]: 
Calling acs_query_server(249). VTL hostname: gollum, VTL IP: 
130.223.5.103, ACSLS IP: 130.223.5.19 [env:50004:130.223.5.19:ssi=0:csi=0]
Dec  2 22:01:17 gollum getupcall [acsls_cdkapi.c:QueryServer:122][7650]: 
Got Response. seqnum=249 reqid=37781 type=1 status=0 [STATUS_SUCCESS]
Dec  2 22:01:18 gollum getupcall [acsls_cdkapi.c:QueryServer:122][7650]: 
Got Response. seqnum=249 reqid=0 type=4 status=0 [STATUS_SUCCESS]
Dec  2 22:01:18 gollum getupcall [acsls_cdkapi.c:QueryServer:181][7650]: 
IP 130.223.5.103. ACSLS IP 130.223.5.19. State: STATE_RUN Freecells: 1
Dec  2 22:01:18 gollum getupcall 
[acsls_cdkapi.c:ACSLS_CDK_DismountVolume:346][7650]: Got Response. 
seqnum=250 reqid=37782 type=1 status=0
Dec  2 22:01:29 gollum getupcall 
[acsls_cdkapi.c:ACSLS_CDK_DismountVolume:346][7650]: Got Response. 
seqnum=250 reqid=0 type=4 status=0
Dec  2 22:01:29 gollum getupcall 
[acslslib.c:VTL_ACSLSDismountVolume:1581][7650]: ACSLS/LS: Successfully 
dismounted volume=000731 from drive=0,0,0,3
Dec  2 22:01:29 gollum tleupcall: 
IPSTOR||1228251689||I||0x00009cac||dismount success||ACSLS||000731||0.0.0.3
Dec  2 22:01:56 gollum ipstorcomm 
[mgtpipe_exec.c:pipe_thread:944][7716]: Rcv'd mgtpipe cmd: 'tle_expand 
791401 410 181248 5120'
Dec  2 22:01:57 gollum ipstorcomm: 
IPSTOR||1228251717||I||0x00002cef||expanded virtual tape||VIRTUAL TAPE 
id 410||5120
Dec  2 22:01:57 gollum kernel: [vtl_tde_329|7697] TLE_INFO: Tape 410 
Expanded successfully, new size 195421011968 bytes
Dec  2 22:01:57 gollum kernel: [vtl_report|10996] TLE_INFO: TAPECACHING: 
ReclamTapesWithMoreSpaceNeededPolicy - total=11432160 MB, 
available=4019538 MB, Threshold: 95
Dec  2 22:07:09 gollum ipstorcomm 
[mgtpipe_exec.c:pipe_thread:944][7716]: Rcv'd mgtpipe cmd: 'tle_expand 
791558 409 171008 5120'
Dec  2 22:07:10 gollum ipstorcomm: 
IPSTOR||1228252030||I||0x00002cef||expanded virtual tape||VIRTUAL TAPE 
id 409||5120
Dec  2 22:07:11 gollum kernel: [vtl_tde_455|7699] TLE_INFO: Tape 409 
Expanded successfully, new size 184683593728 bytes
Dec  2 22:07:11 gollum kernel: [vtl_report|11068] TLE_INFO: TAPECACHING: 
ReclamTapesWithMoreSpaceNeededPolicy - total=11432160 MB, 
available=4014418 MB, Threshold: 95

[logfile:/var/log/messages]
type:100000 (file)
mode:640 (-rw-r-----)
linkcount:1
owner:0 (root)
group:0 (root)
size:198273
clock:1228300596 (2008/12/03-11:36:36)
atime:1228300596 (2008/12/03-11:36:36)
ctime:1228300585 (2008/12/03-11:36:25)
mtime:1228300585 (2008/12/03-11:36:25)

[clientversion]

[clock]
epoch: 1228300596.170595
local: 2008-12-03 11:36:36 CET
UTC: 2008-12-03 10:36:36 GMT


Regards,

Dominique



More information about the Xymon mailing list