que! cdrw support

[David Kleiner]

Shimokawa-san,

I believe, a while ago I have replied to your suggestion to try lower speed - it didn't 
change things much.

I have enabled CAMDEBUG in my kernel config, I've attached the log to this mail.
The bottom line is this: camcontrol reset does not work. Camcontrol rescan all
hangs and camcontrol reset all hangs the laptop (no response to keyboard/mouse
fan noise is high).  Perhaps I need to point out that this is an I-Link to 
firewire cable - this laptop only has Sony I-Link socket.  The multiple lines
are due to turning on/off the drive and plugging/unplugging the firewire cable.


I am running -CURRENT, with WITNESS'es:

FreeBSD wallaby.homeunix.net 5.1-CURRENT FreeBSD 5.1-CURRENT #28: 
Mon Jun 23 00:23:40 PDT 2003     

Please let me know if you need any additional information or if I can add debugging
to f/w probes.

Thank you,

David Kleiner

On Sun, May 25, 2003 at 08:26:44PM +0900, Hidetoshi Shimokawa wrote:
> At Sun, 25 May 2003 06:20:20 -0400,
> David Kleiner wrote:
> > after turning the device off and on, I finally get
> > wallaby% fwcontrol -d 0
> > 04213830 31333934 00ffa000 0001f302 40001b5e 00044ffc 030001f3 8100000f
> 
> The third quadlet(0x00ffa000) indicates that it supports only 100Mbps.
> Can you try with the following?
> 
> # sysctl hw.firewire.sbp.max_speed=0
> 
> /\ Hidetoshi Shimokawa
> \/  simokawa at sat.t.u-tokyo.ac.jp
> PGP public key: http://www.sat.t.u-tokyo.ac.jp/~simokawa/pgp.html
> 
-------------- next part --------------
Jun 23 01:02:07 wallaby kernel: fwohci0: BUS reset
Jun 23 01:02:07 wallaby kernel: fwohci0: node_id=0xc000ffc1, gen=4, CYCLEMASTER mode
Jun 23 01:02:07 wallaby kernel: firewire0: 2 nodes, maxhop <= 1, cable IRM = 1 (me)
Jun 23 01:02:07 wallaby kernel: firewire0: bus manager 1 (me)
Jun 23 01:02:08 wallaby kernel: fwohci0: txd err=14 ack busy_X
Jun 23 01:02:08 wallaby kernel: fw_asybusy
Jun 23 01:02:08 wallaby kernel: fwohci0: txd err=14 ack busy_X
Jun 23 01:02:08 wallaby kernel: fw_asybusy
Jun 23 01:02:08 wallaby kernel: fwohci0: txd err=14 ack busy_X
Jun 23 01:02:08 wallaby kernel: fw_asybusy
Jun 23 01:02:08 wallaby kernel: fwohci0: txd err=14 ack busy_X
Jun 23 01:02:08 wallaby kernel: fw_asybusy
Jun 23 01:02:08 wallaby kernel: firewire0: max_asyretry exceeded
Jun 23 01:02:08 wallaby kernel: node0: resp=16 addr=0x400
Jun 23 01:02:08 wallaby kernel: probe failed for 1 node
Jun 23 01:02:10 wallaby kernel: fwohci0: Initiate bus reset
Jun 23 01:02:11 wallaby kernel: fwohci0: BUS reset
Jun 23 01:02:11 wallaby kernel: fwohci0: node_id=0xc000ffc1, gen=5, CYCLEMASTER mode
Jun 23 01:02:11 wallaby kernel: firewire0: 2 nodes, maxhop <= 1, cable IRM = 1 (me)
Jun 23 01:02:11 wallaby kernel: firewire0: bus manager 1 (me)
Jun 23 01:02:11 wallaby kernel: fwohci0: txd err=14 ack busy_X
Jun 23 01:02:11 wallaby kernel: fw_asybusy
Jun 23 01:02:11 wallaby kernel: fwohci0: txd err=14 ack busy_X
Jun 23 01:02:11 wallaby kernel: fw_asybusy
Jun 23 01:02:11 wallaby kernel: fwohci0: txd err=14 ack busy_X
Jun 23 01:02:11 wallaby kernel: fw_asybusy
Jun 23 01:02:11 wallaby kernel: fwohci0: txd err=14 ack busy_X
Jun 23 01:02:11 wallaby kernel: fw_asybusy
Jun 23 01:02:11 wallaby kernel: firewire0: max_asyretry exceeded
Jun 23 01:02:11 wallaby kernel: node0: resp=16 addr=0x400
Jun 23 01:02:11 wallaby kernel: probe failed for 1 node
Jun 23 01:02:12 wallaby kernel: fwohci0: Initiate bus reset
Jun 23 01:02:12 wallaby kernel: fwohci0: BUS reset
Jun 23 01:02:12 wallaby kernel: fwohci0: node_id=0xc000ffc1, gen=6, CYCLEMASTER mode
Jun 23 01:02:12 wallaby kernel: firewire0: 2 nodes, maxhop <= 1, cable IRM = 1 (me)
Jun 23 01:02:12 wallaby kernel: firewire0: bus manager 1 (me)
Jun 23 01:02:13 wallaby kernel: fwohci0: txd err=14 ack busy_X
Jun 23 01:02:13 wallaby kernel: fw_asybusy
Jun 23 01:02:13 wallaby kernel: fwohci0: txd err=14 ack busy_X
Jun 23 01:02:13 wallaby kernel: fw_asybusy
Jun 23 01:02:13 wallaby kernel: fwohci0: txd err=14 ack busy_X
Jun 23 01:02:13 wallaby kernel: fw_asybusy
Jun 23 01:02:13 wallaby kernel: fwohci0: txd err=14 ack busy_X
Jun 23 01:02:13 wallaby kernel: fw_asybusy
Jun 23 01:02:13 wallaby kernel: firewire0: max_asyretry exceeded
Jun 23 01:02:13 wallaby kernel: node0: resp=16 addr=0x400
Jun 23 01:02:13 wallaby kernel: probe failed for 1 node
Jun 23 01:02:16 wallaby kernel: fwohci0: BUS reset
Jun 23 01:02:16 wallaby kernel: fwohci0: node_id=0xc000ffc0, gen=7, CYCLEMASTER mode
Jun 23 01:02:16 wallaby kernel: firewire0: 1 nodes, maxhop <= 0, cable IRM = 0 (me)
Jun 23 01:02:16 wallaby kernel: firewire0: bus manager 0 (me)
Jun 23 01:02:23 wallaby kernel: fwohci0: BUS reset
Jun 23 01:02:23 wallaby kernel: fwohci0: node_id=0xc000ffc1, gen=9, CYCLEMASTER mode
Jun 23 01:02:23 wallaby kernel: firewire0: 2 nodes, maxhop <= 1, cable IRM = 1 (me)
Jun 23 01:02:23 wallaby kernel: firewire0: bus manager 1 (me)
Jun 23 01:02:24 wallaby kernel: fwohci0: txd err= 3 miss Ack err
Jun 23 01:02:24 wallaby kernel: node0: resp=22 addr=0x400
Jun 23 01:02:24 wallaby kernel: probe failed for 1 node
Jun 23 01:02:25 wallaby kernel: fwohci0: BUS reset
Jun 23 01:02:25 wallaby kernel: fwohci0: node_id=0xc000ffc1, gen=10, CYCLEMASTER mode
Jun 23 01:02:25 wallaby kernel: firewire0: 2 nodes, maxhop <= 1, cable IRM = 1 (me)
Jun 23 01:02:25 wallaby kernel: firewire0: bus manager 1 (me)
Jun 23 01:02:25 wallaby kernel: firewire0: New S400 device ID:0001f30240001b5e
Jun 23 01:02:25 wallaby kernel: cam_debug: xpt_schedule_dev
Jun 23 01:02:25 wallaby kernel: cam_debug: Inserting onto queue
Jun 23 01:02:25 wallaby kernel: cam_debug: xpt_run_dev_sendq
Jun 23 01:02:25 wallaby kernel: cam_debug: running device 0xc2d97800
Jun 23 01:02:25 wallaby kernel: (cd0:sbp0:0:0:0): xpt_done
Jun 23 01:02:25 wallaby kernel: (xpt0:sbp0:0:0:0): xpt_async
Jun 23 01:02:25 wallaby kernel: (cd0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:25 wallaby kernel: (cd0:sbp0:0:0:0): xpt_action
Jun 23 01:02:25 wallaby kernel: (cd0:sbp0:0:0:0): lost device
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_action
Jun 23 01:02:25 wallaby kernel: (xpt0:sbp0:0:0:0): xpt_free_path
Jun 23 01:02:25 wallaby kernel: (xpt0:sbp0:0:0:0): xpt_release_path
Jun 23 01:02:25 wallaby kernel: (cd0:sbp0:0:0:0): camisr
Jun 23 01:02:25 wallaby kernel: (cd0:sbp0:0:0:0): entering cddone
Jun 23 01:02:25 wallaby kernel: (cd0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:25 wallaby kernel: (cd0:sbp0:0:0:0): xpt_action
Jun 23 01:02:25 wallaby kernel: (cd0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:25 wallaby kernel: (cd0:sbp0:0:0:0): xpt_action
Jun 23 01:02:25 wallaby kernel: cam_debug: xpt_release_ccb
Jun 23 01:02:25 wallaby kernel: cam_debug: xpt_schedule_dev
Jun 23 01:02:25 wallaby kernel: cam_debug: Inserting onto queue
Jun 23 01:02:25 wallaby kernel: cam_debug: xpt_run_dev_allocq
Jun 23 01:02:25 wallaby kernel: cam_debug:    qfrozen_cnt == 0x0, entries == 1, openings == 104, active == 0
Jun 23 01:02:25 wallaby kernel: cam_debug: running device 0xc2d97800
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:25 wallaby kernel: cam_debug: calling periph start
Jun 23 01:02:25 wallaby kernel: (cd0:sbp0:0:0:0): removing device entry
Jun 23 01:02:25 wallaby kernel: (cd0:sbp0:0:0:0): xpt_free_path
Jun 23 01:02:25 wallaby kernel: (cd0:sbp0:0:0:0): xpt_release_path
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_action
Jun 23 01:02:25 wallaby kernel: cam_debug: xpt_release_ccb
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_action
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_done
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_action
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_action
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_done
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): entering cdgetccb
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_schedule
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0):    added periph to queue
Jun 23 01:02:25 wallaby kernel: cam_debug: xpt_schedule_dev
Jun 23 01:02:25 wallaby kernel: cam_debug: Inserting onto queue
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0):    calling xpt_run_devq
Jun 23 01:02:25 wallaby kernel: cam_debug: xpt_run_dev_allocq
Jun 23 01:02:25 wallaby kernel: cam_debug:    qfrozen_cnt == 0x0, entries == 1, openings == 104, active == 0
Jun 23 01:02:25 wallaby kernel: cam_debug: running device 0xc2d97800
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:25 wallaby kernel: cam_debug: calling periph start
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_action
Jun 23 01:02:25 wallaby kernel: cam_debug: xpt_schedule_dev
Jun 23 01:02:25 wallaby kernel: cam_debug: Inserting onto queue
Jun 23 01:02:25 wallaby kernel: cam_debug: xpt_run_dev_sendq
Jun 23 01:02:25 wallaby kernel: cam_debug: running device 0xc2d97800
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_done
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): camisr
Jun 23 01:02:25 wallaby kernel: cam_debug: xpt_release_ccb
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_free_path
Jun 23 01:02:25 wallaby kernel: (pass0:sbp0:0:0:0): xpt_release_path
Jun 23 01:02:27 wallaby kernel: (xpt0:sbp0:0:0:0): xpt_compile_path
Jun 23 01:02:29 wallaby kernel: (xpt0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:29 wallaby kernel: (xpt0:sbp0:0:0:0): xpt_action
Jun 23 01:02:29 wallaby kernel: (xpt0:sbp0:0:0:0): xpt_scan_lun
Jun 23 01:02:29 wallaby kernel: (xpt0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:29 wallaby kernel: (xpt0:sbp0:0:0:0): xpt_action
Jun 23 01:02:29 wallaby kernel: (xpt0:sbp0:0:0:0): xpt_done
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_compile_path
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_action
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_done
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_schedule
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0):    added periph to queue
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_schedule_dev
Jun 23 01:02:29 wallaby kernel: cam_debug: Inserting onto queue
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0):    calling xpt_run_devq
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_run_dev_allocq
Jun 23 01:02:29 wallaby kernel: cam_debug:    qfrozen_cnt == 0x0, entries == 1, openings == 104, active == 0
Jun 23 01:02:29 wallaby kernel: cam_debug: running device 0xc2d97c00
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:29 wallaby kernel: cam_debug: calling periph start
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): probestart
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_action
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_schedule_dev
Jun 23 01:02:29 wallaby kernel: cam_debug: Inserting onto queue
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_run_dev_sendq
Jun 23 01:02:29 wallaby kernel: cam_debug: running device 0xc2d97c00
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_done
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): camisr
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): probedone
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_release_ccb
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_schedule
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0):    added periph to queue
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_schedule_dev
Jun 23 01:02:29 wallaby kernel: cam_debug: Inserting onto queue
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0):    calling xpt_run_devq
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_run_dev_allocq
Jun 23 01:02:29 wallaby kernel: cam_debug:    qfrozen_cnt == 0x0, entries == 1, openings == 104, active == 0
Jun 23 01:02:29 wallaby kernel: cam_debug: running device 0xc2d97c00
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:29 wallaby kernel: cam_debug: calling periph start
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): probestart
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_action
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_schedule_dev
Jun 23 01:02:29 wallaby kernel: cam_debug: Inserting onto queue
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_run_dev_sendq
Jun 23 01:02:29 wallaby kernel: cam_debug: running device 0xc2d97c00
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_done
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): camisr
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): probedone
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_release_ccb
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_schedule
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0):    added periph to queue
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_schedule_dev
Jun 23 01:02:29 wallaby kernel: cam_debug: Inserting onto queue
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0):    calling xpt_run_devq
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_run_dev_allocq
Jun 23 01:02:29 wallaby kernel: cam_debug:    qfrozen_cnt == 0x0, entries == 1, openings == 104, active == 0
Jun 23 01:02:29 wallaby kernel: cam_debug: running device 0xc2d97c00
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:29 wallaby kernel: cam_debug: calling periph start
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): probestart
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_action
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_schedule_dev
Jun 23 01:02:29 wallaby kernel: cam_debug: Inserting onto queue
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_run_dev_sendq
Jun 23 01:02:29 wallaby kernel: cam_debug: running device 0xc2d97c00
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_done
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): camisr
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): probedone
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_action
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_done
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_action
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_action
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_done
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_action
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_done
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_done
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_release_ccb
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_schedule
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0):    added periph to queue
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_schedule_dev
Jun 23 01:02:29 wallaby kernel: cam_debug: Inserting onto queue
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0):    calling xpt_run_devq
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_run_dev_allocq
Jun 23 01:02:29 wallaby kernel: cam_debug:    qfrozen_cnt == 0x0, entries == 1, openings == 104, active == 0
Jun 23 01:02:29 wallaby kernel: cam_debug: running device 0xc2d97c00
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_setup_ccb
Jun 23 01:02:29 wallaby kernel: cam_debug: calling periph start
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): probestart
Jun 23 01:02:29 wallaby kernel: (probe0:sbp0:0:0:0): xpt_action
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_schedule_dev
Jun 23 01:02:29 wallaby kernel: cam_debug: Inserting onto queue
Jun 23 01:02:29 wallaby kernel: cam_debug: xpt_run_dev_sendq
Jun 23 01:02:29 wallaby kernel: cam_debug: running device 0xc2d97c00
Jun 23 01:02:41 wallaby kernel: (xpt0:xpt0:0:-1:-1): xpt_action