kern/34711: Re: umass problem with ipod

[john heasley <heas%shrubbery.net@localhost>]

Sun, Jan 18, 2009 at 02:45:34PM +0200, Andreas Gustafsson:
> john heasley wrote:
> > Is anyone else seeing the following problem?
> > [...]
> > sd0 at scsibus0 target 0 lun 0: <Apple, iPod, 1.62> disk removable
> > sd0(umass0:0:0:0):  Check Condition on CDB: 0x00 00 00 00 00 00
> >     SENSE KEY:  Not Ready
> >      ASC/ASCQ:  Logical Unit Is in Process Of Becoming Ready
> 
> kern/34711 (or to be precise, the 24 Jan 2007 followup message thereto).

I spent some time late friday with different kernel scsi & usb debug options.
I really wanted to add some new files, so I swapped in the windows laptop
drive.  windows didn't like the filesystem!  I allowed it to reformat the
ipod.  Swapped the drive back and it works with netbsd once again.

Maybe you too can try reformatting/fdisk from NetBSD.

Here's the scsi debug o/p before the reformat, which I haven't gotten very
far with debugging:

May 16 05:33:23 roome /netbsd: umass0 at uhub3 port 4 configuration 1 interface 0
May 16 05:33:23 roome /netbsd: 
May 16 05:33:23 roome /netbsd: umass0: Apple Inc. iPod, rev 2.00/0.01, addr 2
May 16 05:33:23 roome /netbsd: umass0: using SCSI over Bulk-Only
May 16 05:33:23 roome /netbsd: scsibus0 at umass0: 2 targets, 1 lun per target
May 16 05:33:23 roome /netbsd: umass0: at uhub3 port 4 (addr 2) disconnected
May 16 05:33:23 roome /netbsd: probe(umass0:0:0:0): scsipi_get_xs
May 16 05:33:23 roome /netbsd: probe(umass0:0:0:0): calling pool_get
May 16 05:33:23 roome /netbsd: probe(umass0:0:0:0): returning
May 16 05:33:23 roome /netbsd: probe(umass0:0:0:0): scsi_scsipi_cmd
May 16 05:33:23 roome /netbsd: scsipi_execute_xs: xs(0xc2207ebc): 
xs_control(0x00401024)xs_status(0x00000000)periph(0xc22e1000)retr(0x0)timo(0x2710)cmd(0xc2207f50)len(0x6)data(0xcd030c4a)len(0x24)res(0x24)err(0x0)bp(0x0)probe(umass0:0:0:0):
  command: 0x12,0x0,0x0,0x0,0x24,0x0-[36 bytes]
May 16 05:33:23 roome /netbsd: ------------------------------
May 16 05:33:23 roome /netbsd: 000: 00 00 00 00 00 00 00 00 20 20 20 20 20 20 
20 20 
May 16 05:33:23 roome /netbsd: 016: 20 20 20 20 20 20 20 20 20 20 20 20 20 20 
20 20 
May 16 05:33:23 roome /netbsd: 032: 20 20 20 20 
May 16 05:33:23 roome /netbsd: ------------------------------
May 16 05:33:23 roome /netbsd: 
May 16 05:33:23 roome /netbsd: probe(umass0:0:0:0): scsipi_done
May 16 05:33:23 roome /netbsd: probe(umass0:0:0:0):  command: 
0x12,0x0,0x0,0x0,0x24,0x0-[36 bytes]
May 16 05:33:23 roome /netbsd: ------------------------------
May 16 05:33:23 roome /netbsd: 000: 00 00 00 00 00 00 00 00 20 20 20 20 20 20 
20 20 
May 16 05:33:23 roome /netbsd: 016: 20 20 20 20 20 20 20 20 20 20 20 20 20 20 
20 20 
May 16 05:33:23 roome /netbsd: 032: 20 20 20 20 
May 16 05:33:23 roome /netbsd: ------------------------------
May 16 05:33:23 roome /netbsd: probe(umass0:0:0:0): generic HBA error
May 16 05:33:23 roome /netbsd: probe(umass0:0:0:0): scsipi_free_xs
May 16 05:33:23 roome /netbsd: scsibus0: detached
May 16 05:33:23 roome /netbsd: umass0: detached
May 16 05:33:24 roome /netbsd: umass0 at uhub3 port 4 configuration 1 interface 0
May 16 05:33:24 roome /netbsd: 
May 16 05:33:24 roome /netbsd: umass0: Apple Inc. iPod, rev 2.00/0.01, addr 2
May 16 05:33:24 roome /netbsd: umass0: using SCSI over Bulk-Only
May 16 05:33:24 roome /netbsd: scsibus0 at umass0: 2 targets, 1 lun per target
May 16 05:33:24 roome /netbsd: probe(umass0:0:0:0): scsipi_get_xs
May 16 05:33:24 roome /netbsd: probe(umass0:0:0:0): calling pool_get
May 16 05:33:24 roome /netbsd: probe(umass0:0:0:0): returning
May 16 05:33:24 roome /netbsd: probe(umass0:0:0:0): scsi_scsipi_cmd
May 16 05:33:24 roome /netbsd: scsipi_execute_xs: xs(0xc2207ebc): 
xs_control(0x00401024)xs_status(0x00000000)periph(0xc236a600)retr(0x0)timo(0x2710)cmd(0xc2207f50)len(0x6)data(0xcbe5cc4a)len(0x24)res(0x24)err(0x0)bp(0x0)probe(umass0:0:0:0):
  command: 0x12,0x0,0x0,0x0,0x24,0x0-[36 bytes]
May 16 05:33:24 roome /netbsd: ------------------------------
May 16 05:33:24 roome /netbsd: 000: 00 00 00 00 00 00 00 00 20 20 20 20 20 20 
20 20 
May 16 05:33:24 roome /netbsd: 016: 20 20 20 20 20 20 20 20 20 20 20 20 20 20 
20 20 
May 16 05:33:24 roome /netbsd: 032: 20 20 20 20 
May 16 05:33:24 roome /netbsd: ------------------------------
May 16 05:33:24 roome /netbsd: 
May 16 05:33:24 roome /netbsd: probe(umass0:0:0:0): scsipi_done
May 16 05:33:24 roome /netbsd: probe(umass0:0:0:0):  command: 
0x12,0x0,0x0,0x0,0x24,0x0-[36 bytes]
May 16 05:33:24 roome /netbsd: ------------------------------
May 16 05:33:24 roome /netbsd: 000: 00 80 00 00 33 00 00 00 41 70 70 6c 65 20 
20 20 
May 16 05:33:24 roome /netbsd: 016: 69 50 6f 64 20 20 20 20 20 20 20 20 20 20 
20 20 
May 16 05:33:24 roome /netbsd: 032: 31 2e 36 32 
May 16 05:33:24 roome /netbsd: ------------------------------
May 16 05:33:24 roome /netbsd: probe(umass0:0:0:0): scsipi_free_xs
May 16 05:33:24 roome /netbsd: probe(umass0:0:0:0): scsipi_get_xs
May 16 05:33:24 roome /netbsd: probe(umass0:0:0:0): calling pool_get
May 16 05:33:24 roome /netbsd: probe(umass0:0:0:0): returning
May 16 05:33:24 roome /netbsd: probe(umass0:0:0:0): scsi_scsipi_cmd
May 16 05:33:24 roome /netbsd: scsipi_execute_xs: xs(0xc2207ebc): 
xs_control(0x00401024)xs_status(0x00000000)periph(0xc236a600)retr(0x0)timo(0x2710)cmd(0xc2207f50)len(0x6)data(0xcbe5cc4a)len(0x4a)res(0x4a)err(0x0)bp(0x0)probe(umass0:0:0:0):
  command: 0x12,0x0,0x0,0x0,0x4a,0x0-[74 bytes]
May 16 05:33:24 roome /netbsd: ------------------------------
May 16 05:33:24 roome /netbsd: 000: 00 80 00 00 33 00 00 00 41 70 70 6c 65 20 
20 20 
May 16 05:33:24 roome /netbsd: 016: 69 50 6f 64 20 20 20 20 20 20 20 20 20 20 
20 20 
May 16 05:33:24 roome /netbsd: 032: 31 2e 36 32 00 00 00 00 00 00 00 00 00 00 
00 00 
May 16 05:33:24 roome /netbsd: 048: 00 00 00 00 00 00 00 00 00 00 20 20 20 20 
20 20 
May 16 05:33:24 roome /netbsd: ------------------------------
May 16 05:33:24 roome /netbsd: 
May 16 05:33:24 roome /netbsd: probe(umass0:0:0:0): scsipi_done
May 16 05:33:24 roome /netbsd: probe(umass0:0:0:0):  command: 
0x12,0x0,0x0,0x0,0x4a,0x0-[74 bytes]
May 16 05:33:24 roome /netbsd: ------------------------------
May 16 05:33:24 roome /netbsd: 000: 00 80 00 00 33 00 00 00 41 70 70 6c 65 20 
20 20 
May 16 05:33:24 roome /netbsd: 016: 69 50 6f 64 20 20 20 20 20 20 20 20 20 20 
20 20 
May 16 05:33:24 roome /netbsd: 032: 31 2e 36 32 02 01 00 00 00 00 00 00 00 14 
00 10 
May 16 05:33:24 roome /netbsd: 048: 92 00 00 00 00 00 00 00 00 00 20 20 20 20 
20 20 
May 16 05:33:24 roome /netbsd: ------------------------------
May 16 05:33:24 roome /netbsd: probe(umass0:0:0:0): scsipi_free_xs
May 16 05:33:24 roome /netbsd: scsipi_inqmatch: 2/0/1 <, , >
May 16 05:33:24 roome /netbsd: sd0 at scsibus0 target 0 lun 0: <Apple, iPod, 
1.62> disk removableprobe(umass0:0:0:0): sdattach: 
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): scsipi_get_xs
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): calling pool_get
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): returning
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): scsi_scsipi_cmd
May 16 05:33:24 roome /netbsd: scsipi_execute_xs: xs(0xc2207ebc): 
xs_control(0x00000384)xs_status(0x00000000)periph(0xc236a600)retr(0x0)timo(0x2710)cmd(0xc2207f50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)sd0(umass0:0:0:0):
  command: 0x0,0x0,0x0,0x0,0x0,0x0-[0 bytes]
May 16 05:33:24 roome /netbsd: 
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): scsipi_done
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0):  command: 
0x0,0x0,0x0,0x0,0x0,0x0-[0 bytes]
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0):  sense debug information:
May 16 05:33:24 roome /netbsd:  code 0x70 valid 1
May 16 05:33:24 roome /netbsd:  seg 0x0 key 0x2 ili 0x0 eom 0x0 fmark 0x0
May 16 05:33:24 roome /netbsd: 
May 16 05:33:24 roome /netbsd: info: 0x0 0x0 0x0 0x0 followed by 11 extra bytes
May 16 05:33:24 roome /netbsd:  extra: 0x0 0x0 0x0 0x0 0x4 0x1 0x0 0x0 0x0 0x0 
0x0 
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): calling private err_handler()
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0):  Check Condition on CDB: 0x00 
00 00 00 00 00
May 16 05:33:24 roome /netbsd:    SENSE KEY:  Not Ready
May 16 05:33:24 roome /netbsd:     ASC/ASCQ:  Logical Unit Is in Process Of 
Becoming Ready
May 16 05:33:24 roome /netbsd: 
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): scsipi_free_xs
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): calling private start()
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): sdstart sd0: drive offline
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): scsipi_get_xs
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): calling pool_get
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): returning
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): scsi_scsipi_cmd
May 16 05:33:24 roome /netbsd: scsipi_execute_xs: xs(0xc2207ebc): 
xs_control(0x000001c4)xs_status(0x00000000)periph(0xc236a600)retr(0x0)timo(0x2710)cmd(0xc2207f50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)sd0(umass0:0:0:0):
  command: 0x0,0x0,0x0,0x0,0x0,0x0-[0 bytes]
May 16 05:33:24 roome /netbsd: 
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): scsipi_done
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0):  command: 
0x0,0x0,0x0,0x0,0x0,0x0-[0 bytes]
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0):  sense debug information:
May 16 05:33:24 roome /netbsd:  code 0x70 valid 1
May 16 05:33:24 roome /netbsd:  seg 0x0 key 0x2 ili 0x0 eom 0x0 fmark 0x0
May 16 05:33:24 roome /netbsd: 
May 16 05:33:24 roome /netbsd: info: 0x0 0x0 0x0 0x0 followed by 11 extra bytes
May 16 05:33:24 roome /netbsd:  extra: 0x0 0x0 0x0 0x0 0x4 0x1 0x0 0x0 0x0 0x0 
0x0 
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): calling private err_handler()
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): scsipi_free_xs
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): calling private start()
May 16 05:33:24 roome /netbsd: sd0(umass0:0:0:0): sdstart