April 2001 - Packet Writing - openSUSE Mailing Lists

debugging hard lockups
by Jens Axboe 23 Apr '01

23 Apr '01

Hi, I think most of the hard hangs people are seeing are actually induced on purpose. The BUG() will hard hang a machine at times, usually because interrupts are disabled or just because we are holding a spin lock. Because of that, to debug a hard hang without a serial console you can do something like... : #undef BUG #define BUG() do { \ printk("kernel BUG at %s:%d!\n", __FILE__, __LINE__); \ } while (0); right before the SCSI_IOCTL_SEND_COMMAND define in drivers/block/pktcdvd.c and recompile the kernel/module. _If_ you choose to do so, look for these messages in your logs repeatedly -- I definitely don't want to miss one of them, that's why they are BUG's now. -- Jens Axboe

1 0

packet-0.0.2i-pre2
by Jens Axboe 23 Apr '01

23 Apr '01

Hi, I put up a 0.0.2i-pre2 patch, which is basically what will be the final version. It has all the fixes and changes that I want in the final, but I figured I'd let it spin for a day before releasing final 0.0.2i. List of changes from 0.0.2h so far: - Fix PKT_BUF_LIST error in fs/buffer.c - Fix pkt_find_dev in two spots, use q->queuedata in pkt_elevator_merge. - introduce pkt_make_request as READ remapper (and eventually add block remapper for WRITEs too). - Loose READ/WRITE checks in pkt_elevator_merge and pkt_request, they don't deal with anything but WRITEs after above change. - Fix pending request count in proc reading. - Fix increment usage bug for failed FMODE_WRITE opens. - Loose pd_lock/unlock, use io_request_lock for now. When block switches to per-queue lock, a search/replace operation will be sufficient. - Start to handle buffers non-uniform size buffers in a WRITE request. - Browse buffer cache hash table for possible bh victims - Fix blk_started_io to account buffers from buffer cache too - Do hole merging in regular elevator path - Fix bh_rq_in_between to look in the right direction - Remove sequencing from pkt_elevator - Use bh_cachep slab cache for private pool of buffer_heads - Remove old BH_Packet flag - Default udf to 2kB blocksize - Remove __getblk again Get it here: *.kernel.org/pub/linux/kernel/people/axboe/packet/ while it's hot. 0.0.2i-pre1 -> 0.0.2i-pre2 patch attached. -- Jens Axboe

4 11

Oops with latest patch
by Adam Huffman 23 Apr '01

23 Apr '01

Had multiple Oopsen when copying to a newly-formatted and mounted disc. Here is the first one: pktcdvd: inserted media is CD-RW pktcdvd: Fixed packets, 32 blocks / packet, Mode-2 disc pktcdvd: enabled write caching on pktcdvd0 pktcdvd: speed (R/W) 6/4 pktcdvd: 452288kB available on disc Incorrect segment count at 0xc01c2af7nr_segments is 4 counted segments is 6 Flags 1 0 Segment 0xc3049f80, blocks 4, addr 0x2ec17ff Segment 0xc3049f20, blocks 4, addr 0x2ee5fff Segment 0xc3049ec0, blocks 4, addr 0x2ee67ff Segment 0xc3049da0, blocks 4, addr 0x1675fff Segment 0xc3049d40, blocks 4, addr 0x16767ff Segment 0xc3049b00, blocks 4, addr 0x2c71fff Segment 0xc3049c80, blocks 4, addr 0x2c727ff Segment 0xc3049a40, blocks 4, addr 0x2cc2fff Kernel panic: Ththththaats all folks. Too dangerous to continue. ksymoops -m /boot/System.map-2.4.4-pre5 < udf-oops-24-04-2001 ksymoops 2.3.5 on i586 2.4.4-pre5. Options used -V (default) -k /proc/ksyms (default) -l /proc/modules (default) -o /lib/modules/2.4.4-pre5/ (default) -m /boot/System.map-2.4.4-pre5 (specified) Warning (compare_maps): ksyms_base symbol __VERSIONED_SYMBOL(shmem_file_setup) not found in System.map. Ignoring ksyms_base entry invalid operand: 0000 CPU: 0 EIP: 0010:[<c11e4336>] Using defaults from ksymoops -t elf32-i386 -a i386 EFLAGS: 00010002 eax: c2f1f074 ebx: 00000001 ecx: c2f1f000 edx: 00000001 esi: c1155860 edi: c30497a0 ebp: c2f1f074 esp: c115fd50 ds: 0018 es: 0018 ss: 0018 Process bdflush (pid: 6, stackpage=c115f000) Stack: c4833f4a c2f1f074 00000001 c30497a0 c30497a0 00000004 00000184 00006100 c017e9ea c2f1f074 00000001 c30497a0 00000000 c0192d99 00000001 c30497a0 c30497a0 00000004 00000001 00000001 c017ea5d 00000001 c30497a0 c30497a0 Call Trace: [<c4833f4a>] [<c017e9ea>] [<c0192d99>] [<c017ea5d>] [<c017ece7>] [<c019d09b>] [<c019d0bd>] [<c012f784>] [<c0112316>] [<c012f8a3>] [<c012f8c7>] [<c0111de5>] [<c01c2b46>] [<c01c2af7>] [<c4832267>] [<c01c2ab0>] [<c017e333>] [<c01afe80>] [<c01b6e40>] [<c0107fca>] [<c4833f4a>] [<c017e9ea>] [<c017ea5d>] [<c017ece7>] [<c0132508>] [<c013272c>] [<c0105000>] [<c0105000>] [<c0105496>] [<c01326b0>] Code: ff ff 00 08 00 00 00 00 00 00 00 00 00 00 00 00 00 00 02 00 P; c11e4336 <_end+eae51e/44e1248> <===== Trace; c4833f4a <[pktcdvd]pkt_make_request+5a/70> Trace; c017e9ea <generic_make_request+14a/160> Trace; c0192d99 <scrup+69/110> Trace; c017ea5d <submit_bh+5d/90> Trace; c017ece7 <ll_rw_block+207/250> Trace; c019d09b <serial_console_write+11b/1b0> Trace; c019d0bd <serial_console_write+13d/1b0> Trace; c012f784 <sync_buffers+c4/1a0> Trace; c0112316 <printk+156/170> Trace; c012f8a3 <fsync_dev+13/30> Trace; c012f8c7 <sys_sync+7/10> Trace; c0111de5 <panic+65/e0> Trace; c01c2b46 <scsi_back_merge_fn_c+96/f0> Trace; c01c2af7 <scsi_back_merge_fn_c+47/f0> Trace; c4832267 <[pktcdvd]pkt_back_merge_fn+97/b0> Trace; c01c2ab0 <scsi_back_merge_fn_c+0/f0> Trace; c017e333 <__make_request+183/6f0> Trace; c01afe80 <ide_intr+f0/150> Trace; c01b6e40 <read_intr+0/160> Trace; c0107fca <handle_IRQ_event+3a/70> Trace; c4833f4a <[pktcdvd]pkt_make_request+5a/70> Trace; c017e9ea <generic_make_request+14a/160> Trace; c017ea5d <submit_bh+5d/90> Trace; c017ece7 <ll_rw_block+207/250> Trace; c0132508 <flush_dirty_buffers+78/c0> Trace; c013272c <bdflush+7c/e0> Trace; c0105000 <init+0/130> Trace; c0105000 <init+0/130> Trace; c0105496 <kernel_thread+26/30> Trace; c01326b0 <bdflush+0/e0> Code; c11e4336 <_end+eae51e/44e1248> 00000000 <_EIP>: Code; c11e4336 <_end+eae51e/44e1248> <===== 0: ff (bad) <===== Code; c11e4337 <_end+eae51f/44e1248> 1: ff 00 incl (%eax) Code; c11e4339 <_end+eae521/44e1248> 3: 08 00 or %al,(%eax) Code; c11e4347 <_end+eae52f/44e1248> 11: 00 02 add %al,(%edx) 1 warning issued. Results may not be reliable. then a kernel message: rq->cmd=1, rq->sector=10768, rq->nr_sectors=112 kernel BUG at pktcdvd.c:995! then another Oops: Warning (compare_maps): ksyms_base symbol __VERSIONED_SYMBOL(shmem_file_setup) not found in System.map. Ignoring ksyms_base entry invalid operand: 0000 CPU: 0 EIP: 0010:[<c483312a>] Using defaults from ksymoops -t elf32-i386 -a i386 EFLAGS: 00010086 eax: 0000001d ebx: c3b5ff60 ecx: c2556000 edx: c02b1624 esi: 00002a10 edi: c1155860 ebp: c2f1f074 esp: c2d9bf88 ds: 0018 es: 0018 ss: 0018 Process pktcdvd0 (pid: 926, stackpage=c2d9b000) Stack: c4834d34 c4834e69 000003e3 c2d9a000 00000000 c115e000 c0305540 c2d9a000 c2f1f074 c2f1f000 c1155878 c4832ff4 c2f1f074 c2f1f09c 00000000 c2d9a000 00000000 00000000 00000000 c2d9a000 c2f1f110 c2f1f110 00000f00 c13f1f1c Call Trace: [<c4834d34>] [<c4834e69>] [<c4832ff4>] [<c0105496>] [<c4832f00>] Code: 0f 0b 83 c4 0c b8 06 00 00 00 0f ab 47 48 19 c0 85 c0 75 1e >>EIP; c483312a <[pktcdvd]pkt_request+ca/120> <===== Trace; c4834d34 <[pktcdvd].rodata.start+34/eff> Trace; c4834e69 <[pktcdvd].rodata.start+169/eff> Trace; c4832ff4 <[pktcdvd]kcdrwd+f4/160> Trace; c0105496 <kernel_thread+26/30> Trace; c4832f00 <[pktcdvd]kcdrwd+0/160> Code; c483312a <[pktcdvd]pkt_request+ca/120> 00000000 <_EIP>: Code; c483312a <[pktcdvd]pkt_request+ca/120> <===== 0: 0f 0b ud2a <===== Code; c483312c <[pktcdvd]pkt_request+cc/120> 2: 83 c4 0c add $0xc,%esp Code; c483312f <[pktcdvd]pkt_request+cf/120> 5: b8 06 00 00 00 mov $0x6,%eax Code; c4833134 <[pktcdvd]pkt_request+d4/120> a: 0f ab 47 48 bts %eax,0x48(%edi) Code; c4833138 <[pktcdvd]pkt_request+d8/120> e: 19 c0 sbb %eax,%eax Code; c483313a <[pktcdvd]pkt_request+da/120> 10: 85 c0 test %eax,%eax Code; c483313c <[pktcdvd]pkt_request+dc/120> 12: 75 1e jne 32 <_EIP+0x32> c483315c <[pktcdvd]pkt_request+fc/120> 1 warning issued. Results may not be reliable. and another one: Warning (compare_maps): ksyms_base symbol __VERSIONED_SYMBOL(shmem_file_setup) not found in System.map. Ignoring ksyms_base entry Unable to handle kernel NULL pointer dereference at virtual address 00000000 00000000 *pde = 00000000 Oops: 0000 CPU: 0 EIP: 0010:[<00000000>] Using defaults from ksymoops -t elf32-i386 -a i386 EFLAGS: 00010282 eax: c03224e0 ebx: c2c59e40 ecx: 00000000 edx: 00000000 esi: 00000004 edi: 00000184 ebp: 00000000 esp: c13f1bb4 ds: 0018 es: 0018 ss: 0018 Process cp (pid: 930, stackpage=c13f1000) Stack: c017e9ea c03224e0 00000000 c2c59e40 00000006 00000003 00000006 c2c59e40 c2c59e40 00000004 00000000 00000000 c017ea5d 00000000 c2c59e40 c2c59e40 00000000 c2c59e40 c017ece7 00000000 c2c59e40 c13f0000 c2c59e40 c2c59e40 Call Trace: [<c017e9ea>] [<c017ea5d>] [<c017ece7>] [<c01307f5>] [<c48445d9>] [<c48445eb>] [<c0198fe6>] [<c019924e>] [<c0107fca>] [<c010814d>] [<c010816c>] [<c0106ce0>] [<c4841bf0>] [<c01ae698>] [<c01b6f81>] [<c01b6e40>] [<c01ae698>] [<c01b6f81>] [<c01b6e40>] [<c0107fca>] [<c48418ac>] [<c0130f57>] [<c0121120>] [<c01316b1>] [<c4841800>] [<c0123cd0>] [<c4841800>] [<c0139a53>] [<cd000000>] [<c484087c>] [<c012e9a6>] [<c0106c23>] Code: Bad EIP value. >>EIP; 00000000 Before first symbol Trace; c017e9ea <generic_make_request+14a/160> Trace; c017ea5d <submit_bh+5d/90> Trace; c017ece7 <ll_rw_block+207/250> Trace; c01307f5 <bread+35/70> Trace; c48445d9 <[udf]udf_next_aext+3d/418> Trace; c48445eb <[udf]udf_next_aext+4f/418> Trace; c0198fe6 <transmit_chars+d6/e0> Trace; c019924e <rs_interrupt_single+6e/90> Trace; c0107fca <handle_IRQ_event+3a/70> Trace; c010814d <do_IRQ+6d/b0> Trace; c010816c <do_IRQ+8c/b0> Trace; c0106ce0 <ret_from_intr+0/20> Trace; c4841bf0 <[udf]inode_getblk+204/77c> Trace; c01ae698 <ide_set_handler+58/60> Trace; c01b6f81 <read_intr+141/160> Trace; c01b6e40 <read_intr+0/160> Trace; c01ae698 <ide_set_handler+58/60> Trace; c01b6f81 <read_intr+141/160> Trace; c01b6e40 <read_intr+0/160> Trace; c0107fca <handle_IRQ_event+3a/70> Trace; c48418ac <[udf]udf_get_block+ac/12c> Trace; c0130f57 <__block_prepare_write+e7/260> Trace; c0121120 <add_to_page_cache_unique+d0/e0> Trace; c01316b1 <block_prepare_write+21/40> Trace; c4841800 <[udf]udf_get_block+0/12c> Trace; c0123cd0 <generic_file_write+3d0/560> Trace; c4841800 <[udf]udf_get_block+0/12c> Trace; c0139a53 <open_namei+353/5d0> Trace; cd000000 <END_OF_CODE+87aec61/??? Trace; c484087c <[udf]udf_file_write+f8/12c> Trace; c012e9a6 <sys_write+96/d0> Trace; c0106c23 <system_call+33/40> 1 warning issued. Results may not be reliable. Most of the rest of them are kernel BUG at exit.c:458! Hardware is a Yamaha 8424 internal SCSI on an Adaptec 2904. Adam

2 3

Latest patch
by Adam Huffman 21 Apr '01

21 Apr '01

Compiled and installed the latest patch now, made a new UDF disc and tried to mount it, but each time I get the following error: UDF-fs: No partition found (1) The same disc can be mounted (read-only) on another box, using the normal kernel udf support. Adam

2 5

packet-0.0.2i-pre1
by Jens Axboe 21 Apr '01

21 Apr '01

Hi, Due to the stupid error in 0.0.2h, I put up a 0.0.2i-pre1. There's also a 2h -> 2i-pre1 patch since it's so small, attached here as well. The pre versions will only be put on kernel.org, brw. -- Jens Axboe

1 0

packet-0.0.2h
by Jens Axboe 20 Apr '01

20 Apr '01

Hi, Put up a new release. It's against 2.4.3 and 2.4.4-pre5 -- in fact, the 2.4.3 patch includes all of the pre5 stuff. Get it from the sourceforge page, or from the kernel.org mirrors. I'll be back after the weekend, in the mean time enjoy and don't forget to have fun :-) -- Jens Axboe

4 7

Pseudo-RW-mount of multisession CD's
by Christian Gollwitzer 19 Apr '01

19 Apr '01

Dear guys, I was watching activities in this mailing list for a long time, 'cause I was interested in handling CD's like harddiscs. My first try with some early version of pktcdvd only caused the Kernel to Oops or crash, so I decided just to wait until there will be a stable version. But I found that with some simple shellscripts one can achieve similiar functionality with multisession CDs. I've attached them at this mail, if anyone of you wants to use them. How to install: 1. create the directories /cdmulti and /var/adm/cdmount with 777 permission 2. Adapt the variables DEVICE and RAWDEVICE DEVICE should point to your SCSI-CDROM-special file that is your writer, and RAWDEVICE should be the generic SCSI device. On my system (ide-scsi emulation) DEVICE is /dev/scd0 and RAWDEVICE is /dev/sg0 3. insert a blank disc ( CD-R or RW ) into your writer and execute multimount. You will get some message that it is a blank disc. Copy files you want to be written into /cdmulti ( you may also make symlinks), then execute multiumount. The files will be burned to disc, and the folder /cdmulti will be cleared. If you execute multimount with an already written disc, it gets really mounted to /var/adm/cdmount, and the program puts symlinks int /cdmulti. If you execute multiumount, the program compares with the original filelist and writes changes to the disc. There are yet some problems: *Empty directories are not allowed, they are omitted *Deleting files doesn't work, I haven't mastered to exclude only some old files from the old session *Deleting files would not free the space on the disc, it only makes the directory emtry disappear *They only run as root ( perhaps they are suid-safe ), though copying data inside the pseudo-mountpoint is possible for the non-root user, too *file names cannot contain whitespaces, this confuses the shell's for command Please let me know if you think these scripts are useful, or if you have any suggestions for the above problems -- Valete! Christianus Auriocus

1 0

It seems to be working!
by Graham Vincent 19 Apr '01

19 Apr '01

Hello. Attached file shows the results of my packet writing attempt to a CDRW formatted UDF using adaptec under Win98. The files all seem OK and can be read using Linux or Win98 but the logfile seems to be complaining about something? What's going on here and should I worry about it? Thanks to all those working on this project - I see this as one of the last hurdles to dumping windows altogether :-) Thanks, Graham Apr 7 17:07:23 hal syslogd 1.3-3: restart. Apr 7 17:07:23 hal syslog: syslogd startup succeeded Apr 7 17:07:23 hal syslog: klogd startup succeeded Apr 7 17:07:23 hal kernel: klogd 1.3-3, log source = /proc/kmsg started. Apr 7 17:07:23 hal kernel: Inspecting /boot/System.map-2.4.3 Apr 7 17:07:23 hal identd: identd startup succeeded Apr 7 17:07:23 hal kernel: Loaded 17708 symbols from /boot/System.map-2.4.3. Apr 7 17:07:23 hal kernel: Symbols match kernel version 2.4.3. Apr 7 17:07:23 hal kernel: No module symbols loaded. Apr 7 17:07:23 hal kernel: Linux version 2.4.3 (root(a)hal.gpv.co.nz) (gcc version egcs-2.91.66 19990314/Linux (egcs-1.1.2 release)) #6 Sat Apr 7 16:58:35 GMT+12 2001 snip ..... Apr 7 17:07:23 hal kernel: Kernel command line: BOOT_IMAGE=linux root=306 mem=128M Apr 7 17:07:23 hal kernel: Initializing CPU#0 Apr 7 17:07:23 hal kernel: Detected 731.475 MHz processor. Apr 7 17:07:23 hal kernel: Console: colour VGA+ 80x25 Apr 7 17:07:23 hal kernel: Calibrating delay loop... 1458.17 BogoMIPS Apr 7 17:07:23 hal kernel: Memory: 125956k/131072k available (1480k kernel code, 4728k reserved, 567k data, 224k init, 0k highmem) Apr 7 17:07:23 hal kernel: Dentry-cache hash table entries: 16384 (order: 5, 131072 bytes) Apr 7 17:07:23 hal kernel: Buffer-cache hash table entries: 8192 (order: 3, 32768 bytes) Apr 7 17:07:23 hal kernel: Page-cache hash table entries: 32768 (order: 5, 131072 bytes) Apr 7 17:07:23 hal kernel: Inode-cache hash table entries: 8192 (order: 4, 65536 bytes) Apr 7 17:07:23 hal kernel: CPU: L1 I cache: 16K, L1 D cache: 16K Apr 7 17:07:23 hal kernel: CPU: L2 cache: 256K Apr 7 17:07:23 hal kernel: Intel machine check architecture supported. Apr 7 17:07:23 hal kernel: Intel machine check reporting enabled on CPU#0. Apr 7 17:07:23 hal kernel: CPU: Intel Pentium III (Coppermine) stepping 03 Apr 7 17:07:23 hal kernel: Enabling fast FPU save and restore... done. Apr 7 17:07:23 hal kernel: Enabling unmasked SIMD FPU exception support... done. Apr 7 17:07:23 hal kernel: Checking 'hlt' instruction... OK. Apr 7 17:07:23 hal kernel: POSIX conformance testing by UNIFIX Apr 7 17:07:23 hal kernel: PCI: PCI BIOS revision 2.10 entry at 0xfb160, last bus=1 Apr 7 17:07:23 hal kernel: PCI: Using configuration type 1 Apr 7 17:07:23 hal kernel: PCI: Probing PCI hardware Apr 7 17:07:23 hal kernel: Unknown bridge resource 0: assuming transparent Apr 7 17:07:23 hal kernel: PCI: Using IRQ router VIA [1106/0596] at 00:07.0 Apr 7 17:07:23 hal kernel: Activating ISA DMA hang workarounds. snip ... Apr 7 17:07:23 hal kernel: Uniform Multi-Platform E-IDE driver Revision: 6.31 Apr 7 17:07:23 hal kernel: ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx Apr 7 17:07:23 hal kernel: VP_IDE: IDE controller on PCI bus 00 dev 39 Apr 7 17:07:23 hal kernel: VP_IDE: chipset revision 16 Apr 7 17:07:23 hal kernel: VP_IDE: not 100% native mode: will probe irqs later Apr 7 17:07:23 hal kernel: ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx Apr 7 17:07:23 hal kernel: VP_IDE: VIA vt82c596b (rev 23) IDE UDMA66 controller on pci00:07.1 Apr 7 17:07:23 hal kernel: ide0: BM-DMA at 0xd000-0xd007, BIOS settings: hda:DMA, hdb:pio Apr 7 17:07:23 hal kernel: ide1: BM-DMA at 0xd008-0xd00f, BIOS settings: hdc:DMA, hdd:DMA Apr 7 17:07:23 hal kernel: hda: ST320420A, ATA DISK drive Apr 7 17:07:23 hal kernel: hdc: Hewlett-Packard CD-Writer Plus 8200, ATAPI CD/DVD-ROM drive Apr 7 17:07:23 hal kernel: hdd: CD-ROM 52X/TKU, ATAPI CD/DVD-ROM drive Apr 7 17:07:23 hal kernel: ide0 at 0x1f0-0x1f7,0x3f6 on irq 14 Apr 7 17:07:23 hal kernel: ide1 at 0x170-0x177,0x376 on irq 15 Apr 7 17:07:23 hal kernel: hda: 39851760 sectors (20404 MB) w/2048KiB Cache, CHS=2480/255/63, UDMA(66) Apr 7 17:07:23 hal kernel: Partition check: Apr 7 17:07:23 hal kernel: hda: hda1 hda2 < hda5 hda6 > Apr 7 17:07:23 hal kernel: Floppy drive(s): fd0 is 1.44M Apr 7 17:07:23 hal kernel: FDC 0 is a post-1991 82077 Apr 7 17:07:23 hal kernel: udf: registering filesystem Apr 7 17:07:23 hal kernel: pktcdvd: v0.0.2g 29/03/2001 Jens Axboe (axboe(a)suse.de) Apr 7 17:07:23 hal kernel: Serial driver version 5.05 (2000-12-13) with MANY_PORTS SHARE_IRQ SERIAL_PCI ISAPNP enabled Apr 7 17:07:23 hal kernel: ttyS00 at 0x03f8 (irq = 4) is a 16550A Apr 7 17:07:23 hal kernel: ttyS01 at 0x02f8 (irq = 3) is a 16550A Apr 7 17:07:23 hal kernel: PPP generic driver version 2.4.1 Apr 7 17:07:23 hal kernel: PPP Deflate Compression module registered Apr 7 17:07:23 hal kernel: PPP BSD Compression module registered Apr 7 17:07:23 hal kernel: 8139too Fast Ethernet driver 0.9.15c loaded Apr 7 17:07:23 hal kernel: PCI: Found IRQ 11 for device 00:08.0 Apr 7 17:07:23 hal kernel: eth0: RealTek RTL8139 Fast Ethernet at 0xcb802000, 00:50:ba:8a:42:15, IRQ 11 Apr 7 17:07:23 hal kernel: Linux agpgart interface v0.99 (c) Jeff Hartmann Apr 7 17:07:23 hal kernel: agpgart: Maximum main memory to use for agp memory: 96M Apr 7 17:07:23 hal kernel: agpgart: Detected Via Apollo Pro chipset Apr 7 17:07:23 hal kernel: agpgart: AGP aperture is 64M @ 0xd8000000 Apr 7 17:07:23 hal kernel: SCSI subsystem driver Revision: 1.00 Apr 7 17:07:23 hal kernel: scsi0 : SCSI host adapter emulation for IDE ATAPI devices Apr 7 17:07:23 hal kernel: Vendor: HP Model: CD-Writer+ 8200 Rev: 1.0f Apr 7 17:07:23 hal kernel: Type: CD-ROM ANSI SCSI revision: 02 Apr 7 17:07:23 hal kernel: Vendor: E-IDE Model: CD-ROM 52X/TKU Rev: F51 Apr 7 17:07:23 hal kernel: Type: CD-ROM ANSI SCSI revision: 02 Apr 7 17:07:23 hal kernel: Detected scsi CD-ROM sr0 at scsi0, channel 0, id 0, lun 0 Apr 7 17:07:23 hal kernel: Detected scsi CD-ROM sr1 at scsi0, channel 0, id 1, lun 0 Apr 7 17:07:23 hal kernel: sr0: scsi3-mmc drive: 8x/24x writer cd/rw xa/form2 cdda tray Apr 7 17:07:23 hal kernel: Uniform CD-ROM driver Revision: 3.12 Apr 7 17:07:23 hal kernel: sr1: scsi3-mmc drive: 1x/52x cd/rw xa/form2 cdda tray snip ... Apr 7 17:08:50 hal kernel: pktcdvd: writer sr0 sucessfully registered Apr 7 17:08:50 hal kernel: pktcdvd: kernel thread pktcdvd0 started Apr 7 17:09:14 hal kernel: pktcdvd: inserted media is CD-RW Apr 7 17:09:14 hal kernel: pktcdvd: Fixed packets, 32 blocks / packet, Mode-2 disc Apr 7 17:09:14 hal kernel: pktcdvd: disabled write caching on pktcdvd0 Apr 7 17:09:15 hal kernel: pktcdvd: speed (R/W) 6/4 Apr 7 17:09:15 hal kernel: pktcdvd: 551488KB available on disc Apr 7 17:09:16 hal kernel: UDF-fs INFO UDF 0.9.3-rw (2000/12/25) Mounting volume 'gpv', timestamp 2001/04/06 12:55 (1d30) Apr 7 17:10:08 hal kernel: different zone: prq->sector=10112, rq->sector=9856 Apr 7 17:10:58 hal kernel: different zone: prq->sector=9856, rq->sector=1408 Apr 7 17:10:59 hal kernel: different zone: prq->sector=1408, rq->sector=10112 Apr 7 17:11:00 hal kernel: different zone: prq->sector=10112, rq->sector=10244 Apr 7 17:11:00 hal kernel: different zone: prq->sector=10244, rq->sector=49376 Apr 7 17:11:00 hal kernel: pkt_recheck_segments: rq->nr_segments=31, nr_segments=17 Apr 7 17:11:01 hal kernel: different zone: prq->sector=49376, rq->sector=49408 Apr 7 17:11:01 hal kernel: pkt_recheck_segments: rq->nr_segments=12, nr_segments=9 Apr 7 17:11:01 hal kernel: different zone: prq->sector=49408, rq->sector=49536 Apr 7 17:11:01 hal kernel: different zone: prq->sector=49536, rq->sector=49664 Apr 7 17:11:01 hal kernel: different zone: prq->sector=49664, rq->sector=49792 Apr 7 17:11:01 hal kernel: different zone: prq->sector=49792, rq->sector=49920 Apr 7 17:11:01 hal kernel: different zone: prq->sector=49920, rq->sector=50048 Apr 7 17:11:01 hal kernel: different zone: prq->sector=50048, rq->sector=50176 ... lots of this with steadily increasing numbers... Apr 7 17:11:24 hal kernel: different zone: prq->sector=63104, rq->sector=63232 Apr 7 17:11:24 hal kernel: different zone: prq->sector=63232, rq->sector=63360 Apr 7 17:11:24 hal kernel: different zone: prq->sector=63360, rq->sector=63488 Apr 7 17:11:24 hal kernel: different zone: prq->sector=63488, rq->sector=63616 Apr 7 17:11:24 hal kernel: different zone: prq->sector=63616, rq->sector=63744 Apr 7 17:11:26 hal kernel: pkt_recheck_segments: rq->nr_segments=32, nr_segments=24 Apr 7 17:11:28 hal kernel: different zone: prq->sector=63812, rq->sector=63872 Apr 7 17:11:29 hal kernel: pkt_recheck_segments: rq->nr_segments=18, nr_segments=11 Apr 7 17:11:29 hal kernel: different zone: prq->sector=63872, rq->sector=64000 Apr 7 17:11:29 hal kernel: different zone: prq->sector=64000, rq->sector=64128 Apr 7 17:11:29 hal kernel: different zone: prq->sector=64128, rq->sector=64256 Apr 7 17:11:29 hal kernel: different zone: prq->sector=64256, rq->sector=64384 Apr 7 17:11:29 hal kernel: different zone: prq->sector=64384, rq->sector=64512 Apr 7 17:11:29 hal kernel: different zone: prq->sector=64512, rq->sector=64640 Apr 7 17:11:29 hal kernel: different zone: prq->sector=64640, rq->sector=64768 Apr 7 17:11:29 hal kernel: different zone: prq->sector=64768, rq->sector=64896 Apr 7 17:11:29 hal kernel: different zone: prq->sector=64896, rq->sector=65024 Apr 7 17:11:29 hal kernel: different zone: prq->sector=65024, rq->sector=65152 Apr 7 17:11:29 hal kernel: different zone: prq->sector=65152, rq->sector=65280 Apr 7 17:11:29 hal kernel: different zone: prq->sector=65280, rq->sector=65408 Apr 7 17:11:29 hal kernel: different zone: prq->sector=65408, rq->sector=65536 Apr 7 17:11:29 hal kernel: different zone: prq->sector=65536, rq->sector=65664 Apr 7 17:11:29 hal kernel: different zone: prq->sector=65664, rq->sector=65792 Apr 7 17:11:29 hal kernel: different zone: prq->sector=65792, rq->sector=65920 Apr 7 17:11:29 hal kernel: different zone: prq->sector=65920, rq->sector=66048 Apr 7 17:11:31 hal kernel: pkt_recheck_segments: rq->nr_segments=31, nr_segments=21 Apr 7 17:12:04 hal kernel: pktcdvd: speed (R/W) 6/4 Apr 7 17:12:04 hal kernel: pktcdvd: thread got SIGKILL Apr 7 17:12:04 hal kernel: pktcdvd: kernel thread pktcdvd0 stopped Apr 7 17:12:04 hal kernel: pktcdvd: writer sr0 unregistered snip ...

3 2

Best Rates in Years #6141
by Brad Edwards 09 Apr '01

09 Apr '01

***** This is an HTML Message ! *****

1 0

compile problem, not in packet writing
by Mark Vytlacil 06 Apr '01

06 Apr '01

In my tests, it seemed like anything that flushed the buffers to the writer caused a system hang. After a long series of runs with kgdb, I know why. My ide-cd.o module isn't compiled right. I was using gcc 2.95.2 and tried gcc 2.95.3 but I think it is even worse. Aside from reporting the bug to the gcc people, any suggestions? Following is a partial disassembly of ide-cd.o: /* Now loop while we still have data to read from the drive. DMA * transfers will already have been complete */ while (sectors_to_transfer > 0) { 16bb: 85 db test %ebx,%ebx 16bd: 7e 40 jle 16ff <cdrom_write_intr+0x223> 16bf: 90 nop /* If we've filled the present buffer but there's another chained buffer after it, move on. */ if (rq->current_nr_sectors == 0 && rq->nr_sectors > 0) 16c0: 83 7e 3c 00 cmpl $0x0,0x3c(%esi) 16c4: 75 14 jne 16da <cdrom_write_intr+0x1fe> 16c6: 83 7e 28 00 cmpl $0x0,0x28(%esi) 16ca: 74 0e je 16da <cdrom_write_intr+0x1fe> cdrom_end_request(1, drive); 16cc: 8b 4d 08 mov 0x8(%ebp),%ecx 16cf: 51 push %ecx 16d0: 6a 01 push $0x1 16d2: e8 81 ed ff ff call 458 <cdrom_end_request> 16d7: 83 c4 08 add $0x8,%esp atapi_output_bytes(drive, rq->buffer, rq->current_nr_sectors); 16da: 8b 46 3c mov 0x3c(%esi),%eax 16dd: 50 push %eax 16de: 8b 46 44 mov 0x44(%esi),%eax 16e1: 50 push %eax 16e2: 8b 45 08 mov 0x8(%ebp),%eax 16e5: 50 push %eax 16e6: e8 fc ff ff ff call 16e7 <cdrom_write_intr+0x20b> rq->nr_sectors -= rq->current_nr_sectors; 16eb: 8b 46 3c mov 0x3c(%esi),%eax 16ee: 29 46 28 sub %eax,0x28(%esi) rq->current_nr_sectors = 0; 16f1: c7 46 3c 00 00 00 00 movl $0x0,0x3c(%esi) rq->sector += rq->current_nr_sectors; sectors_to_transfer -= rq->current_nr_sectors; } 16f8: 83 c4 0c add $0xc,%esp 16fb: 85 db test %ebx,%ebx 16fd: 7f c1 jg 16c0 <cdrom_write_intr+0x1e4> No code gets compiled for the lines: rq->sector += rq->current_nr_sectors; sectors_to_transfer -= rq->current_nr_sectors; I am pretty sure that the first of these compiled under 2.95.2, but in any case the loop never ends. BTW, the comment indicates that we are reading from the drive, but the code is really from cdrom_write_intr(). Mark

2 4