[Jim Nance <jlnance@avanticorp.com>] Re: Arla problems

Fri Sep 11 19:00:29 CEST 1998

The error occurs in do_read_attr().

------- Start of forwarded message -------
Date: Fri, 11 Sep 1998 12:31:43 -0400
From: Jim Nance <jlnance at avanticorp.com>
To: Magnus Ahltorp <map at stacken.kth.se>
Subject: Re: Arla problems

> > Fri Sep 11 11:04:36 1998: arlad: multi-sending wakeup: seq = 16, error = 0
> > Fri Sep 11 11:04:36 1998: arlad: Rec message: opcode = 4 (getnode), size = 292
> > Fri Sep 11 11:04:36 1998: arlad: Multi-send: opcode = 5 (installnode), size = 384
> > Fri Sep 11 11:04:36 1
> 
> This doesn't look like a complete log file. Did you really kill arlad
> before you copied the log file?

This one should be a little better.  I did not kill arla before I coppied
the file.  It got killed on reboot though.  Here is the output.

Jim

Fri Sep 11 11:04:11 1998: arlad: read_conffile: /usr/afsws/etc/arla.conf
Fri Sep 11 11:04:11 1998: arlad: read_conffile: /usr/afsws/etc/arla.conf
Fri Sep 11 11:04:11 1998: arlad: Arlad booting sequence:
Fri Sep 11 11:04:11 1998: arlad: connected mode: connected
Fri Sep 11 11:04:11 1998: arlad: initports
Fri Sep 11 11:04:11 1998: arlad: conn_init numconns = 100
Fri Sep 11 11:04:11 1998: arlad: initconncache
Fri Sep 11 11:04:11 1998: arlad: cellcache
Fri Sep 11 11:04:11 1998: arlad: fprio
Fri Sep 11 11:04:11 1998: arlad: fprio_readin: cant open file /usr/afsws/cache/fprio: Operation not permitted
Fri Sep 11 11:04:11 1998: arlad: volcache numvols = 100
Fri Sep 11 11:04:11 1998: arlad: recovered 82 entries to volcache
Fri Sep 11 11:04:11 1998: arlad: rx
Fri Sep 11 11:04:11 1998: arlad: using rxkad level auth
Fri Sep 11 11:04:11 1998: arlad: credcache numcreds = 100
Fri Sep 11 11:04:11 1998: arlad: fcache low_vnodes = 3000, high_vnodes = 4000low_bytes = 45000000, high_bytes = 60000000
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970505990 and got code984.jlnance.ar
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 536874584 and got sim.sys
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970501424 and got product.simulation
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970506435 and got apps.wp60
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 536877294 and got prod.distrib
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 536871005 and got user.kress
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970504174 and got sun4c_411.usr
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 536872425 and got adm
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 536872521 and got wsadmin
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970506437 and got private
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 536876482 and got prod.current
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970506446 and got sgi_53
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970500931 and got sgi_52
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 536877301 and got r2_x11r5
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970506444 and got public
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 536872428 and got var.tmp
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970500929 and got pmax_ul43
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 536870931 and got common
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970504172 and got sun4c_53
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 536879215 and got restore
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970501823 and got garbage
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970504164 and got hp700_ux80
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 536875780 and got backups
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970501011 and got hp700_ux100
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970501041 and got alpha_dux40
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 536872527 and got data
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970500960 and got rs_aix32
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970504607 and got sun4x_56
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970506681 and got sun4x_57
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970499861 and got hp_ux102
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970501807 and got sgi_62
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970497973 and got sgi_61
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970501039 and got hp700_ux90
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970500896 and got local
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970500974 and got hp800_ux100
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970500964 and got m0_local
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970501037 and got alpha_osf32c
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 536881288 and got rs_aix41
Fri Sep 11 11:04:11 1998: arlad: get_info: we asked for 1970506413 and got gnu
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970500920 and got afs33a.linux
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970506677 and got dos
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970500926 and got alpha_osf32
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536880990 and got mir
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970503427 and got prod.qa.libperl
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536873156 and got qadrc.big.rsets
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970506372 and got qa.drc.big
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536875135 and got user.gregc
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536874618 and got qadrc.sm.ltl.gamma
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536874615 and got qadrc.sm.ltl.dev
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536872082 and got qa.drc.small
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970506371 and got qa.drc
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970505987 and got code984.jlnance
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970504171 and got sun4m_412
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970505804 and got code.984.build
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970505837 and got code984.lnx20.opt
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970504175 and got sun4c_411
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970505840 and got code984.lnx20.dbx
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970503341 and got layout982
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970502993 and got prod.flexlm.v60
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970501819 and got root.cell
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970504203 and got sun4x_55
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536871898 and got apps.vl531.sun5
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970505801 and got code.984
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536870987 and got user.jlnance
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536877302 and got user.brewer
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970504184 and got product
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970505798 and got product.code
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970505807 and got code.984.arch
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536872073 and got prod.qa
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970506659 and got sun4x_55.mentor
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970506402 and got users
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970501414 and got product.sys
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536876476 and got prod.flexlm
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536874050 and got prod.rules
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536874803 and got prod.qa.perl
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970500406 and got prod.master
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536871871 and got apps.vl
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970504206 and got apps
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970500945 and got sun4m_54
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 536871332 and got product.layout
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970506216 and got root.afs
Fri Sep 11 11:04:12 1998: arlad: get_info: we asked for 1970500412 and got user.jlnance.sys
Fri Sep 11 11:04:12 1998: arlad: recovered 3000 entries to fcache
Fri Sep 11 11:04:12 1998: arlad: cmcb
Fri Sep 11 11:04:12 1998: arlad: cm
Fri Sep 11 11:04:12 1998: arlad: arla init done.
Fri Sep 11 11:04:12 1998: arlad: pre-creating nodes
Fri Sep 11 11:04:12 1998: arlad: Arla: selecting on fd: 6
Fri Sep 11 11:04:22 1998: arlad: Rec message: opcode = 2 (getroot), size = 20
Fri Sep 11 11:04:22 1998: arlad: callback (192.160.17.224)
Fri Sep 11 11:04:22 1998: arlad: 0: (536870987, 304, 1194172)
Fri Sep 11 11:04:22 1998: arlad: Multi-send: opcode = 3 (installroot), size = 112
Fri Sep 11 11:04:22 1998: arlad: multi-sending wakeup: seq = 0, error = 0
Fri Sep 11 11:04:30 1998: arlad: Rec message: opcode = 22 (pioctl), size = 2096
Fri Sep 11 11:04:30 1998: arlad: sending wakeup: seq = 1, error = 22
Fri Sep 11 11:04:30 1998: arlad: Send message: opcode = 1 (wakeup), size = 20
Fri Sep 11 11:04:30 1998: arlad: Rec message: opcode = 4 (getnode), size = 292
Fri Sep 11 11:04:30 1998: arlad: Multi-send: opcode = 5 (installnode), size = 384
Fri Sep 11 11:04:30 1998: arlad: multi-sending wakeup: seq = 2, error = 0
Fri Sep 11 11:04:30 1998: arlad: Rec message: opcode = 8 (getdata), size = 40
Fri Sep 11 11:04:30 1998: arlad: Multi-send: opcode = 9 (installdata), size = 116
Fri Sep 11 11:04:30 1998: arlad: multi-sending wakeup: seq = 3, error = 0
Fri Sep 11 11:04:30 1998: arlad: Rec message: opcode = 4 (getnode), size = 292
Fri Sep 11 11:04:30 1998: arlad: read_data
Fri Sep 11 11:04:30 1998: arlad: Multi-send: opcode = 5 (installnode), size = 384
Fri Sep 11 11:04:30 1998: arlad: multi-sending wakeup: seq = 4, error = 0
Fri Sep 11 11:04:30 1998: arlad: Rec message: opcode = 4 (getnode), size = 292
Fri Sep 11 11:04:30 1998: arlad: read_data
Fri Sep 11 11:04:30 1998: arlad: InitCallBackState (192.160.17.226)
Fri Sep 11 11:04:30 1998: arlad: Multi-send: opcode = 5 (installnode), size = 384
Fri Sep 11 11:04:30 1998: arlad: multi-sending wakeup: seq = 5, error = 0
Fri Sep 11 11:04:30 1998: arlad: Rec message: opcode = 10 (inactivenode), size = 32
Fri Sep 11 11:04:30 1998: arlad: Rec message: opcode = 4 (getnode), size = 292
Fri Sep 11 11:04:30 1998: arlad: read_data
Fri Sep 11 11:04:30 1998: arlad: Multi-send: opcode = 5 (installnode), size = 384
Fri Sep 11 11:04:30 1998: arlad: multi-sending wakeup: seq = 7, error = 0
Fri Sep 11 11:04:30 1998: arlad: Rec message: opcode = 4 (getnode), size = 292
Fri Sep 11 11:04:30 1998: arlad: multi-sending wakeup: seq = 8, error = 2
Fri Sep 11 11:04:30 1998: arlad: Rec message: opcode = 22 (pioctl), size = 2096
Fri Sep 11 11:04:30 1998: arlad: sending wakeup: seq = 9, error = 22
Fri Sep 11 11:04:30 1998: arlad: Send message: opcode = 1 (wakeup), size = 20
Fri Sep 11 11:04:30 1998: arlad: Rec message: opcode = 10 (inactivenode), size = 32
Fri Sep 11 11:04:30 1998: arlad: Rec message: opcode = 22 (pioctl), size = 2096
Fri Sep 11 11:04:30 1998: arlad: ticket_st has size 56
Fri Sep 11 11:04:30 1998: arlad: Send message: opcode = 11 (invalidnode), size = 28
Fri Sep 11 11:04:30 1998: arlad: Send message: opcode = 11 (invalidnode), size = 28
Fri Sep 11 11:04:30 1998: arlad: sending wakeup: seq = 11, error = 0
Fri Sep 11 11:04:30 1998: arlad: Send message: opcode = 1 (wakeup), size = 20
Fri Sep 11 11:04:30 1998: arlad: Rec message: opcode = 10 (inactivenode), size = 32
Fri Sep 11 11:04:31 1998: arlad: Rec message: opcode = 10 (inactivenode), size = 32
Fri Sep 11 11:04:32 1998: arlad: Rec message: opcode = 8 (getdata), size = 40
Fri Sep 11 11:04:32 1998: arlad: pre-created 1000 nodes
Fri Sep 11 11:04:32 1998: arlad: Multi-send: opcode = 9 (installdata), size = 116
Fri Sep 11 11:04:32 1998: arlad: multi-sending wakeup: seq = 14, error = 0
Fri Sep 11 11:04:36 1998: arlad: Rec message: opcode = 4 (getnode), size = 292
Fri Sep 11 11:04:36 1998: arlad: Multi-send: opcode = 5 (installnode), size = 384
Fri Sep 11 11:04:36 1998: arlad: multi-sending wakeup: seq = 15, error = 0
Fri Sep 11 11:04:36 1998: arlad: Rec message: opcode = 8 (getdata), size = 40
Fri Sep 11 11:04:36 1998: arlad: Multi-send: opcode = 9 (installdata), size = 116
Fri Sep 11 11:04:36 1998: arlad: multi-sending wakeup: seq = 16, error = 0
Fri Sep 11 11:04:36 1998: arlad: Rec message: opcode = 4 (getnode), size = 292
Fri Sep 11 11:04:36 1998: arlad: Multi-send: opcode = 5 (installnode), size = 384
Fri Sep 11 11:04:36 1998: arlad: multi-sending wakeup: seq = 17, error = 0
Fri Sep 11 11:04:36 1998: arlad: Rec message: opcode = 8 (getdata), size = 40
Fri Sep 11 11:04:36 1998: arlad: Multi-send: opcode = 9 (installdata), size = 116
Fri Sep 11 11:04:36 1998: arlad: multi-sending wakeup: seq = 18, error = 0
Fri Sep 11 11:04:39 1998: arlad: Rec message: opcode = 4 (getnode), size = 292
Fri Sep 11 11:04:39 1998: arlad: read_data
Fri Sep 11 11:04:39 1998: arlad: InitCallBackState (192.160.17.221)
Fri Sep 11 11:04:39 1998: arlad: fetch-status: Software caused connection abort
Fri Sep 11 11:04:39 1998: arlad: multi-sending wakeup: seq = 19, error = 103
Fri Sep 11 11:05:20 1998: arlad: callback (192.160.17.224)
Fri Sep 11 11:05:20 1998: arlad: 0: (536870987, 1, 1)
Fri Sep 11 11:07:59 1998: arlad: fatal signal received
Fri Sep 11 11:07:59 1998: arlad: storing state
Fri Sep 11 11:07:59 1998: arlad: wrote 3000 entries to fcache
Fri Sep 11 11:07:59 1998: arlad: wrote 82 entries to volcache

-- 
----------------------------------------------------------------------------
Jim Nance                                                 Avant! Corporation
(919) 941-6655    Do you have sweet iced tea?       jim_nance at avanticorp.com
                  No, but there's sugar on the table.

------- End of forwarded message -------