USB Bulk transfer errors when trying to access external USB crytographic token
Ricardo Scop <[email protected]>
| Newsgroups | gmane.comp.lib.muscle |
|---|---|
| Message-ID | <CAFJH5mtQK9m0UiKO2ReKo5UwJyjGMdXv6qgbq12LzS8T5ke5GA@mail.gmail.com> |
Hi all, While debugging an embedded system based on a Maxim MAX32590 secure ARM processor, I find a lot of bulk transfer errors while trying to access an external USB token using pcsc-lite, ccid driver and libusb. Kernel is a Maxim's customized version based on Linux version 2.6.36 with contributions from linux-arm stable, and usb host controller driver is ohci-hcd with glue driver (maximasp_ohci) by Maxim. Pcsc-lite and ccid driver are also customized versions of pcsc-lite 1.7.4 and ccid 1.4.4, customizations made by the vendor of the USB token, which is a company called Watchdata Pte, from China. uSB token vendor also includes a proprietary PKCS#11 API library in binary form, which is linked to libpcsclite 1.7.4 and which my test application is using to communicate with the external USB token. Whatchdata claims that their solution works well with 2 other ARM embedded environments, both with newer kernel releases (3.2). So they basically gave up helping me, despite all their customization. But at least they've sent me their custom source codes for pcsc-lite 1.7.4 and ccid 1.4.4. By the way, I'm compiling and linking them with recent libusb 1.0.9 stock release. You can see an excerpt of a debugging session in the attached debug.txt file. Without any debugging messages enabled, the only error messages in syslog buffer (excerpt also attached, as syslog.txt) are: Jan 1 01:05:40 jibe-eek user.info syslog: ifdhandler.c:135:IFDHCreateChannelByName() failed Jan 1 01:05:40 jibe-eek user.info syslog: readerfactory.c:965:RFInitializeReader() Open Port 0x200001 Failed (usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if1) Jan 1 01:05:40 jibe-eek user.info syslog: readerfactory.c:275:RFAddReader() WD CCID init failed. and a bunch of: Jan 1 01:05:56 jibe-eek user.debug kernel: [ 358.870000] usb 1-1: usbfs: usb_submit_urb returned -121 Jan 1 01:05:56 jibe-eek user.info syslog: ccid_usb.c:684:ReadUSB() read failed (1/2): -99 Success Despite the "275:RFAddReader() WD CCID init failed" I can see from my test program printout that some initial information is extracted from the external token, and this is confirmed by some successful initial bulk transfers of 1 URB each in the debug log. But any real multi-URB transfers always fail with a -121 (-EREMOTEIO) status in the first of the 5 URBs of each transfers. I wonder if this is a real data underrun during multi-urb transfers, or maybe I have some incompatibility issues between pcsc-lite, ccid, libusb and usb kernel drivers. Haven't anybody found similiar issues in the past? Can anybody point me out which userland packages' releases are more suitable to kernel 2.6.36 usb drivers? Right now I think I'm stuck to that kernel release, because of all drivers Maxim have adapted to it. Also, any other pointers on how to further diagnose and possibly solve those errors will be greatly appreciated. My boss says he can even throw a bounty for help in the right direction, because we need an urgent solution. Sorry for the long post and thank you for the attention. P. S.: Complete versions of the log files are available at https://dl.dropboxusercontent.com/u/27720595/pcscd_wd-libusb.debug.txt and https://dl.dropboxusercontent.com/u/27720595/syslog.txt for the interested. Regards, Ricardo Scop _______________________________________________ Muscle mailing list [email protected] http://lists.musclecard.com/mailman/listinfo/muscle_lists.musclecard.com
pcscd_wd-libusb.debug.txt
(text/plain, 17.1 KB)
[root@jibe-eek /root]# pcscd_wd -fad &
[root@jibe-eek /root]# 00000000 debuglog.c:269:DebugLogSetLevel() debug level=debug
00005337 configfile.l:287:DBGetReaderList() Parsing conf file: /home/scop/maxim-ic/output/jibe-eek/user/buildroot/target/etc/reader_wd.conf.d
00006640 pcscdaemon.c:543:main() pcsc-lite 1.7.4 daemon ready.
.
.
.
.
00101349 hotplug_libusb.c:504:HPAddHotPluggable() Adding USB device: 1:2:0
00102421 readerfactory.c:934:RFInitializeReader() Attempting startup of WD CCID 00 00 using /usr/bin/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
00108728 readerfactory.c:824:RFBindFunctions() Loading IFD Handler 3.0
00109855 ifdhandler.c:1655:init_driver() Driver version: 1.4.4
00116962 ifdhandler.c:1673:init_driver() LogLevel: 0x000F
00117532 ifdhandler.c:1684:init_driver() DriverOptions: 0x0000
00119094 ifdhandler.c:113:IFDHCreateChannelByName() lun: 0, device: usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if0
00119878 ccid_usb.c:186:OpenUSBByName() Reader index: 0, Device: usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if0
00125188 ccid_usb.c:252:OpenUSBByName() ifdManufacturerString: WatchData
00125740 ccid_usb.c:253:OpenUSBByName() ifdProductString: WatchData CCID driver
00126159 ccid_usb.c:254:OpenUSBByName() Copyright: This driver is protected by terms of the GNU Lesser General Public License version 2.1, or (at your option) any later version.
libusb: 0.101338 debug [libusb_init] libusb-1.0.9
.
.
.
.
00867840 ccid_usb.c:527:OpenUSBByName() Found Vendor/Product: 163C/0407 (WD CCID)
00868256 ccid_usb.c:529:OpenUSBByName() Using USB bus/device: 1/2
00868552 ccid_usb.c:1025:ControlUSB() request: 0x03
libusb: 0.844108 debug [libusb_submit_transfer] arm timerfd for timeout in 2000ms (first in line)
libusb: 0.846026 debug [libusb_handle_events_timeout_completed] doing our own event handling
libusb: 0.847277 debug [handle_events] poll() 3 fds with timeout in 60000ms
libusb: 0.849567 debug [handle_events] poll() returned 1
libusb: 0.851048 debug [reap_for_handle] urb type=2 status=0 transferred=0
libusb: 0.852683 debug [handle_control_completion] handling completion status 0
libusb: 0.853838 debug [disarm_timerfd]
libusb: 0.854880 debug [usbi_handle_transfer_completion] transfer 0x2704c has callback 0x402205bc
libusb: 0.856354 debug [ctrl_transfer_cb] actual_length=0
00883362 receive:
00883808 ccid_usb.c:969:get_data_rates() IFD does not support GET_DATA_RATES request: 0
libusb: 0.858967 debug [libusb_unref_device] destroy device 1.1
00886114 -> 000000 65 00 00 00 00 00 00 00 00 00
libusb: 0.861345 debug [submit_bulk_transfer] need 1 urbs for new transfer with length 10
libusb: 0.863110 debug [libusb_submit_transfer] arm timerfd for timeout in 5000ms (first in line)
libusb: 0.864779 debug [libusb_handle_events_timeout_completed] doing our own event handling
libusb: 0.865989 debug [handle_events] poll() 3 fds with timeout in 60000ms
libusb: 0.868008 debug [handle_events] poll() returned 1
libusb: 0.869521 debug [reap_for_handle] urb type=3 status=0 transferred=10
libusb: 0.871071 debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
libusb: 0.872551 debug [handle_bulk_completion] last URB in transfer --> complete!
libusb: 0.873657 debug [disarm_timerfd]
libusb: 0.874659 debug [usbi_handle_transfer_completion] transfer 0x2704c has callback 0x40220568
libusb: 0.876180 debug [bulk_transfer_cb] actual_length=10
libusb: 0.877487 debug [submit_bulk_transfer] need 1 urbs for new transfer with length 10
libusb: 0.879747 debug [libusb_submit_transfer] arm timerfd for timeout in 1000ms (first in line)
libusb: 0.881451 debug [libusb_handle_events_timeout_completed] doing our own event handling
libusb: 0.882641 debug [handle_events] poll() 3 fds with timeout in 60000ms
libusb: 0.884177 debug [handle_events] poll() returned 1
libusb: 0.885464 debug [reap_for_handle] urb type=3 status=0 transferred=10
libusb: 0.886984 debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
libusb: 0.889129 debug [handle_bulk_completion] last URB in transfer --> complete!
libusb: 0.890247 debug [disarm_timerfd]
libusb: 0.891263 debug [usbi_handle_transfer_completion] transfer 0x2704c has callback 0x40220568
libusb: 0.892754 debug [bulk_transfer_cb] actual_length=10
00919338 <- 000000 81 00 00 00 00 00 00 00 00 00
00919997 ifdhandler.c:435:IFDHGetCapabilities() tag: 0xFB3, usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if0 (lun: 0)
00920426 readerfactory.c:290:RFAddReader() Using the pcscd polling thread
00923685 ifdhandler.c:1185:IFDHPowerICC() action: PowerUp, usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if0 (lun: 0)
00924405 -> 000000 62 00 00 00 00 00 01 01 00 00
libusb: 0.899666 debug [submit_bulk_transfer] need 1 urbs for new transfer with length 10
libusb: 0.901568 debug [libusb_submit_transfer] arm timerfd for timeout in 5000ms (first in line)
libusb: 0.903086 debug [libusb_handle_events_timeout_completed] doing our own event handling
libusb: 0.904229 debug [handle_events] poll() 3 fds with timeout in 60000ms
libusb: 0.905806 debug [handle_events] poll() returned 1
libusb: 0.907091 debug [reap_for_handle] urb type=3 status=0 transferred=10
libusb: 0.909435 debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
libusb: 0.910922 debug [handle_bulk_completion] last URB in transfer --> complete!
libusb: 0.912014 debug [disarm_timerfd]
libusb: 1.579486 debug [usbi_handle_transfer_completion] transfer 0x2704c has callback 0x40220568
libusb: 1.581023 debug [bulk_transfer_cb] actual_length=10
libusb: 1.582416 debug [submit_bulk_transfer] need 1 urbs for new transfer with length 43
libusb: 1.584169 debug [libusb_submit_transfer] arm timerfd for timeout in 60000ms (first in line)
libusb: 1.585836 debug [libusb_handle_events_timeout_completed] doing our own event handling
libusb: 1.587037 debug [handle_events] poll() 3 fds with timeout in 60000ms
libusb: 1.589232 debug [handle_events] poll() returned 1
libusb: 1.590559 debug [reap_for_handle] urb type=3 status=-121 transferred=28
libusb: 1.592172 debug [handle_bulk_completion] handling completion status -121 of bulk urb 1/1
libusb: 1.593691 debug [handle_bulk_completion] last URB in transfer --> complete!
libusb: 1.594756 debug [disarm_timerfd]
libusb: 1.595768 debug [usbi_handle_transfer_completion] transfer 0x2704c has callback 0x40220568
libusb: 1.597245 debug [bulk_transfer_cb] actual_length=28
01624928 <- 000000 80 12 00 00 00 00 01 01 00 00 3B 6E 00 00 57 44 36 19 69 86 93 02 31 00 86 25 21 13
01625948 ifdhandler.c:435:IFDHGetCapabilities() tag: 0xFAE, usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if0 (lun: 0)
01626433 ifdhandler.c:523:IFDHGetCapabilities() Reader supports 1 slot(s)
01627614 eventhandler.c:256:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
01628120 Card ATR: 3B 6E 00 00 57 44 36 19 69 86 93 02 31 00 86 25 21 13
01629928 hotplug_libusb.c:504:HPAddHotPluggable() Adding USB device: 1:2:1
01631095 ifdhandler.c:435:IFDHGetCapabilities() tag: 0xFAF, usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if0 (lun: 0)
01634499 readerfactory.c:587:RFSetReaderName() Support 16 simultaneous readers
01635214 ifdhandler.c:435:IFDHGetCapabilities() tag: 0xFAD, usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if0 (lun: 0)
01635568 readerfactory.c:237:RFAddReader() Driver is thread safe
01635961 readerfactory.c:934:RFInitializeReader() Attempting startup of WD CCID 01 00 using /usr/bin/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
01636251 readerfactory.c:738:RFLoadReader() Reusing already loaded driver for /usr/bin/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
01636645 readerfactory.c:824:RFBindFunctions() Loading IFD Handler 3.0
01637421 ifdhandler.c:113:IFDHCreateChannelByName() lun: 10000, device: usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if1
01637815 ccid_usb.c:186:OpenUSBByName() Reader index: 1, Device: usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if1
01642031 ccid_usb.c:252:OpenUSBByName() ifdManufacturerString: WatchData
01642564 ccid_usb.c:253:OpenUSBByName() ifdProductString: WatchData CCID driver
01643693 ccid_usb.c:254:OpenUSBByName() Copyright: This driver is protected by terms of the GNU Lesser General Public License version 2.1, or (at your option) any later version.
libusb: 1.618890 debug [libusb_get_device_list]
libusb: 1.621511 debug [sysfs_scan_device] scan usb1
libusb: 1.626894 debug [sysfs_scan_device] bus=1 dev=1
libusb: 1.629180 debug [enumerate_device] busnum 1 devaddr 1 session_id 257
libusb: 1.630698 debug [enumerate_device] allocating new device for 1/1 (session 257)
libusb: 1.634889 debug [sysfs_scan_device] scan 1-1
libusb: 1.640036 debug [sysfs_scan_device] bus=1 dev=2
libusb: 1.641592 debug [enumerate_device] busnum 1 devaddr 2 session_id 258
libusb: 1.643060 debug [enumerate_device] using existing device for 1/2 (session 258)
libusb: 1.645031 debug [libusb_get_device_descriptor]
libusb: 1.647130 debug [libusb_get_device_descriptor]
01675432 ccid_usb.c:368:OpenUSBByName() Checking device: 1/2
01675922 ifdhandler.c:135:IFDHCreateChannelByName() failed
01676261 readerfactory.c:965:RFInitializeReader() Open Port 0x200001 Failed (usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if1)
01676606 readerfactory.c:275:RFAddReader() WD CCID init failed.
01676886 readerfactory.c:985:RFUnInitializeReader() Attempting shutdown of WD CCID 01 00.
libusb: 2.319008 debug [libusb_unref_device] destroy device 1.1
libusb: 2.320694 debug [libusb_unref_device] destroy device 1.2
02347620 hotplug_libusb.c:411:HPEstablishUSBNotifications() Driver ifd-ccid.bundle does not support IFD_GENERATE_HOTPLUG. Using active polling instead.
02348023 hotplug_libusb.c:420:HPEstablishUSBNotifications() Polling forced every 1 second(s)
02033060 ifdhandler.c:1185:IFDHPowerICC() action: PowerDown, usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if0 (lun: 0)
02349724 -> 000000 63 00 00 00 00 00 02 00 00 00
.
.
.08342164 winscard_svc.c:408:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 4
08345334 winscard_svc.c:315:ContextThread() Received command: CMD_GET_READERS_STATE from client 4
08348226 winscard_svc.c:315:ContextThread() Received command: CMD_GET_READERS_STATE from client 4
08351560 winscard_svc.c:315:ContextThread() Received command: CMD_GET_READERS_STATE from client 4
== Criptoki Library Information ==
Criptoki Version: 2.10
ManufacturerID: WatchData
Flags: 0x0
Library Description:
Library Version: 32.32
08359435 winscard_svc.c:315:ContextThread() Received command: CONNECT from client 4
08361192 winscard.c:235:SCardConnect() Attempting Connect to WD CCID 00 00 using protocol: 3
08361772 ifdhandler.c:1185:IFDHPowerICC() action: PowerUp, usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if0 (lun: 0)
08362640 -> 000000 62 00 00 00 00 00 03 01 00 00
libusb: 8.339104 debug [submit_bulk_transfer] need 1 urbs for new transfer with length 10
libusb: 8.340925 debug [libusb_submit_transfer] arm timerfd for timeout in 5000ms (first in line)
libusb: 8.342595 debug [libusb_handle_events_timeout_completed] doing our own event handling
libusb: 8.343772 debug [handle_events] poll() 3 fds with timeout in 60000ms
libusb: 8.345318 debug [handle_events] poll() returned 1
libusb: 8.346608 debug [reap_for_handle] urb type=3 status=0 transferred=10
libusb: 8.348820 debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
libusb: 8.350316 debug [handle_bulk_completion] last URB in transfer --> complete!
libusb: 8.351397 debug [disarm_timerfd]
libusb: 8.352406 debug [usbi_handle_transfer_completion] transfer 0x2db7c has callback 0x40220568
libusb: 8.353885 debug [bulk_transfer_cb] actual_length=10
libusb: 8.355255 debug [submit_bulk_transfer] need 1 urbs for new transfer with length 43
libusb: 8.356951 debug [libusb_submit_transfer] arm timerfd for timeout in 60000ms (first in line)
libusb: 8.359215 debug [libusb_handle_events_timeout_completed] doing our own event handling
libusb: 8.360354 debug [handle_events] poll() 3 fds with timeout in 60000ms
libusb: 8.361912 debug [handle_events] poll() returned 1
libusb: 8.363207 debug [reap_for_handle] urb type=3 status=-121 transferred=28
libusb: 8.365209 debug [handle_bulk_completion] handling completion status -121 of bulk urb 1/1
libusb: 8.366797 debug [handle_bulk_completion] last URB in transfer --> complete!
libusb: 8.368413 debug [disarm_timerfd]
libusb: 8.369511 debug [usbi_handle_transfer_completion] transfer 0x2db7c has callback 0x40220568
libusb: 8.370972 debug [bulk_transfer_cb] actual_length=28
08397776 <- 000000 80 12 00 00 00 00 03 01 00 00 3B 6E 00 00 57 44 36 19 69 86 93 02 31 00 86 25 21 13
08398196 winscard.c:309:SCardConnect() power up complete.
08398653 Card ATR: 3B 6E 00 00 57 44 36 19 69 86 93 02 31 00 86 25 21 13
08398965 winscard.c:328:SCardConnect() powerState: POWER_STATE_INUSE
08400435 prothandler.c:127:PHSetProtocol() Attempting PTS to T=0
08400933 ifdhandler.c:734:IFDHSetProtocolParameters() protocol T=0, usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if0 (lun: 0)
08401355 towitoko/atr.c:341:ATR_GetDefaultProtocol() no default protocol found in ATR. Using T=0
08401931 ifdhandler.c:1091:IFDHSetProtocolParameters() Communication timeout: 259367 ms
08403726 commands.c:2088:SetParameters() length: 5 bytes
08404460 -> 000000 61 05 00 00 00 00 04 00 00 00 11 00 00 0A 00
libusb: 8.379672 debug [submit_bulk_transfer] need 1 urbs for new transfer with length 15
libusb: 8.381404 debug [libusb_submit_transfer] arm timerfd for[31116.380000] usb 1-1: usbfs: usb_submit_urb returned -121
timeout in 5000ms (first in line)
libusb: 8.383062 debug [libusb_handle_events_timeout_completed] doing our own event handling
libusb: 9.050669 debug [handle_events] poll() 3 fds with timeout in 60000ms
libusb: 9.052366 debug [handle_events] poll() returned 1
libusb: 9.053758 debug [reap_for_handle] urb type=3 status=0 transferred=15
libusb: 9.055333 debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
libusb: 9.056873 debug [handle_bulk_completion] last URB in transfer --> complete!
libusb: 9.059028 debug [disarm_timerfd]
libusb: 9.060195 debug [usbi_handle_transfer_completion] transfer 0x2db7c has callback 0x40220568
libusb: 9.062767 debug [bulk_transfer_cb] actual_length=15
libusb: 9.065158 debug [submit_bulk_transfer] need 5 urbs for new transfer with length 65556
libusb: 8.603632 debug [libusb_get_device_list]
libusb: 9.071383 debug [sysfs_scan_device] scan usb1
libusb: 9.075638 debug [sysfs_scan_device] bus=1 dev=1
libusb: 9.077292 debug [enumerate_device] busnum 1 devaddr 1 session_id 257
libusb: 9.096619 debug [enumerate_device] libusb: 9.098148 error [submit_bulk_transfer] submiturb failed error -1 errno=0
libusb: 9.099880 warning [discard_urbs] unrecognised discard errno 0
libusb: 9.101132 debug [submit_bulk_transfer] reporting successful submission but waiting for 1 discards before reporting error
libusb: 9.102361 debug [libusb_submit_transfer] arm timerfd for timeout in 259367ms (first in line)
libusb: 9.103680 debug [libusb_handle_events_timeout_completed] doing our own event handling
libusb: 9.104805 debug [handle_events] poll() 3 fds with timeout in 60000ms
libusb: 9.106359 debug [handle_events] poll() returned 1
allocating new device for 1/1 (session 257)
libusb: 9.111668 debug [sysfs_scan_device] scan 1-1
libusb: 9.115905 debug [sysfs_scan_device] bus=1 dev=2
libusb: 9.118257 debug [reap_for_handle] urb type=3 status=-121 transferred=15
libusb: 9.120003 debug [handle_bulk_completion] handling completion status -121 of bulk urb 1/5
libusb: 9.121536 debug [handle_bulk_completion] abnormal reap: urb status -121
libusb: 9.122761 debug [handle_bulk_completion] received 15 bytes of surplus data
libusb: 9.123964 debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
libusb: 9.125007 debug [disarm_timerfd]
libusb: 9.126062 debug [usbi_handle_transfer_completion] transfer 0x2db7c has callback 0x40220568
libusb: 9.128786 debug [bulk_transfer_cb] actual_length=15
libusb: 9.130186 warning [do_sync_bulk_transfer] unrecognised status code 1
09158170 ccid_usb.c:684:ReadUSB() read failed (1/2): -99 Success
libusb: 9.117528 debug [enumerate_device] busnum 1 devaddr 2 session_id 258
libusb: 9.135481 debug [enumerate_device] allocating new device for 1/2 (session 258)
09163354 <- 000000 82 05 00 00 00 00 04 00 00 00 11 00 00 0A 00
09165120 winscard.c:406:SCardConnect() Active Protocol: T=0
09165584 winscard.c:426:SCardConnect() hCard Identity: 1f6d5
09166103 winscard_svc.c:449:ContextThread() CONNECT rv=0x0 for client 4
09170061 winscard_svc.c:315:ContextThread() Received command: TRANSMIT from client 4
09172504 winscard.c:1551:SCardTransmit() Send Protocol: T=0
libusb: 9.150769 debug [libusb_get_device_descriptor]
libusb: 9.153517 debug [libusb_get_active_config_descriptor]
libusb: 9.155821 debug [sysfs_get_active_config_descriptor] active configuration 1
09183577 APDU: 00 A4 00 00 02 00 E0
syslog.txt
(text/plain, 5.7 KB)
Jan 1 01:00:18 jibe-eek syslog.info syslogd started: BusyBox v1.17.4 Jan 1 01:00:18 jibe-eek user.notice kernel: [ 0.000000] Linux version 2.6.36.4-3-b1dev-maxim-jibe-b1-00019-gb314b9a (scop@scop-laptop) (gcc version 4.4.1 (Sourcery G++ Lite 2010q1-188) ) #14 PREEMPT Wed Nov 6 04:19:24 BRST 2013 . . . . Jan 1 01:00:19 jibe-eek user.warn kernel: [ 3.370000] VFS: Mounted root (ubifs filesystem) on device 0:12. Jan 1 01:00:19 jibe-eek user.info kernel: [ 3.410000] Freeing TCM link memory: 4K Jan 1 01:00:19 jibe-eek user.info kernel: [ 3.430000] Freeing init memory: 120K Jan 1 01:00:19 jibe-eek user.warn kernel: [ 4.510000] udevd (85): /proc/85/oom_adj is deprecated, please use /proc/85/oom_score_adj instead. Jan 1 01:00:22 jibe-eek user.info login[134]: root login on 'ttyS0' Jan 1 01:05:25 jibe-eek user.info kernel: [ 328.050000] usbcore: registered new interface driver usbfs Jan 1 01:05:25 jibe-eek user.info kernel: [ 328.100000] usbcore: registered new interface driver hub Jan 1 01:05:25 jibe-eek user.info kernel: [ 328.150000] usbcore: registered new device driver usb Jan 1 01:05:25 jibe-eek user.info kernel: [ 328.260000] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Jan 1 01:05:25 jibe-eek user.info kernel: [ 328.290000] maximasp-ohci maximasp-ohci: Maxim Integrated Products OHCI Jan 1 01:05:25 jibe-eek user.info kernel: [ 328.320000] maximasp-ohci maximasp-ohci: new USB bus registered, assigned bus number 1 Jan 1 01:05:25 jibe-eek user.info kernel: [ 328.340000] maximasp-ohci maximasp-ohci: irq 2, io mem 0xfff50100 Jan 1 01:05:25 jibe-eek user.info kernel: [ 328.430000] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001 Jan 1 01:05:25 jibe-eek user.info kernel: [ 328.450000] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 1 01:05:25 jibe-eek user.info kernel: [ 328.480000] usb usb1: Product: Maxim Integrated Products OHCI Jan 1 01:05:25 jibe-eek user.info kernel: [ 328.500000] usb usb1: Manufacturer: Linux 2.6.36.4-3-b1dev-maxim-jibe-b1-00019-gb314b9a ohci_hcd Jan 1 01:05:25 jibe-eek user.info kernel: [ 328.530000] usb usb1: SerialNumber: maxim Jan 1 01:05:25 jibe-eek user.info kernel: [ 328.550000] hub 1-0:1.0: USB hub found Jan 1 01:05:25 jibe-eek user.info kernel: [ 328.590000] hub 1-0:1.0: 1 port detected Jan 1 01:05:39 jibe-eek user.info kernel: [ 342.110000] usb 1-1: new full speed USB device using maximasp-ohci and address 2 Jan 1 01:05:39 jibe-eek user.info kernel: [ 342.310000] usb 1-1: New USB device found, idVendor=163c, idProduct=0407 Jan 1 01:05:39 jibe-eek user.info kernel: [ 342.330000] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jan 1 01:05:39 jibe-eek user.info kernel: [ 342.350000] usb 1-1: Product: USB Key Jan 1 01:05:39 jibe-eek user.info kernel: [ 342.360000] usb 1-1: Manufacturer: Watchdata Jan 1 01:05:40 jibe-eek user.notice kernel: [ 343.140000] SCSI subsystem initialized Jan 1 01:05:40 jibe-eek user.info syslog: ccid_usb.c:478:OpenUSBByName() Can't detach driver of interface 0 from kernel: -99 Jan 1 01:05:40 jibe-eek user.info syslog: ccid_usb.c:478:OpenUSBByName() Can't detach driver of interface 1 from kernel: -99 Jan 1 01:05:40 jibe-eek user.info kernel: [ 343.470000] Initializing USB Mass Storage driver... Jan 1 01:05:40 jibe-eek user.info kernel: [ 343.500000] scsi0 : usb-storage 1-1:1.0 Jan 1 01:05:40 jibe-eek user.info syslog: ifdhandler.c:135:IFDHCreateChannelByName() failed Jan 1 01:05:40 jibe-eek user.info syslog: readerfactory.c:965:RFInitializeReader() Open Port 0x200001 Failed (usb:163c/0407:libhal:/org/freedesktop/Hal/devices/usb_device_163c_0407_serialnotneeded_if1) Jan 1 01:05:40 jibe-eek user.info syslog: readerfactory.c:275:RFAddReader() WD CCID init failed. Jan 1 01:05:40 jibe-eek user.info kernel: [ 343.560000] usbcore: registered new interface driver usb-storage Jan 1 01:05:40 jibe-eek user.info kernel: [ 343.600000] USB Mass Storage support registered. Jan 1 01:05:41 jibe-eek user.notice kernel: [ 344.600000] scsi 0:0:0:0: CD-ROM Watch CD-ROM 1.00 PQ: 0 ANSI: 5 Jan 1 01:05:54 jibe-eek user.info syslog: ccid_usb.c:684:ReadUSB() read failed (1/2): -99 Success Jan 1 01:05:54 jibe-eek user.debug kernel: [ 356.770000] usb 1-1: usbfs: usb_submit_urb returned -121 Jan 1 01:05:54 jibe-eek user.debug kernel: [ 357.080000] usb 1-1: usbfs: usb_submit_urb returned -121 Jan 1 01:05:54 jibe-eek user.info syslog: ccid_usb.c:684:ReadUSB() read failed (1/2): -99 Success Jan 1 01:05:56 jibe-eek user.debug kernel: [ 358.870000] usb 1-1: usbfs: usb_submit_urb returned -121 Jan 1 01:05:56 jibe-eek user.info syslog: ccid_usb.c:684:ReadUSB() read failed (1/2): -99 Success Jan 1 01:05:57 jibe-eek user.debug kernel: [ 359.670000] usb 1-1: usbfs: usb_submit_urb returned -121 Jan 1 01:05:57 jibe-eek user.info syslog: ccid_usb.c:684:ReadUSB() read failed (1/2): -99 Success Jan 1 01:05:59 jibe-eek user.err syslog: src/New_host.c:235 Mechanism Invalid Jan 1 01:07:00 jibe-eek user.info syslog: pcscdaemon.c:625:clean_temp_files() Cannot remove /var/run/pcscd_wd/pcscd_wd.comm: No such file or directory Jan 1 01:07:00 jibe-eek user.info syslog: pcscdaemon.c:630:clean_temp_files() Cannot remove /var/run/pcscd_wd/pcscd_wd.pid: No such file or directory Jan 1 01:07:00 jibe-eek user.info syslog: pcscdaemon.c:625:clean_temp_files() Cannot remove /var/run/pcscd_wd/pcscd_wd.comm: No such file or directory Jan 1 01:07:00 jibe-eek user.info syslog: pcscdaemon.c:630:clean_temp_files() Cannot remove /var/run/pcscd_wd/pcscd_wd.pid: No such file or directory Jan 1 01:14:25 jibe-eek user.err syslog: src/New_host.c:235 Mechanism Invalid Jan 1 02:39:28 jibe-eek user.info kernel: [ 5971.330000] eth0: link up (100/Full) Jan 1 03:09:26 jibe-eek user.info kernel: [ 7769.340000] eth0: link down