[argyllcms] Aw: i1pro2 apparent usb timeout problems

  • From: 0815xl@xxxxxx
  • To: argyllcms@xxxxxxxxxxxxx
  • Date: Tue, 15 May 2018 14:15:12 +0200

I had the same problem with my 1pro2. A strong USB voltage is needing. After 
using the usb hub of my monitor it works perfekt.

Gesendet: Dienstag, 15. Mai 2018 um 14:00 Uhr
Von: "Jan Drewes" <mail@xxxxxxxxxxxx>
An: argyllcms@xxxxxxxxxxxxx
Betreff: [argyllcms] i1pro2 apparent usb timeout problems

Dear Argyll Mailing List,
this is my first posting (2nd attempt).
We have recently acquired an i1pro2 and I am trying to get it to work
under Linux (Kubuntu 18.04).
The profiling/calibration run aborted after the first few color patches
were measured:
dispread: Error - new_disprd failed with 'Instrument Access Failed'
While I am using DisplayCal, I believe my problem is really in argyll
territory(?), hence my posting to this mailing list. It seems that
DisplayCal measures 16-or-so patches at first (which always works),
then sets up to measure another 175 patches, which always fails.
The part of the log that I believe to be relevant is copied below.
At first, I had an apparently well-known problem with USB3. After
connecting the i1pro2 to a USB2 hub, the initial problems were solved,
but now I am having the problem noted above. It is reproducible every
time, on several different computers, including the following:
Custom-made Desktop-Computer running Kubuntu 18.04argyll 2.00,
DisplayCal 3.5.0argyll 2.0, DisplayCal 3.5.2tried multiple front- and
rear USB ports, both USB3 and USB2 (see above).
Laptop, Dell Precision 5510, Kubuntu 18.04argyll 2.0, DisplayCal
3.5.0argyll 1.8.3, DisplayCal 3.5.2left USB 3.0 portright USB 3.0 port
both computers were also tested with 2 different USB2 hubs.
The problem is always the same.


What could it be? Can I provide more information?
Thank you in advance

Jan
Logfile:
15:35:16,009 Number of patches = 17515:35:16,009 Setting up the
instrument15:35:16,009 new_inst: called with path '/dev/bus/usb/001/008
(X-Rite i1 Pro 2)' type 'i1 Pro15:35:16,009 2'15:35:16,009 new_icoms
'/dev/bus/usb/001/008 (X-Rite i1 Pro 2)' itype 'i1 Pro 2'
dctype15:35:16,009 0x1000215:35:16,010 icom_copy_path_to_icom
'/dev/bus/usb/001/008 (X-Rite i1 Pro 2)' returning dctype15:35:16,010
0x1000215:35:16,010 i1pro_init_coms: called15:35:16,010
i1pro_init_coms: about to init USB15:35:16,010 icoms_set_usb_port:
About to set usb port characteristics15:35:16,011 usb_open_port: Make
sure USB port is open, tries 015:35:16,011 usb_open_port: About to open
USB port '/dev/bus/usb/001/008'15:35:16,011 usb_open_port: open port
'/dev/bus/usb/001/008' succeeded15:35:20,948 icoms_usb_clearhalt failed
with -115:35:20,949 usb_open_port: 'serial' read quanta = packet size =
815:35:20,953 usb_open_port: USB port is now open15:35:20,954
usb_install_signal_handlers: called15:35:20,955
usb_install_signal_handlers: done15:35:20,956 icoms_set_usb_port: usb
port characteristics set ok15:35:20,956 i1pro_init_coms: init coms has
suceeded15:35:20,958 i1pro_init_inst: called15:35:20,959 urb_reaper:
reap starting15:35:20,961 i1pro_init:15:35:20,962 i1pro_reset: reset
with mask 0x1f @ 5021 msec15:35:20,962 icoms_usb_control: message  40,
ca 0000 0000 000215:35:20,963  writing data 1f 0015:35:20,964
icoms_usb_control_msg: type 0x40 req 0xca size 215:35:20,964
icoms_usb_transaction: req type 0x0 ep 0x80 size 10 to 200015:35:20,965
icoms_usb_transaction: set req 0x7ffd47bcf160 nourbs to 115:35:20,966
urb_reaper: urb reap URB 0 with status 0, bytes 2, urbs left
015:35:20,966 coms_usb_transaction: reap - 0 left15:35:20,966
coms_usb_transaction: returning err 0x0 and 10 bytes15:35:20,966
icoms_usb_control_msg: returning err 0x0 and 2 bytes15:35:20,967
icoms_usb_control: returning ICOM err 0x015:35:20,967 i1pro_reset:
complete, ICOM err 0x0 (1 msec)15:35:21,049 i1pro_getmisc: @ 5122
msec15:35:21,050 icoms_usb_control: message  c0, c9 0000 0000
000815:35:21,051 icoms_usb_control_msg: type 0xc0 req 0xc9 size
815:35:21,051 icoms_usb_transaction: req type 0x0 ep 0x0 size 16 to
200015:35:21,052 icoms_usb_transaction: set req 0x7ffd47bcf100 nourbs
to 115:35:21,056 Switch thread started15:35:21,057
i1pro_waitfor_switch_th: read 1 byte from switch hit port @ 5122
msec15:35:21,058 icoms_usb_transaction: req type 0x1 ep 0x84 size 1 to
60000015:35:21,059 icoms_usb_transaction: set req 0x7fc83a2f8d00 nourbs
to 115:35:21,060 urb_reaper: urb reap URB 0 with status 0, bytes 8,
urbs left 015:35:21,061 coms_usb_transaction: reap - 0 left15:35:21,062
coms_usb_transaction: returning err 0x0 and 16 bytes15:35:21,063
icoms_usb_control_msg: returning err 0x0 and 8 bytes15:35:21,064
icoms_usb_control: returning ICOM err 0x015:35:21,065  read data 02 7a
07 d0 fa 00 01 0015:35:21,066 i1pro_getmisc: returning 634, 0x07d0,
0xfa00, 0x01, 0x00 ICOM err 0x0 (1 msec)15:35:21,067 Firmware rev =
634, max +ve value = 0xfa0015:35:21,067 i1pro2_geteesize:
called15:35:21,068 icoms_usb_control: message  c0, d9 0000 0000
000415:35:21,069 icoms_usb_control_msg: type 0xc0 req 0xd9 size
415:35:21,070 icoms_usb_transaction: req type 0x0 ep 0x0 size 12 to
200015:35:21,071 icoms_usb_transaction: set req 0x7ffd47bcf160 nourbs
to 115:35:21,072 urb_reaper: urb reap URB 0 with status 0, bytes 4,
urbs left 015:35:21,073 coms_usb_transaction: reap - 0 left15:35:21,074
coms_usb_transaction: returning err 0x0 and 12 bytes15:35:21,075
icoms_usb_control_msg: returning err 0x0 and 4 bytes15:35:21,077
icoms_usb_control: returning ICOM err 0x015:35:21,078  read data 00 00
40 0015:35:21,079 i1pro2_geteesize: returning 16384 ICOM err
0x015:35:21,080 i1pro_readEEProm: address 0x0 size 0x4000 @ 5124
msec15:35:21,081 icoms_usb_control: message  40, c4 0000 0000
000615:35:21,082  writing data 00 00 00 00 40 0015:35:21,082
icoms_usb_control_msg: type 0x40 req 0xc4 size 615:35:21,083
icoms_usb_transaction: req type 0x0 ep 0x80 size 14 to 200015:35:21,084
icoms_usb_transaction: set req 0x7ffd47bcf0c0 nourbs to 115:35:21,085
urb_reaper: urb reap URB 0 with status 0, bytes 6, urbs left
015:35:21,085 coms_usb_transaction: reap - 0 left15:35:21,086
coms_usb_transaction: returning err 0x0 and 14 bytes15:35:21,087
icoms_usb_control_msg: returning err 0x0 and 6 bytes15:35:21,087
icoms_usb_control: returning ICOM err 0x015:35:21,088
icoms_usb_transaction: req type 0x2 ep 0x82 size 16384 to
500015:35:21,088 icoms_usb_transaction: set req 0x7ffd47bcf0c0 nourbs
to 115:35:26,051 coms_usb_transaction: time out - cancel remaining
URB's15:35:26,052 cancel_req 015:35:26,062 urb_reaper: urb reap URB 0
with status -2, bytes 16320, urbs left 015:35:26,063
coms_usb_transaction: returning err 0x4000 and 16320 bytes15:35:26,064
icoms_usb_rw: returning 0 bytes, ICOM err 0x400015:35:26,065
i1pro_readEEProm: read failed with ICOM err 0x4000 (5003
msec)15:35:26,066 i1pro_init_inst: failed with 0x7215:35:26,066
init_inst returned 'Communications failure' (Communications
failure)15:35:26,066 new_disprd failed because init_inst
failed15:35:26,067 i1pro_del called15:35:26,067
i1pro_restore_calibration xdg_bds failed to locate file'15:35:26,067
i1pro_terminate_switch: called15:35:26,067 icoms_usb_control:
message  40, d0 0003 0000 000815:35:26,067  writing data ff ff ff ff fc
ee 12 0015:35:26,068 icoms_usb_control_msg: type 0x40 req 0xd0 size
815:35:26,068 icoms_usb_transaction: req type 0x0 ep 0x80 size 16 to
200015:35:26,068 icoms_usb_transaction: set req 0x7ffd47bcf280 nourbs
to 115:35:26,068 urb_reaper: urb reap URB 0 with status 0, bytes 8,
urbs left 015:35:26,069 coms_usb_transaction: reap - 0 left15:35:26,069
coms_usb_transaction: returning err 0x0 and 16 bytes15:35:26,069
icoms_usb_control_msg: returning err 0x0 and 8 bytes15:35:26,069
icoms_usb_control: returning ICOM err 0x015:35:26,070
i1pro_terminate_switch: done, ICOM err 0x015:35:26,104 i1pro terminate
switch thread failed, canceling I/O15:35:26,104 icoms_usb_cancel_io
called15:35:26,105 cancel_req 015:35:26,106 urb_reaper: urb reap URB 0
with status -2, bytes 0, urbs left 015:35:26,107 coms_usb_transaction:
reap - 0 left15:35:26,107 coms_usb_transaction: returning err 0x100 and
0 bytes15:35:26,107 icoms_usb_rw: returning 0 bytes, ICOM err
0x10015:35:26,107 i1pro_waitfor_switch_th: failed with ICOM err 0x100
(5057 msec)15:35:26,108 Switch handler triggered with rv 114, th_term
115:35:26,108 Switch thread returning15:35:26,156 i1pro switch thread
terminated15:35:26,157 icoms_del: called15:35:26,157 icoms_del: closing
port15:35:26,157 usb_close_port: called15:35:26,158 usb_close_port:
waking reaper thread to trigger exit15:35:26,159 usb_close_port:
waiting for reaper thread15:35:26,159 urb_reaper: poll returned events
0 1 - shutting down15:35:26,159 urb_reaper: shutdown or too many
failure15:35:26,160 urb_reaper: cleared requests15:35:26,160
urb_reaper: thread done15:35:26,160 usb_close_port: usb port has been
released and closed15:35:26,160 dispread: Error - new_disprd failed
with 'Instrument Access Failed'15:35:26,161 15:35:26,164 DisplayCAL:
Reached EOF (OK)15:35:26,271 ...aborted.
-- 


Jan Drewes, Dr. rer. nat.

Technische Universität Chemnitz
Institut für Physik
Professur Physik kognitiver Prozesse (PHKP)
Reichenhainer Straße 70
Physikgebäude (PhG), Raum P134
09126 Chemnitz
Germany

Cell: +49 176 26086222
Office: +49 371 531-33850
mail@xxxxxxxxxxxx

Other related posts: