From owner-freebsd-scsi Tue May 2 21: 5: 2 2000 Delivered-To: freebsd-scsi@freebsd.org Received: from pau-amma.whistle.com (pau-amma.whistle.com [207.76.205.64]) by hub.freebsd.org (Postfix) with ESMTP id 75D7837B944 for ; Tue, 2 May 2000 21:04:53 -0700 (PDT) (envelope-from dhw@whistle.com) Received: (from dhw@localhost) by pau-amma.whistle.com (8.10.0/8.10.0) id e4344ql31790 for freebsd-scsi@freebsd.org; Tue, 2 May 2000 21:04:52 -0700 (PDT) Date: Tue, 2 May 2000 21:04:52 -0700 (PDT) From: David Wolfskill Message-Id: <200005030404.e4344ql31790@pau-amma.whistle.com> To: freebsd-scsi@freebsd.org Subject: ch: warning: could not map element source addr... valid element type Sender: owner-freebsd-scsi@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.org About a year ago, I see that there was a message on this list that mentioned the warning message I'm now getting... but I didn't see a followup or a resolution... so I'm asking for some assistance in figuring out what is going on. Background: I had set up a dedicated "backup server", running amanda (2.4.2-19991216-beta1, with a few custom patches [some of which didn't seem to catch the fancy of the amanda community, but which I found useful, so I kept them in anyway]); the box was running FreeBSD 2.2.6-R for a long time. Since it's only in active use for a few hours each day, I had been trying to set it up so I could boot it either from 2.2.6-R or 4.x-C (a few months ago, before 4.0-R existed). Today, I decided to "upgrade" the 4.0-C to 4.0-R -- actually, I did a re-install, overlaying the old 4.0-C. (This after running 4.0-R for several weeks on the laptop I'm using to type this message... which is mostly serving xterms that are opened up on various machines; quite handy, actually, for a sysadmin.) After tweaking things here & there with the filesystem layouts, re-building & re-installing amanda, and getting all the amanda configuration stuff taken care of, things seem to work OK so far: I haven't actually done a backup yet, but the "amcheck" works just fine. But just as I was getting ready to leave the machine room, I glanced up at the console (which I had inadvertently left on), and I saw a large quantity fo messages like that in the Subject: -- e.g. /kernel: ch: warning: could not map element source address 256d to a valid element type (The address in question rotated among 256d, 512d, 768d, 1280d, 1536d, 1792d, and 1280d. The changer in question has 7 slots. More below.) Now, although I'm using a fairly recent version of amanda, I had started with amanda about 1.5 years ago... and back then, there was no "changer script" that appeared to be suitable that was distributed with amanda (and I wasn't aware that someone else was working one one), so I cobbled up a fairly simple-minded one (in Perl 5) that makes use of Jason Thorpe's "chio" command to manipulate the changer... and I'm still using it. (Please note that the functions appear to work -- I was able to get the device to cycle through all the tapes, for instance -- it just spams syslog with the warnings, is all.) One of the things it does early on (for example) is issue "chio params" and "chio status" commands (and parse the results of same) in order to get a clue as to what the changer thinks its current configuration & status is. With that in mind, here are the results of the commands in question: eng-backup# chio params /dev/ch0: 7 slots, 1 drive, 1 picker /dev/ch0: current picker: 0 eng-backup# chio status picker 0: slot 0: slot 1: slot 2: slot 3: slot 4: slot 5: slot 6: drive 0: eng-backup# The warning messages in question appear to be generated in coincidence with issuing the latter command ("chio params"). [Basis for this observation is watching a "tail -f /var/log/message" while issuing the above sequence of commands.] Further, the message appears to be issued by code in cam/scsi/scsi_ch.c, around lines 1036-1057: if (desc->flags2 & READ_ELEMENT_STATUS_SVALID) { eaddr = scsi_2btoul(desc->ssea); /* convert source address to logical format */ for (et = CHET_MT; et <= CHET_DT; et++) { if ((softc->sc_firsts[et] <= eaddr) && ((softc->sc_firsts[et] + softc->sc_counts[et]) > eaddr)) { ces->ces_source_addr = eaddr - softc->sc_firsts[et]; ces->ces_source_type = et; ces->ces_flags |= CES_SOURCE_VALID; break; } } if (!(ces->ces_flags & CES_SOURCE_VALID)) printf("ch: warning: could not map element source " "address %ud to a valid element type", eaddr); } I confess, though, to being fairly unfamiliar with just what is supposed to be going on here.... I'll copy the output of dmesg from a "boot -v" below my .sig; I'm certainly willing to provide additional information, but the kernel configuration (for example) differs from GENERIC (only) in that: * I removed all the "cpu" directives but I686_CPU. * I increased maxusers form 32 to 64. * I decreased the SCSI "settle time" from 15 sec. to 5 sec. * Lots of devices I didn't care about were commented out. * The ch device (which I *do* care about!) was copied in from LINT. * I added kernel support for (but have yet to enable) soft updates. Thanks, david -- David Wolfskill dhw@whistle.com UNIX System Administrator voice: (650) 577-7158 pager: (888) 347-0197 FAX: (650) 372-5915 Copyright (c) 1992-2000 The FreeBSD Project. Copyright (c) 1982, 1986, 1989, 1991, 1993 The Regents of the University of California. All rights reserved. FreeBSD 4.0-RELEASE #0: Tue May 2 16:56:14 PDT 2000 root@eng-backup.whistle.com:/usr/src/sys/compile/ENG-BACKUP Calibrating clock(s) ... TSC clock: 199312052 Hz, i8254 clock: 1193200 Hz CLK_USE_I8254_CALIBRATION not specified - using default frequency Timecounter "i8254" frequency 1193182 Hz CLK_USE_TSC_CALIBRATION not specified - using old calibration method Timecounter "TSC" frequency 199310348 Hz CPU: Pentium Pro (199.31-MHz 686-class CPU) Origin = "GenuineIntel" Id = 0x619 Stepping = 9 Features=0xf9ff real memory = 67108864 (65536K bytes) Physical memory chunk(s): 0x00001000 - 0x0009ffff, 651264 bytes (159 pages) 0x0033d000 - 0x03ff7fff, 63680512 bytes (15547 pages) avail memory = 61755392 (60308K bytes) bios32: Found BIOS32 Service Directory header at 0xc00f8050 bios32: Entry = 0xf7aa0 (c00f7aa0) Rev = 0 Len = 1 pcibios: PCI BIOS entry at 0x7ad0 pnpbios: Found PnP BIOS data at 0xc00fbff0 pnpbios: Entry = f0000:c020 Rev = 1.0 pnpbios: OEM ID cd041 Other BIOS signatures found: ACPI: 00000000 Preloaded elf kernel "kernel" at 0xc0324000. Pentium Pro MTRR support enabled md0: Malloc disk Creating DISK md0 pci_open(1): mode 1 addr port (0x0cf8) is 0x8000005c pci_open(1a): mode1res=0x80000000 (0x80000000) pci_cfgcheck: device 0 [class=060000] [hdr=00] is there (id=12378086) npx0: on motherboard npx0: INT 16 interface pci_open(1): mode 1 addr port (0x0cf8) is 0x00000000 pci_open(1a): mode1res=0x80000000 (0x80000000) pci_cfgcheck: device 0 [class=060000] [hdr=00] is there (id=12378086) pcib0: on motherboard found-> vendor=0x8086, dev=0x1237, revid=0x02 class=06-00-00, hdrtype=0x00, mfdev=0 subordinatebus=0 secondarybus=0 found-> vendor=0x8086, dev=0x7000, revid=0x01 class=06-01-00, hdrtype=0x00, mfdev=1 subordinatebus=0 secondarybus=0 found-> vendor=0x8086, dev=0x7010, revid=0x00 class=01-01-80, hdrtype=0x00, mfdev=0 subordinatebus=0 secondarybus=0 found-> vendor=0x9004, dev=0x8178, revid=0x00 class=01-00-00, hdrtype=0x00, mfdev=0 subordinatebus=0 secondarybus=0 intpin=a, irq=9 map[10]: type 1, range 32, base 0000e000, size 8 map[14]: type 1, range 32, base fa000000, size 12 found-> vendor=0x1000, dev=0x0001, revid=0x12 class=01-00-00, hdrtype=0x00, mfdev=0 subordinatebus=0 secondarybus=0 intpin=a, irq=12 map[10]: type 1, range 32, base 0000d800, size 8 map[14]: type 1, range 32, base f9800000, size 8 found-> vendor=0x1011, dev=0x0009, revid=0x20 class=02-00-00, hdrtype=0x00, mfdev=0 subordinatebus=0 secondarybus=0 intpin=a, irq=10 map[10]: type 1, range 32, base 0000d400, size 7 map[14]: type 1, range 32, base f9000000, size 7 found-> vendor=0x9004, dev=0x8178, revid=0x01 class=01-00-00, hdrtype=0x00, mfdev=0 subordinatebus=0 secondarybus=0 intpin=a, irq=11 map[10]: type 1, range 32, base 0000d000, size 8 map[14]: type 1, range 32, base f8800000, size 12 found-> vendor=0x102b, dev=0x051a, revid=0x02 class=03-00-00, hdrtype=0x00, mfdev=0 subordinatebus=0 secondarybus=0 intpin=a, irq=9 map[10]: type 1, range 32, base f8000000, size 14 map[14]: type 1, range 32, base fb000000, size 23 map[18]: type 1, range 32, base f7800000, size 23 pci0: on pcib0 isab0: at device 1.0 on pci0 I/O Recovery Timing: 8-bit 8 clocks, 16-bit 4 clocks Extended BIOS: disabled Lower BIOS: enabled Coprocessor IRQ13: enabled Mouse IRQ12: disabled Interrupt Routing: A: IRQ11, B: IRQ10, C: IRQ12, D: IRQ9 MB0: disabled, MB1: isa0: on isab0 pci0: (vendor=0x8086, dev=0x7010) at 1.1 ahc0: port 0xe000-0xe0ff mem 0xfa000000-0xfa000fff irq 9 at device 9.0 on pci0 ahc0: Reading SEEPROM...done. ahc0: Low byte termination Enabled ahc0: High byte termination Enabled ahc0: aic7880 Wide Channel A, SCSI Id=7, 16/255 SCBs ahc0: Downloading Sequencer Program... 415 instructions downloaded sym0: <810a> port 0xd800-0xd8ff mem 0xf9800000-0xf98000ff irq 12 at device 10.0 on pci0 sym0: No NVRAM, ID 7, Fast-10, SE, parity checking sym0: open drain IRQ line driver de0: port 0xd400-0xd47f mem 0xf9000000-0xf900007f irq 10 at device 11.0 on pci0 de0: SMC 9332BDT 21140A [10-100Mb/s] pass 2.0 de0: address 00:00:c0:6a:69:f9 de0: supplying EUI64: 00:00:c0:ff:fe:6a:69:f9 bpf: de0 attached de0: driver is using old-style compatability shims ahc1: port 0xd000-0xd0ff mem 0xf8800000-0xf8800fff irq 11 at device 12.0 on pci0 ahc1: Reading SEEPROM...done. ahc1: Low byte termination Enabled ahc1: High byte termination Enabled ahc1: aic7880 Wide Channel A, SCSI Id=7, 16/255 SCBs ahc1: Downloading Sequencer Program... 415 instructions downloaded pci0: (vendor=0x102b, dev=0x051a) at 13.0 irq 9 Trying Read_Port at 203 Trying Read_Port at 243 Trying Read_Port at 283 Trying Read_Port at 2c3 Trying Read_Port at 303 Trying Read_Port at 343 Trying Read_Port at 383 Trying Read_Port at 3c3 isa_probe_children: disabling PnP devices isa_probe_children: probing non-PnP devices fdc0: at port 0x3f0-0x3f5,0x3f7 irq 6 drq 2 on isa0 fdc0: FIFO enabled, 8 bytes threshold fd0: <1440-KB 3.5" drive> on fdc0 drive 0 atkbdc0: at port 0x60-0x6f on isa0 atkbd0: irq 1 on atkbdc0 atkbd: the current kbd controller command byte 0067 atkbd: keyboard ID 0x41ab (2) kbdc: RESET_KBD return code:00fa kbdc: RESET_KBD status:00aa kbd0: atkbd0, AT 101/102 (2), config:0x0, flags:0x3d0000 psm0: current command byte:0067 kbdc: TEST_AUX_PORT status:0000 kbdc: RESET_AUX return code:00fe kbdc: RESET_AUX return code:00fe kbdc: RESET_AUX return code:00fe kbdc: DIAGNOSE status:0055 kbdc: TEST_KBD_PORT status:0000 psm0: failed to reset the aux device. vga0: at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0 fb0: vga0, vga, type:VGA (5), flags:0x7007f fb0: port:0x3c0-0x3df, crtc:0x3d4, mem:0xa0000 0x20000 fb0: init mode:24, bios mode:3, current mode:24 fb0: window:0xc00b8000 size:32k gran:32k, buf:0 size:32k VGA parameters upon power-up 50 18 10 00 00 00 03 00 02 67 5f 4f 50 82 55 81 bf 1f 00 4f 0e 0f 00 00 07 80 9c 8e 8f 28 1f 96 b9 a3 ff 00 01 02 03 04 05 14 07 38 39 3a 3b 3c 3d 3e 3f 0c 00 0f 08 00 00 00 00 00 10 0e 00 ff VGA parameters in BIOS for mode 24 50 18 10 00 10 00 03 00 02 67 5f 4f 50 82 55 81 bf 1f 00 4f 0d 0e 00 00 00 00 9c 8e 8f 28 1f 96 b9 a3 ff 00 01 02 03 04 05 14 07 38 39 3a 3b 3c 3d 3e 3f 0c 00 0f 08 00 00 00 00 00 10 0e 00 ff EGA/VGA parameters to be used for mode 24 50 18 10 00 10 00 03 00 02 67 5f 4f 50 82 55 81 bf 1f 00 4f 0d 0e 00 00 00 00 9c 8e 8f 28 1f 96 b9 a3 ff 00 01 02 03 04 05 14 07 38 39 3a 3b 3c 3d 3e 3f 0c 00 0f 08 00 00 00 00 00 10 0e 00 ff sc0: on isa0 sc0: VGA <16 virtual consoles, flags=0x200> sc0: fb0, kbd0, terminal emulator: sc (syscons terminal) sio0: irq maps: 0x1041 0x1051 0x1041 0x1041 sio0 at port 0x3f8-0x3ff irq 4 flags 0x10 on isa0 sio0: type 16550A sio1: irq maps: 0x1041 0x1049 0x1041 0x1041 sio1 at port 0x2f8-0x2ff irq 3 on isa0 sio1: type 16550A sio2: not probed (disabled) sio3: not probed (disabled) ppc0: parallel port found at 0x378 ppc0: SPP ppc0: at port 0x378-0x37f irq 7 on isa0 ppc0: Generic chipset (NIBBLE-only) in COMPATIBLE mode ppi0: on ppbus0 lpt0: on ppbus0 lpt0: Interrupt-driven port isa_probe_children: probing PnP devices BIOS Geometries: 0:0154bf3f 0..340=341 cylinders, 0..191=192 heads, 1..63=63 sectors 1:03fb103d 0..1019=1020 cylinders, 0..16=17 heads, 1..61=61 sectors 2:03fefe3f 0..1022=1023 cylinders, 0..254=255 heads, 1..63=63 sectors 3:03fefe3f 0..1022=1023 cylinders, 0..254=255 heads, 1..63=63 sectors 0 accounted for Device configuration finished. bpf: lo0 attached bpf: gif0 attached bpf: gif1 attached bpf: gif2 attached bpf: gif3 attached bpf: stf0 attached bpf: faith0 attached Waiting 5 seconds for SCSI devices to settle (noperiph:ahc0:0:-1:-1): SCSI bus reset delivered. 0 SCBs aborted. (noperiph:sym0:0:-1:-1): SCSI BUS reset delivered. (noperiph:ahc1:0:-1:-1): SCSI bus reset delivered. 0 SCBs aborted. de0: enabling Full Duplex 100baseTX port (probe17:sym0:0:2:0): INQUIRY. CDB: 12 1 80 0 ff 0 (probe17:sym0:0:2:0): ILLEGAL REQUEST asc:24,0 (probe17:sym0:0:2:0): Invalid field in CDB sks:c8,1 (probe21:sym0:0:6:0): INQUIRY. CDB: 12 1 80 0 ff 0 (probe21:sym0:0:6:0): ILLEGAL REQUEST asc:24,0 (probe21:sym0:0:6:0): Invalid field in CDB Sending WDTR! ahc0: target 8 using 16bit transfers ahc0: target 8 synchronous at 20.0MHz, offset = 0x8 Sending SDTR! ahc0: target 8 using asynchronous transfers ahc0: target 8 synchronous at 20.0MHz, offset = 0x8 ahc0: target 8 synchronous at 10.0MHz, offset = 0x8 ahc0: target 0 using 16bit transfers ahc0: target 0 synchronous at 20.0MHz, offset = 0x8 ahc1: target 3 synchronous at 10.0MHz, offset = 0xf Creating DISK da0 Creating DISK da1 Creating DISK da2 Creating DISK da3 sa0 at ahc1 bus 0 target 3 lun 0 sa0: Removable Sequential Access SCSI-2 device sa0: Serial Number CX914M0098 sa0: 10.000MB/s transfers (10.000MHz, offset 15) Creating DISK cd0 pass0 at ahc0 bus 0 target 0 lun 0 pass0: Fixed Direct Access SCSI-2 device pass0: Serial Number RE460219 pass0: 40.000MB/s transfers (20.000MHz, offset 8, 16bit), Tagged Queueing Enabled pass1 at ahc0 bus 0 target 8 lun 0 pass1: Fixed Direct Access SCSI-3 device pass1: Serial Number 111001140521 pass1: 20.000MB/s transfers (10.000MHz, offset 8, 16bit), Tagged Queueing Enabled pass2 at sym0 bus 0 target 0 lun 0 pass2: Fixed Direct Access SCSI-2 device pass2: Serial Number 399702418491 pass2: 10.000MB/s transfers (10.000MHz, offset 8), Tagged Queueing Enabled pass3 at sym0 bus 0 target 2 lun 0 pass3: Fixed Direct Access SCSI-2 device pass3: 10.000MB/s transfers (10.000MHz, offset 8) pass4 at sym0 bus 0 target 6 lun 0 pass4: Removable CD-ROM SCSI-2 device pass4: 10.000MB/s transfers (10.000MHz, offset 8) pass5 at ahc1 bus 0 target 1 lun 0 pass5: Removable Changer SCSI-2 device pass5: Serial Number ADIC910279414 pass5: 3.300MB/s transfers pass6 at ahc1 bus 0 target 3 lun 0 pass6: Removable Sequential Access SCSI-2 device pass6: Serial Number CX914M0098 pass6: 10.000MB/s transfers (10.000MHz, offset 15) da1 at ahc0 bus 0 target 8 lun 0 da1: Fixed Direct Access SCSI-3 device da1: Serial Number 111001140521 da1: 20.000MB/s transfers (10.000MHz, offset 8, 16bit), Tagged Queueing Enabled da1: 17357MB (35548320 512 byte sectors: 255H 63S/T 2212C) da3 at sym0 bus 0 target 2 lun 0 da3: Fixed Direct Access SCSI-2 device da3: 10.000MB/s transfers (10.000MHz, offset 8) da3: 516MB (1057758 512 byte sectors: 64H 32S/T 516C) da2 at sym0 bus 0 target 0 lun 0 da2: Fixed Direct Access SCSI-2 device da2: Serial Number 399702418491 da2: 10.000MB/s transfers (10.000MHz, offset 8), Tagged Queueing Enabled da2: 2014MB (4124736 512 byte sectors: 255H 63S/T 256C) da0 at ahc0 bus 0 target 0 lun 0 da0: Fixed Direct Access SCSI-2 device da0: Serial Number RE460219 da0: 40.000MB/s transfers (20.000MHz, offset 8, 16bit), Tagged Queueing Enabled da0: 8715MB (17850000 512 byte sectors: 255H 63S/T 1111C) Mounting root from ufs:/dev/da2s1a da2s1: type 0xa5, start 63, end = 4124735, size 4124673 : OK ch0 at ahc1 bus 0 target 1 lun 0 ch0: Removable Changer SCSI-2 device ch0: Serial Number ADIC910279414 ch0: 3.300MB/s transfers ch0: 7 slots, 1 drive, 1 picker, 0 portals start_init: trying /sbin/init da1s1: type 0xa5, start 0, end = 35548319, size 35548320 : OK da0s1: type 0xa5, start 0, end = 17849999, size 17850000 : OK da3s1: type 0xa5, start 0, end = 1057757, size 1057758 da3s1: C/H/S end 581/83/6 (293327) != end 1057757: invalid da3s1: type 0xa5, start 0, end = 1057757, size 1057758 da3s1: C/H/S end 581/83/6 (293327) != end 1057757: invalid da1s1: type 0xa5, start 0, end = 35548319, size 35548320 : OK da0s1: type 0xa5, start 0, end = 17849999, size 17850000 : OK de0: starting DAD for fe80:0001::0200:c0ff:fe6a:69f9 de0: DAD complete for fe80:0001::0200:c0ff:fe6a:69f9 - no duplicates found (da2:sym0:0:0:0): tagged openings now 18 (da2:sym0:0:0:0): tagged openings now 17 (da2:sym0:0:0:0): tagged openings now 16 (da2:sym0:0:0:0): tagged openings now 15 (cd0:sym0:0:6:0): READ CD RECORDED CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 (cd0:sym0:0:6:0): NOT READY asc:3a,0 (cd0:sym0:0:6:0): Medium not present cd0 at sym0 bus 0 target 6 lun 0 cd0: Removable CD-ROM SCSI-2 device cd0: 10.000MB/s transfers (10.000MHz, offset 8) cd0: Attempt to query device size failed: NOT READY, Medium not present To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-scsi" in the body of the message