multiple apps hang on dvd read

[fati]

Binary package hint: k9copy

When starting k9copy with a DVD inserted the program hangs for an incredibly long time while trying to read the disc. Afterward the program will also take an incredibly long time to analyze the disc. If the program is started without a DVD inserted it starts fine. If I then insert the disc it will open (analyze) the contents perfectly but will then hang while trying to copy.

It seems to be that all operations perform as they should if I can beat the hald daemon to the device, but this could be a red herring. Once the program hangs it cannot be killed with either SIGTERM or SIGKILL.

Here is what dmesg reports while the operation is in progress:
[ 129.243351] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[ 129.243364] ata2.00: cmd a0/01:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0xac d
ata 8 in
[ 129.243368] res 40/00:03:00:00:00/00:00:00:00:00/b0 Emask 0x4 (timeo
ut)
[ 136.227006] ata2: port is slow to respond, please be patient (Status 0xd0)
[ 159.176100] ata2: port failed to respond (30 secs, Status 0xd0)
[ 159.176108] ata2: soft resetting port
[ 161.134580] ata2.00: configured for UDMA/66
[ 191.047242] ata2.01: qc timeout (cmd 0xef)
[ 191.047251] ata2.01: failed to set xfermode (err_mask=0x4)
[ 191.047258] ata2: failed to recover some devices, retrying in 5 secs
[ 196.036705] ata2: soft resetting port
[ 196.854482] ata2.00: configured for UDMA/66
[ 197.018008] ata2.01: configured for UDMA/33
[ 197.018030] ata2: EH complete
[ 261.793084] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[ 261.793096] ata2.00: cmd a0/01:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0xac d
ata 8 in
[ 261.793100] res 40/00:03:00:00:00/00:00:00:00:00/b0 Emask 0x4 (timeo
ut)
[ 268.776743] ata2: port is slow to respond, please be patient (Status 0xd0)
[ 291.725833] ata2: port failed to respond (30 secs, Status 0xd0)
[ 291.725842] ata2: soft resetting port
[ 292.543658] ata2.00: configured for UDMA/66
[ 292.707165] ata2.01: configured for UDMA/33
[ 292.707191] ata2: EH complete
[ 352.624394] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[ 352.624407] ata2.00: cmd a0/01:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0xac d
ata 8 in
[ 352.624409] res 40/00:03:00:00:00/00:00:00:00:00/b0 Emask 0x4 (timeo
ut)
[ 359.608046] ata2: port is slow to respond, please be patient (Status 0xd0)
[ 382.557135] ata2: port failed to respond (30 secs, Status 0xd0)
[ 382.557144] ata2: soft resetting port
[ 383.374941] ata2.00: configured for UDMA/66
[ 413.287602] ata2.01: qc timeout (cmd 0xef)
[ 413.287612] ata2.01: failed to set xfermode (err_mask=0x4)
[ 413.287619] ata2: failed to recover some devices, retrying in 5 secs
[ 418.277064] ata2: soft resetting port
[ 419.094891] ata2.00: configured for UDMA/66
[ 449.007506] ata2.01: qc timeout (cmd 0xef)
[ 449.007516] ata2.01: failed to set xfermode (err_mask=0x4)
[ 449.007524] ata2.01: limiting speed to UDMA/33:PIO3
[ 449.007527] ata2: failed to recover some devices, retrying in 5 secs
[ 453.996972] ata2: soft resetting port
[ 454.814752] ata2.00: configured for UDMA/66
[ 484.727413] ata2.01: qc timeout (cmd 0xef)
[ 484.727423] ata2.01: failed to set xfermode (err_mask=0x4)
[ 484.727430] ata2.01: disabled
[ 484.727434] ata2: failed to recover some devices, retrying in 5 secs
[ 489.716875] ata2.00: failed to set xfermode (err_mask=0x40)
[ 489.716883] ata2: failed to recover some devices, retrying in 5 secs
[ 494.706337] ata2: soft resetting port
[ 495.360603] ata2.00: configured for UDMA/66
[ 495.360636] ata2: EH complete
[ 555.329677] ata2.00: limiting speed to UDMA/44:PIO4
[ 555.329685] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[ 555.329694] ata2.00: cmd a0/01:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0xac d
ata 8 in
[ 555.329699] res 40/00:03:00:00:00/00:00:00:00:00/b0 Emask 0x4 (timeo
ut)
[ 562.313330] ata2: port is slow to respond, please be patient (Status 0xd0)
[ 585.262419] ata2: port failed to respond (30 secs, Status 0xd0)
[ 585.262428] ata2: soft resetting port
[ 585.916699] ata2.00: configured for UDMA/44
[ 585.916721] ata2: EH complete
[ 645.829943] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[ 645.829955] ata2.00: cmd a0/01:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0xac d
ata 8 in
[ 645.829957] res 40/00:03:00:00:00/00:00:00:00:00/b0 Emask 0x4 (timeo
ut)
[ 652.813595] ata2: port is slow to respond, please be patient (Status 0xd0)
[ 675.762689] ata2: port failed to respond (30 secs, Status 0xd0)
[ 675.762698] ata2: soft resetting port
[ 676.416964] ata2.00: configured for UDMA/44
[ 676.416992] ata2: EH complete
[ 736.326220] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[ 736.326232] ata2.00: cmd a0/01:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0xac d
ata 8 in
[ 736.326236] res 40/00:03:00:00:00/00:00:00:00:00/b0 Emask 0x4 (timeo
ut)
[ 743.309875] ata2: port is slow to respond, please be patient (Status 0xd0)
[ 766.258969] ata2: port failed to respond (30 secs, Status 0xd0)
[ 766.258978] ata2: soft resetting port
[ 766.909257] ata2.00: configured for UDMA/44
[ 766.909285] ata2: EH complete
[ 826.854404] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[ 826.854416] ata2.00: cmd a0/01:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0xac d ata 8 in
[ 826.854421] res 40/00:03:00:00:00/00:00:00:00:00/b0 Emask 0x4 (timeo ut)
[ 833.834084] ata2: port is slow to respond, please be patient (Status 0xd0)
[ 856.783169] ata2: port failed to respond (30 secs, Status 0xd0)
[ 856.783179] ata2: soft resetting port
[ 857.433455] ata2.00: configured for UDMA/44
[ 857.433476] ata2: EH complete
[ 921.929343] ata2.00: limiting speed to UDMA/33:PIO4
[ 921.929350] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[ 921.929362] ata2.00: cmd a0/01:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0xac d ata 8 in
[ 921.929364] res 40/00:03:00:00:00/00:00:00:00:00/b0 Emask 0x4 (timeo ut)
[ 928.909009] ata2: port is slow to respond, please be patient (Status 0xd0)
[ 951.870067] ata2: port failed to respond (30 secs, Status 0xd0)
[ 951.870075] ata2: soft resetting port
[ 953.848489] ata2.00: configured for UDMA/33
[ 953.848515] ata2: EH complete

Here is a snippet of where strace hangs *full trace is available but has been omitted for brevity ;)
open("/dev/scd0", O_RDWR|O_NONBLOCK) = 11
ioctl(11, SG_IO, 0x81db228) = 0
uname({sys="Linux", node="eve", ...}) = 0
ioctl(11, SG_IO, 0x81db228) = 0
uname({sys="Linux", node="eve", ...}) = 0
ioctl(11, SG_IO, 0x81db228) = 0
uname({sys="Linux", node="eve", ...}) = 0
ioctl(11, SG_IO, 0x81db228) = 0
uname({sys="Linux", node="eve", ...}) = 0
ioctl(11, SG_IO, 0x81db228) = 0
uname({sys="Linux", node="eve", ...}) = 0
ioctl(11, SG_IO, 0x81db228) = 0
uname({sys="Linux", node="eve", ...}) = 0
ioctl(11, SG_IO, 0x81db228) = 0
uname({sys="Linux", node="eve", ...}) = 0
close(11) = 0
open("/dev/scd0", O_RDONLY|O_NONBLOCK) = 11
ioctl(11, SG_IO, 0x81db228) = 0
ioctl(11, SG_IO, 0x81db228) = 0
uname({sys="Linux", node="eve", ...}) = 0
ioctl(11, SG_IO, 0x81db698) = 0
uname({sys="Linux", node="eve", ...}) = 0
ioctl(11, SG_IO, 0x81db698) = 0
ioctl(11, SG_IO, 0x81db698) = 0
uname({sys="Linux", node="eve", ...}) = 0
ioctl(11, SG_IO, 0x81db698) = 0
ioctl(11, SG_IO

[fati (fativi)]

Also note, this is reported as a k9copy error as kaffiene does not seem to have a problem with DVD's.

[fati (fativi)]

After some research it seems that this problem exists in some changes to libata in the 2.6.20 kernel, it seems that k9copy just happens to be the software tripping on the bug though it is not the root cause.

[fati (fativi)]

Kernel 2.6.20-16.28 resolved the issue, this can be closed.

[Brian Murray (brian-murray)]

This bug report is being closed due to your last comment regarding this being fixed with an update. Thanks again for taking the time to report this bug and helping to make Ubuntu better. Feel free to submit any future bugs you may find.

[fati (fativi)]

The latest kernel push (2.6.20.16.28.1) has caused this problem to resurface.

[Brian Murray (brian-murray)]

Could you please add the output of 'dmesg' after attempting to read a DVD with the newest version of the kernel? Thanks in advance.

[fati (fativi)]

One thing that's interesting, that I should have included before, is that in the kernel release where things were acting normally, my dvd and cd drives were appearing as standard IDE drives, the problem surfaces when they're routed through scsi emulation. I've tried loading the generic ide module before the ata_piix module (my sata/ide controller is an Intel ICH5). This causes the optical drives to be loaded as plain IDE devices but does not allow DMA to be used making it a bad fix. Similarly, I'm unable to use DMA modes when using the old piix module.

dmesg output during the problem:
[182619.863213] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[182619.863224] ata2.00: cmd a0/01:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0xac data 8 in
[182619.863229] res 40/00:03:00:00:00/00:00:00:00:00/a0 Emask 0x4 (timeout)
[182626.846590] ata2: port is slow to respond, please be patient (Status 0xd0)
[182649.795220] ata2: port failed to respond (30 secs, Status 0xd0)
[182649.795230] ata2: soft resetting port
[182651.665924] ata2.00: configured for UDMA/33
[182651.665953] ata2: EH complete
[182711.705585] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[182711.705597] ata2.00: cmd a0/01:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0xac data 8 in
[182711.705602] res 40/00:03:00:00:00/00:00:00:00:00/a0 Emask 0x4 (timeout)
[182718.689104] ata2: port is slow to respond, please be patient (Status 0xd0)
[182741.637735] ata2: port failed to respond (30 secs, Status 0xd0)
[182741.637744] ata2: soft resetting port
[182742.292034] ata2.00: configured for UDMA/33
[182742.292063] ata2: EH complete
[182802.192080] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[182802.192092] ata2.00: cmd a0/01:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0xac data 8 in
[182802.192097] res 40/00:03:00:00:00/00:00:00:00:00/a0 Emask 0x4 (timeout)
[182809.171612] ata2: port is slow to respond, please be patient (Status 0xd0)
[182832.136195] ata2: port failed to respond (30 secs, Status 0xd0)
[182832.136206] ata2: soft resetting port
[182832.794459] ata2.00: configured for UDMA/33
[182832.794486] ata2: EH complete
[182892.738399] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[182892.738412] ata2.00: cmd a0/01:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0xac data 8 in
[182892.738415] res 40/00:03:00:00:00/00:00:00:00:00/a0 Emask 0x4 (timeout)
[182899.721921] ata2: port is slow to respond, please be patient (Status 0xd0)
[182922.670548] ata2: port failed to respond (30 secs, Status 0xd0)
[182922.670558] ata2: soft resetting port
[182923.324826] ata2.00: configured for UDMA/33
[182923.324856] ata2: EH complete
[182927.997363] UDF-fs: Partition marked readonly; forcing readonly mount
--- snip ---- (At this point the DVD is opened)

[Brian Murray (brian-murray)]

Just to be clear is it only k9copy that has these issues? In your second comment you mentioned that kaffiene works fine.

[fati (fativi)]

Correct, kaffiene isn't having any issues and opens normally with no error messages. Opening a dvd with k3b however demonstrates the same slow down and error messages:

dmesg output during k3b startup:
84577.188805] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[84577.188817] ata2.00: cmd a0/01:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0xac d
ata 8 in
[84577.188821] res 40/00:03:00:00:00/00:00:00:00:00/b0 Emask 0x4 (timeo
ut)
[84584.172327] ata2: port is slow to respond, please be patient (Status 0xd0)
[84607.120958] ata2: port failed to respond (30 secs, Status 0xd0)
[84607.120967] ata2: soft resetting port
[84608.864037] ata2.00: configured for UDMA/66
[84638.776098] ata2.01: qc timeout (cmd 0xef)
[84638.776108] ata2.01: failed to set xfermode (err_mask=0x4)
[84638.776114] ata2: failed to recover some devices, retrying in 5 secs
[84643.765450] ata2: soft resetting port
[84644.579236] ata2.00: configured for UDMA/66
[84674.495277] ata2.01: qc timeout (cmd 0xef)
[84674.495287] ata2.01: failed to set xfermode (err_mask=0x4)
[84674.495296] ata2.01: limiting speed to UDMA/33:PIO3
[84674.495299] ata2: failed to recover some devices, retrying in 5 secs
[84679.484643] ata2: soft resetting port
[84680.306429] ata2.00: configured for UDMA/66
[84710.218458] ata2.01: qc timeout (cmd 0xef)
[84710.218468] ata2.01: failed to set xfermode (err_mask=0x4)
[84710.218474] ata2.01: disabled
[84710.218478] ata2: failed to recover some devices, retrying in 5 secs
[84715.207824] ata2.00: failed to set xfermode (err_mask=0x40)
[84715.207832] ata2: failed to recover some devices, retrying in 5 secs
[84720.197186] ata2: soft resetting port
[84720.851438] ata2.00: configured for UDMA/66
[84720.851468] ata2: EH complete
[84725.974511] UDF-fs: Partition marked readonly; forcing readonly mount

[fati (fativi)]

Confirmed, latest kernel package (linux-image-2.6.20-16-generic) does not resolve the issue. Is there any simple way to downgrade to the kernel image that was working previously (linux-image-generic 2.6.20.16.28.1)

[fati (fativi)]

Upgrade to gutsy gibbon resolved the issue.

[Launchpad Janitor (janitor)]

Beginning with the Hardy Heron 8.04 development cycle, all open Ubuntu kernel bugs need to be reported against the "linux" kernel package. We are automatically migrating this bug to the new "linux" package. However, development has already began for the upcoming Intrepid Ibex 8.10 release. It would be helpful if you could test the upcoming release and verify if this is still an issue - http://www.ubuntu.com/testing . If the issue still exists, please update this report by changing the Status of the "linux" task from "Incomplete" to "New". We appreciate your patience and understanding as we make this transition. Thanks!

[Leann Ogasawara (leannogasawara)]

The Ubuntu Kernel Team is planning to move to the 2.6.27 kernel for the upcoming Intrepid Ibex 8.10 release. As a result, the kernel team would appreciate it if you could please test this newer 2.6.27 Ubuntu kernel. There are one of two ways you should be able to test:

1) If you are comfortable installing packages on your own, the linux-image-2.6.27-* package is currently available for you to install and test.

--or--

2) The upcoming Alpha5 for Intrepid Ibex 8.10 will contain this newer 2.6.27 Ubuntu kernel. Alpha5 is set to be released Thursday Sept 4. Please watch http://www.ubuntu.com/testing for Alpha5 to be announced. You should then be able to test via a LiveCD.

Please let us know immediately if this newer 2.6.27 kernel resolves the bug reported here or if the issue remains. More importantly, please open a new bug report for each new bug/regression introduced by the 2.6.27 kernel and tag the bug report with 'linux-2.6.27'. Also, please specifically note if the issue does or does not appear in the 2.6.26 kernel. Thanks again, we really appreicate your help and feedback.

[fati (fativi)]

On Fri, Aug 29, 2008 at 1:34 AM, Leann Ogasawara <email address hidden> wrote:

> The Ubuntu Kernel Team is planning to move to the 2.6.27 kernel for the
> upcoming Intrepid Ibex 8.10 release. As a result, the kernel team would
> appreciate it if you could please test this newer 2.6.27 Ubuntu kernel.
> There are one of two ways you should be able to test:
>
> 1) If you are comfortable installing packages on your own, the linux-
> image-2.6.27-* package is currently available for you to install and
> test.
>
> --or--
>
> 2) The upcoming Alpha5 for Intrepid Ibex 8.10 will contain this newer
> 2.6.27 Ubuntu kernel. Alpha5 is set to be released Thursday Sept 4.
> Please watch http://www.ubuntu.com/testing for Alpha5 to be announced.
> You should then be able to test via a LiveCD.
>
> Please let us know immediately if this newer 2.6.27 kernel resolves the
> bug reported here or if the issue remains. More importantly, please
> open a new bug report for each new bug/regression introduced by the
> 2.6.27 kernel and tag the bug report with 'linux-2.6.27'. Also, please
> specifically note if the issue does or does not appear in the 2.6.26
> kernel. Thanks again, we really appreicate your help and feedback.
>
> ** Tags added: cft-2.6.27
>
> --
> multiple apps hang on dvd read
> https://bugs.launchpad.net/bugs/112410
> You received this bug notification because you are a direct subscriber
> of the bug.
>

This issue was resolved sometime ago with the Gutsy Gibbon release.

--
Brian
"It's not stupid, it's advaaanced!" - Tallest Purple

[Leann Ogasawara (leannogasawara)]

Thanks for the udpate. Marking this "Fix Released" against Gutsy.

[Launchpad Janitor (janitor)]

Per a decision made by the Ubuntu Kernel Team, bugs will longer be assigned to the ubuntu-kernel-team in Launchpad as part of the bug triage process. The ubuntu-kernel-team is being unassigned from this bug report. Refer to https://wiki.ubuntu.com/KernelTeamBugPolicies for more information. Thanks.