First attempt - is drive locked? [solved]

The place to discuss linux version of MakeMKV
Post Reply
skeptic_always
Posts: 5
Joined: Fri Jan 08, 2010 4:31 pm

First attempt - is drive locked? [solved]

Post by skeptic_always »

[ Solved by updating firmware for my blu-ray drive (Liteon DH-401S). ]

I own exactly one blu-ray movie, Live Free or Die Hard. It's the only BR movie to be put in my BR drive, but when I try to open the disk with MakeMKV 1.4.11 beta I get this (from the log file):

Code: Select all

Debug log started at Fri Jan  8 16:18:04 2010 , written by MakeMKV v1.4.11 beta linux(x86-release)                      
001014:000000:0000 MakeMKV v1.4.11 beta linux(x86-release) started                                                      
001013:000000:0000 Debug logging enabled, log will be saved as /home/user/MakeMKV_log.txt                               
001012:000000:0020 DEBUG: Code 256 at qK*K.cv80{` /,B; xS9R:29394942                                                    
001012:000000:0020 DEBUG: Code 256 at qK*K.cv80{` /,B; xS9R:29394942                                                    
003010:000000:0000 Using direct disc access mode                                                                        
001012:000000:0020 DEBUG: Code 256 at qK*K.cv80{` /,B; xS9R:29394942                                                    
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured
 while issuing SCSI command to device 'SG:dev_11:1'                                                                          
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:29394344                                                            
001012:000000:0020 DEBUG: Code 0 at ?]=L2Bca-]"|qiT2bf:121261727                                                             
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured
 while issuing SCSI command to device 'SG:dev_11:1'                                                                          
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:29394344                                                            
001012:000000:0020 DEBUG: Code 1 at ?]=L2Bca-]"|qiT2bf:121261727                                                             
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured
 while issuing SCSI command to device 'SG:dev_11:1'                                                                          
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:29394344                                                            
001012:000000:0020 DEBUG: Code 2 at ?]=L2Bca-]"|qiT2bf:121261727                                                             
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured
 while issuing SCSI command to device 'SG:dev_11:1'                                                                          
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:29394344                                                            
001012:000000:0020 DEBUG: Code 3 at ?]=L2Bca-]"|qiT2bf:121261727                                                             
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured
 while issuing SCSI command to device 'SG:dev_11:1'                                                                          
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:29394344                                                            
001012:000000:0020 DEBUG: Code 4 at ?]=L2Bca-]"|qiT2bf:121261727                                                             
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured
 while issuing SCSI command to device 'SG:dev_11:1'                                                                          
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:29394344                                                            
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - KEY NOT ESTABLISHED' occured wh
ile issuing SCSI command to device 'SG:dev_11:1'                                                                             
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:121262026                                                           
001012:000000:0020 DEBUG: Code 0 at ?]=L2Bca-]"|qiT2bf:121261727                                                             
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured
 while issuing SCSI command to device 'SG:dev_11:1'                                                                          
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:29394344                                                            
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - KEY NOT ESTABLISHED' occured wh
ile issuing SCSI command to device 'SG:dev_11:1'                                                                             
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:121262026
001012:000000:0020 DEBUG: Code 1 at ?]=L2Bca-]"|qiT2bf:121261727
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured
 while issuing SCSI command to device 'SG:dev_11:1'
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:29394344
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - KEY NOT ESTABLISHED' occured wh
ile issuing SCSI command to device 'SG:dev_11:1'
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:121262026
001012:000000:0020 DEBUG: Code 2 at ?]=L2Bca-]"|qiT2bf:121261727
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured
 while issuing SCSI command to device 'SG:dev_11:1'
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:29394344
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - KEY NOT ESTABLISHED' occured wh
ile issuing SCSI command to device 'SG:dev_11:1'
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:121262026
001012:000000:0020 DEBUG: Code 3 at ?]=L2Bca-]"|qiT2bf:121261727
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured
 while issuing SCSI command to device 'SG:dev_11:1'
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:29394344
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - KEY NOT ESTABLISHED' occured wh
ile issuing SCSI command to device 'SG:dev_11:1'
001012:000000:0020 DEBUG: Code 0 at Zbo*.3IMI$\P=)HtDmXg:121262026
001012:000000:0020 DEBUG: Code 4 at ?]=L2Bca-]"|qiT2bf:121261727
003311:000000:0000 Can't read AACS VID from disc - most likely current AACS host certificate is revoked by your drive
003302:000000:0000 The volume key is unknown for this disc - video can't be decrypted
001012:000000:0020 DEBUG: Code 0 at ?iuK/E)CG)TOA9 t~gK:121263015
001012:000000:0020 DEBUG: Code 0 at ?iuK/E)CG)TOA9 t~gK:213132721
005010:000000:0000 Failed to open disc
I tried running it as myself as well as root (sudo). It was released in '07 so I don't see how it could have revoked the AACS host certificate??? If it makes a difference, it is a BD+ movie.
Last edited by skeptic_always on Mon Jan 11, 2010 11:13 pm, edited 1 time in total.
theophile
Posts: 21
Joined: Sat Jan 09, 2010 5:44 am

Re: First attempt - is drive locked?

Post by theophile »

I'm getting a similar error from three different BD discs I've tried. I'm a newcomer to this "scene" but I'm fairly handy with Linux. Have tried running as root to no avail. My debug log is this:

Code: Select all

Debug log started at Sat Jan  9 05:42:07 2010 , written by MakeMKV v1.4.11 beta linux(x64-release)
001014:000000:0000 MakeMKV v1.4.11 beta linux(x64-release) started
001013:000000:0000 Debug logging enabled, log will be saved as /root/MakeMKV_log.txt
001012:000000:0020 DEBUG: Code 256 at hU9bI67c}EHVoVe_kI.d(:29394942
003010:000000:0000 Using direct disc access mode
001012:000000:0020 DEBUG: Code 256 at hU9bI67c}EHVoVe_kI.d(:29394942
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:29394344
001012:000000:0020 DEBUG: Code 0 at oGp,#J`nWnOmh3*|.[:121261727
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:29394344
001012:000000:0020 DEBUG: Code 1 at oGp,#J`nWnOmh3*|.[:121261727
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:29394344
001012:000000:0020 DEBUG: Code 2 at oGp,#J`nWnOmh3*|.[:121261727
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:29394344
001012:000000:0020 DEBUG: Code 3 at oGp,#J`nWnOmh3*|.[:121261727
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:29394344
001012:000000:0020 DEBUG: Code 4 at oGp,#J`nWnOmh3*|.[:121261727
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:29394344
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - KEY NOT ESTABLISHED' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:121262026
001012:000000:0020 DEBUG: Code 0 at oGp,#J`nWnOmh3*|.[:121261727
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:29394344
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - KEY NOT ESTABLISHED' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:121262026
001012:000000:0020 DEBUG: Code 1 at oGp,#J`nWnOmh3*|.[:121261727
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:29394344
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - KEY NOT ESTABLISHED' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:121262026
001012:000000:0020 DEBUG: Code 2 at oGp,#J`nWnOmh3*|.[:121261727
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:29394344
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - KEY NOT ESTABLISHED' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:121262026
001012:000000:0020 DEBUG: Code 3 at oGp,#J`nWnOmh3*|.[:121261727
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - AUTHENTICATION FAILURE' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:29394344
002014:000000:0000 Error 'Scsi error - ILLEGAL REQUEST:COPY PROTECTION KEY EXCHANGE FAILURE - KEY NOT ESTABLISHED' occured while issuing SCSI command to device 'SG:dev_11:0'
001012:000000:0020 DEBUG: Code 0 at u'Wv"eC__h*1w^P6Nqi,:121262026
001012:000000:0020 DEBUG: Code 4 at oGp,#J`nWnOmh3*|.[:121261727
003311:000000:0000 Can't read AACS VID from disc - most likely current AACS host certificate is revoked by your drive
003302:000000:0000 The volume key is unknown for this disc - video can't be decrypted
001012:000000:0020 DEBUG: Code 0 at )`{#h~L[K+b6y0LG`/,:121263015
001012:000000:0020 DEBUG: Code 0 at )`{#h~L[K+b6y0LG`/,:213132721
005010:000000:0000 Failed to open disc
Application exited at Sat Jan  9 05:43:28 2010
Are we in the same boat?
skeptic_always
Posts: 5
Joined: Fri Jan 08, 2010 4:31 pm

Re: First attempt - is drive locked?

Post by skeptic_always »

theophile wrote:I'm getting a similar error from three different BD discs I've tried. I'm a newcomer to this "scene" but I'm fairly handy with Linux. Have tried running as root to no avail. My debug log is this:
<snip>
Are we in the same boat?
As this is my first exposure to MakeMKV I don't know what I should be looking for in the debug, but a casual glance says yes, we have the same problem. I admit I'm not well versed in blu-ray protection, key revocation, etc. but it doesn't seem likely to me that a host certificate has been revoked.

Not sure it helps, but I did try DVDFab in windows (desktop is dual boot). It was able to create an ISO without complaint, but I am unable to watch the iso.
theophile
Posts: 21
Joined: Sat Jan 09, 2010 5:44 am

Re: First attempt - is drive locked?

Post by theophile »

I've been playing around some more and installed Windows 7 in a VM (using VirtualBox) on the same machine. Using AnyDVD HD in Windows, I have been able to decrypt and rip every BD I've thrown at it, but I still get this error from MakeMKV. I'm also trying to get the Windows version of MakeMKV to work in the VM but so far it hangs while scanning the drives. I'm still tinkering...
skeptic_always
Posts: 5
Joined: Fri Jan 08, 2010 4:31 pm

Re: First attempt - is drive locked?

Post by skeptic_always »

Quick update - Windows (windows 7) version of MakeMKV gives the same error. I downloaded and installed the demo versions of AnyDVD and PowerDVD. With anyDVD running and the powerDVD virtual drive fix box checked PowerDVD gives this error: "there is a disk with an unsupported format". However, I am able to browse to the stream directory and with SMPlayer (mplayer for windows) play the .m2ts file and watch the movie.

Also, with AnyDVD running I am able to run MakeMKV and rip the movie into a .mkv file just fine. Looks like my drive is just fine, but MakeMKV is unable to decrypt this movie. I'd be happy to post/e-mail more info on this if there is anything useful to provide.
mike admin
Posts: 4075
Joined: Wed Nov 26, 2008 2:26 am
Contact:

Re: First attempt - is drive locked?

Post by mike admin »

Thank you for providing the log. It looks like a particular drive bug. What drive are you using?
theophile
Posts: 21
Joined: Sat Jan 09, 2010 5:44 am

Re: First attempt - is drive locked?

Post by theophile »

My drive is a "Sunext BDROM V8402" revision "BH98". This drive was the OEM drive inside the Memorex 2511 standalone player. Due to terrible customer support by Memorex, there is a buggy firmware update for the 2511 that causes the unit to be irreparably bricked by newed Bluray discs. Nevertheless, the drive works great when connected to a computer and using AnyDVD HD.

This is a separate issue, but I'm also trying to figure out if there's a way to flash upgrade this firmware using Linux, since there is a more recent revision than BH98. "Sunext" is now owned by Broadcom so I will contact them and see what can be done. In the mean time, I have to assume that it is theoretically possible to make this drive work in Linux since it does work in Windows.

Thanks for your help!
skeptic_always
Posts: 5
Joined: Fri Jan 08, 2010 4:31 pm

Re: First attempt - is drive locked?

Post by skeptic_always »

Mine is a Liteon. I believe the model number is dh-401s. I will look to see if there is a firmware upgrade then report back if it makes any difference.
skeptic_always
Posts: 5
Joined: Fri Jan 08, 2010 4:31 pm

Re: First attempt - is drive locked?

Post by skeptic_always »

As AnyDVD allows me to rip/watch the movie I didn't think there was likely to be an issue with the drive. Much to my surprise, after updating firmware MakeMKV can rip the movie to .mkv in both Windows and Linux.

Fantastic job guys! I may actually buy blu-ray movies now.
theophile
Posts: 21
Joined: Sat Jan 09, 2010 5:44 am

Re: First attempt - is drive locked? [solved]

Post by theophile »

Bah, looks like I'm on my own now. I contacted Broadcom about a firmware update but I don't think that's going to happen since this is an OEM drive. Is it a revocation list problem that is solved by the firmware update? Am I completely out of luck with MakeMKV?
mike admin
Posts: 4075
Joined: Wed Nov 26, 2008 2:26 am
Contact:

Re: First attempt - is drive locked? [solved]

Post by mike admin »

as it seems it is a drive firmware bug that manifests under rare conditions, and MakeMKV somehow creates these. We're trying to identify the problem but so far no luck.
theophile
Posts: 21
Joined: Sat Jan 09, 2010 5:44 am

Re: First attempt - is drive locked? [solved]

Post by theophile »

Please let me know if there is anything I can do to help.
richwill
Posts: 7
Joined: Tue May 03, 2011 12:27 pm

Re: First attempt - is drive locked? [solved]

Post by richwill »

Is this issue related? If so, I don't mind running other tests, etc. The drive I'm using is an LG BD-RE BH10NS30 with the H102 firmware.

Here's the log I get no matter what BD disk I use. Interestingly enough, MakeMKV worked just fine on version 1.6.7 even with the same disk.

Debug log started at Wed Sep 7 11:28:13 2011 , written by MakeMKV v1.6.14 linux(x64-release)
Using 1048320KB for read cache.
001005:0000 MakeMKV v1.6.14 linux(x64-release) started
001004:0000 Debug logging enabled, log will be saved as /home/rich/MakeMKV_log.txt
001003:0020 DEBUG: Code 0 at iT=;<B?!PgGmTo`D:121261911
001003:0020 DEBUG: Code 0 at %H:d]`9`.\O!it:213130375
003006:0000 Opening files on harddrive at /dev/sr2
001003:0020 DEBUG: Code 2 at d"OWT;\=6 2yn67t3Aq/;>:121261658
001003:0020 DEBUG: Code 2 at d"OWT;\=6 2yn67t3Aq/;>:121261658
001003:0020 DEBUG: Code 0 at %,_WU9K&9VvMEIL&d:213130260
001003:0020 DEBUG: Code 0 at [QLHUYXI0V'q[L)8~:29394137
001003:0020 DEBUG: Code 2 at d"OWT;\=6 2yn67t3Aq/;>:121261658
005010:0000 Failed to open disc
Application exited at Wed Sep 7 11:28:29 2011

HTH
mike admin
Posts: 4075
Joined: Wed Nov 26, 2008 2:26 am
Contact:

Re: First attempt - is drive locked? [solved]

Post by mike admin »

Seems you're using invalid command line - do you use GUI or console app?
Post Reply