automatic-ripping-machine: 🐛Error 'Scsi error - ILLEGAL REQUEST:INVALID FIELD IN CDB' occurred while issuing SCSI command 460200010..00100 to device

Is there an existing issue for this?

• I have searched the existing issues

Does this issue exist in the latest version?

• I’m using the latest release

Describe the bug?

I setup the 1337server/automatic-ripping-machine:latest docker image. I bought my own key for makemkv because I kept getting the error to upgrade or register.

Now I get the error:

PRGV:0,0,65536 MSG:2004,16777216,4,“Error ‘Scsi error - ILLEGAL REQUEST:INVALID FIELD IN CDB’ occurred while issuing SCSI command 460200010…00100 to device ‘SG:dev_21:0’”,“Error ‘%1’ occurred while issuing SCSI command %4 to device ‘%2:%3’”,“Scsi error - ILLEGAL REQUEST:INVALID FIELD IN CDB”,“SG”,“dev_21:0”,“460200010…00100” MSG:5010,0,0,“Failed to open disc”,“Failed to open disc”

Which appears to be happening after: [02-11-2023 19:52:51] INFO ARM: Using MakeMKV to get information on all the tracks on the disc. This will take a few minutes… [02-11-2023 19:52:52] INFO ARM: Found 0 titles

So maybe when it is calling “get_track_info”?

I’m not sure. I’m confused as to why I’d have a Scsi error if I’m using Sata. Also I’m seeing it get the name of the DVD so it is apparently there and mounted.

I’ve tried a DVD and a Blueray and they both give this same error at this same point.

Anyone know what my problem is or is this a bug? Is there an easy way to set the debug level to debug instead of just info?

To Reproduce

Steps to Reproduce:

1. insert disk
2. (if failed run before) run: mount /dev/sr0
3. (my auto udev thing doesn’t seem to detect the drive so I manually start it each time since I have not gotten it to work at all yet) run : /usr/bin/python3 /opt/arm/arm/ripper/main.py -d sr0
4. the Job is running on the :8080/index.html so navigate to /jobdetails?job_id=12
5. grab log file.

Built from source files ?

• I Built the image from source

What architecture are you seeing the problem on?

Other (Please state in Anything else section)

Expected behavior?

Expected behavior? Not sure, how to get past this error or what to debug next?

Relevant log output

Loading log: ERASER_NA_16X9_167614510613.log...
[02-11-2023 19:51:46] INFO ARM: Database is up to date
[02-11-2023 19:51:46] INFO ARM: ************* Starting ARM processing at 2023-02-11 19:51:46.639172 *************
[02-11-2023 19:51:47] INFO ARM: Branch: main - Commit: 2631ac2
[02-11-2023 19:51:47] INFO ARM: ARM version: 2.6.12
[02-11-2023 19:51:47] INFO ARM: Python version: 3.8.10 (default, Nov 14 2022, 12:59:47) [GCC 9.4.0]
[02-11-2023 19:51:47] INFO ARM: User is: arm
[02-11-2023 19:51:47] INFO ARM: Looking for log files older than 1 days old.
[02-11-2023 19:51:47] INFO ARM: Job: ERASER_NA_16X9
[02-11-2023 19:51:47] INFO ARM: Job #12 with PID 3002 is currently running.
[02-11-2023 19:51:47] INFO ARM: Starting Disc identification
[02-11-2023 19:51:47] INFO ARM: Mounting disc to: /mnt/dev/sr0
[02-11-2023 19:51:47] INFO ARM: Disc identified as video
[02-11-2023 19:51:47] INFO ARM: DVD CRC64 hash is: 3766a31a10af798c
[02-11-2023 19:51:47] ERROR ARM: Pydvdid failed with the error: <urlopen error [SSL: WRONG_VERSION_NUMBER] wrong version number (_ssl.c:1131)>
[02-11-2023 19:51:49] INFO ARM: Disc title Post ident -  title:ERASER_NA_16X9 year:None video_type:unknown disctype: dvd
[02-11-2023 19:51:49] INFO ARM: Waiting 60 seconds for manual override.
[02-11-2023 19:52:49] INFO ARM: ******************* Logging ARM variables *******************
[02-11-2023 19:52:49] INFO ARM: devpath: /dev/sr0
[02-11-2023 19:52:49] INFO ARM: mountpoint: /mnt/dev/sr0
[02-11-2023 19:52:49] INFO ARM: title: ERASER_NA_16X9
[02-11-2023 19:52:49] INFO ARM: year: None
[02-11-2023 19:52:49] INFO ARM: video_type: unknown
[02-11-2023 19:52:49] INFO ARM: hasnicetitle: False
[02-11-2023 19:52:49] INFO ARM: label: ERASER_NA_16X9
[02-11-2023 19:52:49] INFO ARM: disctype: dvd
[02-11-2023 19:52:49] INFO ARM: ******************* End of ARM variables *******************
[02-11-2023 19:52:49] INFO ARM: ******************* Logging config parameters *******************
[02-11-2023 19:52:49] INFO ARM: skip_transcode: False
[02-11-2023 19:52:49] INFO ARM: mainfeature: False
[02-11-2023 19:52:49] INFO ARM: minlength: 600
[02-11-2023 19:52:49] INFO ARM: maxlength: 99999
[02-11-2023 19:52:49] INFO ARM: videotype: auto
[02-11-2023 19:52:49] INFO ARM: manual_wait: True
[02-11-2023 19:52:49] INFO ARM: manual_wait_time: 60
[02-11-2023 19:52:49] INFO ARM: ripmethod: mkv
[02-11-2023 19:52:49] INFO ARM: mkv_args: 
[02-11-2023 19:52:49] INFO ARM: delrawfiles: True
[02-11-2023 19:52:49] INFO ARM: hb_preset_dvd: HQ 720p30 Surround
[02-11-2023 19:52:49] INFO ARM: hb_preset_bd: HQ 1080p30 Surround
[02-11-2023 19:52:49] INFO ARM: hb_args_dvd: --subtitle scan -F
[02-11-2023 19:52:49] INFO ARM: hb_args_bd: --subtitle scan -F --subtitle-burned --audio-lang-list eng --all-audio
[02-11-2023 19:52:49] INFO ARM: raw_path: /home/arm/raw/
[02-11-2023 19:52:49] INFO ARM: transcode_path: /home/arm/transcode/
[02-11-2023 19:52:49] INFO ARM: completed_path: /home/arm/media/completed/
[02-11-2023 19:52:49] INFO ARM: extras_sub: extras
[02-11-2023 19:52:49] INFO ARM: emby_refresh: False
[02-11-2023 19:52:49] INFO ARM: emby_server: 
[02-11-2023 19:52:49] INFO ARM: emby_port: 8096
[02-11-2023 19:52:49] INFO ARM: notify_rip: True
[02-11-2023 19:52:49] INFO ARM: notify_transcode: True
[02-11-2023 19:52:49] INFO ARM: max_concurrent_transcodes: 0
[02-11-2023 19:52:49] INFO ARM: ******************* End of config parameters *******************
[02-11-2023 19:52:49] INFO ARM: Checking for fstab entry.
[02-11-2023 19:52:49] INFO ARM: fstab entry is: /dev/sr0  /mnt/dev/sr0  udf,iso9660  users,noauto,exec,utf8,ro  0  0
[02-11-2023 19:52:49] INFO ARM: Output directory "/home/arm/transcode/unidentified/ERASER_NA_16X9" already exists.
[02-11-2023 19:52:49] INFO ARM: Final Output directory "/home/arm/transcode/unidentified/ERASER_NA_16X9_167614510613"
[02-11-2023 19:52:49] INFO ARM: Output directory "/home/arm/media/completed/unidentified/ERASER_NA_16X9" already exists.
[02-11-2023 19:52:49] INFO ARM: Final Output directory "/home/arm/media/completed/unidentified/ERASER_NA_16X9_167614510613"
[02-11-2023 19:52:49] INFO ARM: Processing files to: /home/arm/transcode/unidentified/ERASER_NA_16X9_167614510613
[02-11-2023 19:52:49] INFO ARM: ************* Ripping disc with MakeMKV *************
[02-11-2023 19:52:49] INFO ARM: Updating MakeMKV key...
[02-11-2023 19:52:50] INFO ARM: Starting MakeMKV rip. Method is mkv
[02-11-2023 19:52:51] INFO ARM: MakeMKV disc number: 0
[02-11-2023 19:52:51] INFO ARM: Destination is /home/arm/raw/ERASER_NA_16X9
[02-11-2023 19:52:51] INFO ARM: /home/arm/raw/ERASER_NA_16X9 exists.  Adding timestamp.
[02-11-2023 19:52:51] INFO ARM: raw_path is /home/arm/raw/ERASER_NA_16X9_167614510613
[02-11-2023 19:52:51] INFO ARM: Processing files to: /home/arm/raw/ERASER_NA_16X9_167614510613
[02-11-2023 19:52:51] INFO ARM: Using MakeMKV to get information on all the tracks on the disc. This will take a few minutes...
[02-11-2023 19:52:52] INFO ARM: Found 0 titles
MSG:1005,0,1,"MakeMKV v1.17.2 linux(x64-release) started","%1 started","MakeMKV v1.17.2 linux(x64-release)"
PRGT:5018,0,"Scanning CD-ROM devices"
PRGC:5018,0,"Scanning CD-ROM devices"
PRGV:0,0,65536
PRGV:0,0,65536
PRGV:65536,0,65536
PRGV:65536,65536,65536
MSG:5075,131072,2,"The new version 1.17.3 is available for download at http://www.makemkv.com/download/","The new version %1 is available for download at %2","1.17.3","http://www.makemkv.com/download/"
MSG:2004,0,4,"Error 'Scsi error - ILLEGAL REQUEST:INVALID FIELD IN CDB' occurred while issuing SCSI command 460200010..00100 to device 'SG:dev_21:0'","Error '%1' occurred while issuing SCSI command %4 to device '%2:%3'","Scsi error - ILLEGAL REQUEST:INVALID FIELD IN CDB","SG","dev_21:0","460200010..00100"
DRV:0,2,999,0,"DVD-ROM QEMU QEMU DVD-ROM 2.5+","","/dev/sr0"
DRV:1,256,999,0,"","",""
DRV:2,256,999,0,"","",""
DRV:3,256,999,0,"","",""
DRV:4,256,999,0,"","",""
DRV:5,256,999,0,"","",""
DRV:6,256,999,0,"","",""
DRV:7,256,999,0,"","",""
DRV:8,256,999,0,"","",""
DRV:9,256,999,0,"","",""
DRV:10,256,999,0,"","",""
DRV:11,256,999,0,"","",""
DRV:12,256,999,0,"","",""
DRV:13,256,999,0,"","",""
DRV:14,256,999,0,"","",""
DRV:15,256,999,0,"","",""
PRGV:0,65536,65536
PRGV:0,0,65536
MSG:2004,16777216,4,"Error 'Scsi error - ILLEGAL REQUEST:INVALID FIELD IN CDB' occurred while issuing SCSI command 460200010..00100 to device 'SG:dev_21:0'","Error '%1' occurred while issuing SCSI command %4 to device '%2:%3'","Scsi error - ILLEGAL REQUEST:INVALID FIELD IN CDB","SG","dev_21:0","460200010..00100"
MSG:5010,0,0,"Failed to open disc","Failed to open disc"
[02-11-2023 19:52:53] ERROR ARM: Call to MakeMKV failed with code: 11 (b'')
[02-11-2023 19:52:53] ERROR ARM: MakeMKV did not complete successfully.  Exiting ARM! Error: exceptions must derive from BaseException
[02-11-2023 19:52:53] ERROR ARM: A fatal error has occurred and ARM is exiting.  See traceback below for details.

Anything else?

I am running this in a docker container on a proxmox x64 server. From the settings page: Python version: 3.8.10 A.R.M version: 2.6.12 Update Available: update image You are on the latest version

Code of Conduct

• I agree to follow this project’s Code of Conduct