You are not logged in.

#1 2015-06-25 23:05:08

radnek
Member
From: Poland
Registered: 2015-06-25
Posts: 3

[SOLVED] After sys upgrade usb_modeswitch not run automatically

After full system upgrade usb_modeswitch is not automatically switching USB 3G modems. Manual switching works fine. I tried with Nokia 21M-02 and Huaweii E173.

Previous full system upgrade: 2015-05-27.

Package usb_modeswitch seems not to be upgraded.
Below upgrade log from /var/log/pacman.log:

[2015-06-24 19:07] [PACMAN] Running 'pacman -Syu'
[2015-06-24 19:07] [PACMAN] synchronizing package lists
[2015-06-24 19:07] [PACMAN] starting full system upgrade
[2015-06-24 19:25] [PACMAN] Running 'pacman -Syu'
[2015-06-24 19:25] [PACMAN] synchronizing package lists
[2015-06-24 19:25] [PACMAN] starting full system upgrade
[2015-06-24 19:43] [PACMAN] Running 'pacman -Syu'
[2015-06-24 19:43] [PACMAN] synchronizing package lists
[2015-06-24 19:44] [PACMAN] starting full system upgrade
[2015-06-24 19:44] [ALPM] transaction started
[2015-06-24 19:44] [ALPM] removed dnsutils (9.9.2.P2-2)
[2015-06-24 19:44] [ALPM] upgraded archlinux-keyring (20150523-1 -> 20150605-1)
[2015-06-24 19:44] [ALPM-SCRIPTLET] ==> Appending keys from archlinux.gpg...
[2015-06-24 19:44] [ALPM-SCRIPTLET] ==> Locally signing trusted keys in keyring...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Locally signing key 0E8B644079F599DFC1DDC3973348882F6AC6A4C2...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Locally signing key 684148BB25B49E986A4944C55184252D824B18E8...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Locally signing key 44D4A033AC140143927397D47EFD567D4C7EA887...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Locally signing key 27FFC4769E19F096D41D9265A04F9397CDFD6BB0...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Locally signing key AB19265E5D7D20687D303246BA1DFB64FFF979E7...
[2015-06-24 19:44] [ALPM-SCRIPTLET] ==> Importing owner trust values...
[2015-06-24 19:44] [ALPM-SCRIPTLET] ==> Disabling revoked keys in keyring...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Disabling key F5A361A3A13554B85E57DDDAAF7EF7873CFD4BB6...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Disabling key 7FA647CD89891DEDC060287BB9113D1ED21E1A55...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Disabling key D4DE5ABDE2A7287644EAC7E36D1A9E70E19DAA50...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Disabling key BC1FBE4D2826A0B51E47ED62E2539214C6C11350...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Disabling key 9515D8A8EAB88E49BB65EDBCE6B456CAF15447D5...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Disabling key 4A8B17E20B88ACA61860009B5CED81B7C2E5C0D2...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Disabling key 63F395DE2D6398BBE458F281F2DBB4931985A992...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Disabling key 0B20CA1931F5DA3A70D0F8D2EA6836E1AB441196...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Disabling key 8F76BEEA0289F9E1D3E229C05F946DED983D4366...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Disabling key 66BD74A036D522F51DD70A3C7F2A16726521E06D...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Disabling key 81D7F8241DB38BC759C80FCE3A726C6170E80477...
[2015-06-24 19:44] [ALPM-SCRIPTLET]   -> Disabling key E7210A59715F6940CF9A4E36A001876699AD6E84...
[2015-06-24 19:44] [ALPM-SCRIPTLET] ==> Updating trust database...
[2015-06-24 19:44] [ALPM-SCRIPTLET] gpg: next trustdb check due at 2016-01-22
[2015-06-24 19:44] [ALPM] upgraded tzdata (2015d-1 -> 2015e-1)
[2015-06-24 19:44] [ALPM] upgraded zlib (1.2.8-3 -> 1.2.8-4)
[2015-06-24 19:44] [ALPM] upgraded boost-libs (1.58.0-1 -> 1.58.0-2)
[2015-06-24 19:44] [ALPM] upgraded boost (1.58.0-1 -> 1.58.0-2)
[2015-06-24 19:44] [ALPM] upgraded openssl (1.0.2.a-1 -> 1.0.2.c-1)
[2015-06-24 19:44] [ALPM] upgraded pcre (8.37-1 -> 8.37-2)
[2015-06-24 19:44] [ALPM] upgraded ca-certificates-mozilla (3.18.1-1 -> 3.19.1-2)
[2015-06-24 19:44] [ALPM] upgraded libdbus (1.8.16-2 -> 1.8.18-1)
[2015-06-24 19:44] [ALPM] upgraded dbus (1.8.16-2 -> 1.8.18-1)
[2015-06-24 19:44] [ALPM] upgraded lz4 (129-1 -> 130-1)
[2015-06-24 19:44] [ALPM] upgraded libsystemd (219-6 -> 221-1)
[2015-06-24 19:44] [ALPM] upgraded libutil-linux (2.26.1-3 -> 2.26.2-1)
[2015-06-24 19:44] [ALPM] upgraded libtirpc (0.3.0-1 -> 0.3.2-2)
[2015-06-24 19:44] [ALPM] upgraded util-linux (2.26.1-3 -> 2.26.2-1)
[2015-06-24 19:44] [ALPM] warning: /etc/systemd/timesyncd.conf installed as /etc/systemd/timesyncd.conf.pacnew
[2015-06-24 19:44] [ALPM] upgraded systemd (219-6 -> 221-1)
[2015-06-24 19:44] [ALPM] installed libgudev (230-1)
[2015-06-24 19:44] [ALPM] upgraded libjpeg-turbo (1.4.0-1 -> 1.4.1-1)
[2015-06-24 19:44] [ALPM] upgraded colord (1.2.9-2 -> 1.2.9-3)
[2015-06-24 19:44] [ALPM] upgraded cronie (1.4.12-1 -> 1.5.0-1)
[2015-06-24 19:44] [ALPM] upgraded wayland (1.7.0-1 -> 1.8.1-1)
[2015-06-24 19:44] [ALPM] upgraded mesa (10.5.6-1 -> 10.6.0-1)
[2015-06-24 19:44] [ALPM] upgraded mesa-libgl (10.5.6-1 -> 10.6.0-1)
[2015-06-24 19:44] [ALPM] upgraded harfbuzz (0.9.40-2 -> 0.9.41-1)
[2015-06-24 19:44] [ALPM] upgraded freetype2 (2.5.5-1 -> 2.6-1)
[2015-06-24 19:44] [ALPM] upgraded poppler (0.32.0-1 -> 0.33.0-1)
[2015-06-24 19:44] [ALPM] upgraded qpdf (5.1.2-1 -> 5.1.3-1)
[2015-06-24 19:44] [ALPM] upgraded cups-filters (1.0.68-1 -> 1.0.69-1)
[2015-06-24 19:44] [ALPM] upgraded curl (7.42.0-1 -> 7.43.0-1)
[2015-06-24 19:44] [ALPM] upgraded device-mapper (2.02.116-1 -> 2.02.120-2)
[2015-06-24 19:44] [ALPM] upgraded dialog (1:1.2_20150513-1 -> 1:1.2_20150528-1)
[2015-06-24 19:44] [ALPM] installed geoip-database (20150602-1)
[2015-06-24 19:44] [ALPM] installed geoip (1.6.2-1)
[2015-06-24 19:44] [ALPM] installed bind-tools (9.10.2.P1-1)
[2015-06-24 19:44] [ALPM] upgraded ethtool (1:3.18-1 -> 1:4.0-1)
[2015-06-24 19:44] [ALPM] upgraded gnutls (3.4.1-1 -> 3.4.2-1)
[2015-06-24 19:44] [ALPM] upgraded x265 (1.6-1 -> 1.7-2)
[2015-06-24 19:44] [ALPM] upgraded ffmpeg (1:2.6.3-1 -> 1:2.7.1-1)
[2015-06-24 19:44] [ALPM] upgraded file (5.22-1 -> 5.23-2)
[2015-06-24 19:44] [ALPM] upgraded nss (3.18.1-1 -> 3.19.1-2)
[2015-06-24 19:44] [ALPM] upgraded gtk-update-icon-cache (3.16.3-2 -> 3.16.4-1)
[2015-06-24 19:44] [ALPM] upgraded libcups (2.0.2-4 -> 2.0.3-1)
[2015-06-24 19:44] [ALPM] upgraded firefox (38.0.1-1 -> 38.0.5-1)
[2015-06-24 19:44] [ALPM] upgraded flashplugin (11.2.202.460-1 -> 11.2.202.468-1)
[2015-06-24 19:44] [ALPM] upgraded foomatic-db-engine (3:20150308-1 -> 3:20150620-1)
[2015-06-24 19:44] [ALPM] upgraded mpfr (3.1.2.p11-1 -> 3.1.3-1)
[2015-06-24 19:44] [ALPM] upgraded gawk (4.1.2-1 -> 4.1.3-1)
[2015-06-24 19:44] [ALPM] upgraded libmng (2.0.2-3 -> 2.0.3-1)
[2015-06-24 19:44] [ALPM] upgraded python2 (2.7.9-1 -> 2.7.10-1)
[2015-06-24 19:44] [ALPM] upgraded gimp (2.8.14-2 -> 2.8.14-3)
[2015-06-24 19:44] [ALPM] upgraded perl-error (0.17023-1 -> 0.17024-1)
[2015-06-24 19:44] [ALPM] upgraded git (2.4.1-1 -> 2.4.4-1)
[2015-06-24 19:44] [ALPM] upgraded gnupg (2.1.4-1 -> 2.1.5-1)
[2015-06-24 19:44] [ALPM] upgraded xkeyboard-config (2.14-1 -> 2.15-1)
[2015-06-24 19:44] [ALPM] upgraded gtk3 (3.16.3-2 -> 3.16.4-1)
[2015-06-24 19:45] [ALPM] upgraded harfbuzz-icu (0.9.40-2 -> 0.9.41-1)
[2015-06-24 19:45] [ALPM] upgraded hplip (3.15.4-1 -> 3.15.6-1)
[2015-06-24 19:45] [ALPM] upgraded hunspell-en (7.1-3 -> 2015.05.18-4)
[2015-06-24 19:45] [ALPM] upgraded hunspell-pl (20120911-2 -> 20150428-2)
[2015-06-24 19:45] [ALPM] upgraded inetutils (1.9.3-1 -> 1.9.4-1)
[2015-06-24 19:45] [ALPM] upgraded iproute2 (4.0.0-1 -> 4.0.0-2)
[2015-06-24 19:45] [ALPM] upgraded libdvbpsi (1:1.1.2-1 -> 1:1.3.0-1)
[2015-06-24 19:45] [ALPM] upgraded libreoffice-still (4.3.7-1 -> 4.3.7-3)
[2015-06-24 19:45] [ALPM] upgraded libuv (1.5.0-1 -> 1.6.1-1)
[2015-06-24 19:45] [ALPM] upgraded libvisual (0.4.0-5 -> 0.4.0-6)
[2015-06-24 19:45] [ALPM] upgraded libwps (0.3.1-1 -> 0.4.0-1)
[2015-06-24 19:45] [ALPM] upgraded linux-firmware (20150511.8e18132-1 -> 20150527.3161bfa-1)
[2015-06-24 19:45] [ALPM] upgraded linux (4.0.4-2 -> 4.0.5-1)
[2015-06-24 19:45] [ALPM-SCRIPTLET] >>> Updating module dependencies. Please wait ...
[2015-06-24 19:45] [ALPM-SCRIPTLET] >>> Generating initial ramdisk, using mkinitcpio.  Please wait...
[2015-06-24 19:45] [ALPM-SCRIPTLET] ==> Building image from preset: /etc/mkinitcpio.d/linux.preset: 'default'
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> -k /boot/vmlinuz-linux -c /etc/mkinitcpio.conf -g /boot/initramfs-linux.img
[2015-06-24 19:45] [ALPM-SCRIPTLET] ==> Starting build: 4.0.5-1-ARCH
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [base]
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [udev]
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [autodetect]
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [modconf]
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [block]
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [filesystems]
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [keyboard]
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [fsck]
[2015-06-24 19:45] [ALPM-SCRIPTLET] ==> Generating module dependencies
[2015-06-24 19:45] [ALPM-SCRIPTLET] ==> Creating gzip-compressed initcpio image: /boot/initramfs-linux.img
[2015-06-24 19:45] [ALPM-SCRIPTLET] ==> Image generation successful
[2015-06-24 19:45] [ALPM-SCRIPTLET] ==> Building image from preset: /etc/mkinitcpio.d/linux.preset: 'fallback'
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> -k /boot/vmlinuz-linux -c /etc/mkinitcpio.conf -g /boot/initramfs-linux-fallback.img -S autodetect
[2015-06-24 19:45] [ALPM-SCRIPTLET] ==> Starting build: 4.0.5-1-ARCH
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [base]
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [udev]
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [modconf]
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [block]
[2015-06-24 19:45] [ALPM-SCRIPTLET] ==> WARNING: Possibly missing firmware for module: aic94xx
[2015-06-24 19:45] [ALPM-SCRIPTLET] ==> WARNING: Possibly missing firmware for module: wd719x
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [filesystems]
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [keyboard]
[2015-06-24 19:45] [ALPM-SCRIPTLET]   -> Running build hook: [fsck]
[2015-06-24 19:45] [ALPM-SCRIPTLET] ==> Generating module dependencies
[2015-06-24 19:45] [ALPM-SCRIPTLET] ==> Creating gzip-compressed initcpio image: /boot/initramfs-linux-fallback.img
[2015-06-24 19:45] [ALPM-SCRIPTLET] ==> Image generation successful
[2015-06-24 19:45] [ALPM] upgraded lua (5.3.0-2 -> 5.3.1-1)
[2015-06-24 19:45] [ALPM] upgraded lvm2 (2.02.116-1 -> 2.02.120-2)
[2015-06-24 19:45] [ALPM] upgraded openbox (3.5.2-7 -> 3.6-1)
[2015-06-24 19:45] [ALPM] upgraded lxappearance-obconf (0.2.2-1 -> 0.2.2-2)
[2015-06-24 19:45] [ALPM] upgraded mono (4.0.1.28-1 -> 4.0.1.44-1)
[2015-06-24 19:45] [ALPM] upgraded openra (20150424-1 -> 20150614-1)
[2015-06-24 19:45] [ALPM] warning: /etc/pacman.d/mirrorlist installed as /etc/pacman.d/mirrorlist.pacnew
[2015-06-24 19:45] [ALPM] upgraded pacman-mirrorlist (20150519-1 -> 20150618-1)
[2015-06-24 19:45] [ALPM] upgraded perl-encode-locale (1.04-1 -> 1.05-1)
[2015-06-24 19:46] [ALPM] upgraded qt4 (4.8.6-6 -> 4.8.7-1)
[2015-06-24 19:46] [ALPM] upgraded vlc (2.2.1-4 -> 2.2.1-6)
[2015-06-24 19:46] [ALPM] upgraded phonon-qt4 (4.8.3-1 -> 4.8.3-2)
[2015-06-24 19:46] [ALPM] upgraded poppler-glib (0.32.0-1 -> 0.33.0-1)
[2015-06-24 19:46] [ALPM] upgraded qt5-base (5.4.1-9 -> 5.4.2-1)
[2015-06-24 19:46] [ALPM] upgraded qt5-xmlpatterns (5.4.1-9 -> 5.4.2-1)
[2015-06-24 19:46] [ALPM] upgraded qt5-declarative (5.4.1-9 -> 5.4.2-1)
[2015-06-24 19:46] [ALPM] upgraded qt5-location (5.4.1-9 -> 5.4.2-1)
[2015-06-24 19:46] [ALPM] upgraded qt5-sensors (5.4.1-9 -> 5.4.2-1)
[2015-06-24 19:46] [ALPM] upgraded qt5-webchannel (5.4.1-9 -> 5.4.2-1)
[2015-06-24 19:46] [ALPM] upgraded qt5-webkit (5.4.1-9 -> 5.4.2-1)
[2015-06-24 19:46] [ALPM] upgraded qt5-script (5.4.1-9 -> 5.4.2-1)
[2015-06-24 19:46] [ALPM] upgraded qt5-quick1 (5.4.1-9 -> 5.4.2-1)
[2015-06-24 19:46] [ALPM] upgraded s-nail (14.8.0-1 -> 14.8.2-1)
[2015-06-24 19:46] [ALPM] upgraded systemd-sysvcompat (219-6 -> 221-1)
[2015-06-24 19:46] [ALPM] upgraded udisks (1.0.5-1 -> 1.0.5-2)
[2015-06-24 19:46] [ALPM] upgraded xf86-video-intel (2.99.917-5 -> 1:2.99.917+364+gb24e758-1)
[2015-06-24 19:46] [ALPM] upgraded xorg-server-common (1.17.1-5 -> 1.17.2-1)
[2015-06-24 19:46] [ALPM] upgraded xorg-server (1.17.1-5 -> 1.17.2-1)
[2015-06-24 19:46] [ALPM] transaction completed

Below are journal logs after inserting modem to USB.

1. Before upgrade (behaviour as expected):

cze 24 19:02:33 rd kernel: usb 7-1: new high-speed USB device number 2 using ehc
cze 24 19:02:33 rd kernel: usb-storage 7-1:1.0: USB Mass Storage device detected
cze 24 19:02:33 rd kernel: scsi host5: usb-storage 7-1:1.0
cze 24 19:02:33 rd kernel: usb-storage 7-1:1.1: USB Mass Storage device detected
cze 24 19:02:33 rd kernel: scsi host6: usb-storage 7-1:1.1
cze 24 19:02:33 rd udisks-daemon[391]: **** scsi_host ADDING /sys/devices/pci000
cze 24 19:02:33 rd udisks-daemon[391]: **** scsi_host IGNORING ADD /sys/devices/
cze 24 19:02:33 rd udisks-daemon[391]: **** scsi_host ADDING /sys/devices/pci000
cze 24 19:02:33 rd udisks-daemon[391]: **** scsi_host IGNORING ADD /sys/devices/
cze 24 19:02:34 rd usb_modeswitch[554]: switch device 12d1:1446 on 007/002               <---- !!!!!
cze 24 19:02:34 rd udisks-daemon[391]: **** scsi_host IGNORING REMOVE /sys/devic
cze 24 19:02:34 rd kernel: usb 7-1: USB disconnect, device number 2
cze 24 19:02:34 rd systemd-udevd[156]: error opening USB device 'descriptors' fi
cze 24 19:02:34 rd udisks-daemon[391]: **** scsi_host IGNORING REMOVE /sys/devic
cze 24 19:02:38 rd kernel: usb 7-1: new high-speed USB device number 3 using ehc
cze 24 19:02:38 rd kernel: usb-storage 7-1:1.0: USB Mass Storage device detected
cze 24 19:02:38 rd kernel: usb-storage 7-1:1.1: USB Mass Storage device detected
cze 24 19:02:38 rd kernel: usb-storage 7-1:1.2: USB Mass Storage device detected
cze 24 19:02:38 rd kernel: usb-storage 7-1:1.3: USB Mass Storage device detected
cze 24 19:02:38 rd kernel: usb-storage 7-1:1.4: USB Mass Storage device detected
cze 24 19:02:38 rd kernel: usb-storage 7-1:1.5: USB Mass Storage device detected
cze 24 19:02:38 rd kernel: scsi host12: usb-storage 7-1:1.5
cze 24 19:02:38 rd kernel: usb-storage 7-1:1.6: USB Mass Storage device detected
cze 24 19:02:38 rd kernel: scsi host13: usb-storage 7-1:1.6
cze 24 19:02:38 rd udisks-daemon[391]: **** scsi_host ADDING /sys/devices/pci000
cze 24 19:02:38 rd udisks-daemon[391]: **** scsi_host IGNORING ADD /sys/devices/
cze 24 19:02:38 rd udisks-daemon[391]: **** scsi_host ADDING /sys/devices/pci000
cze 24 19:02:38 rd udisks-daemon[391]: **** scsi_host IGNORING ADD /sys/devices/
cze 24 19:02:38 rd kernel: cdc_ether 7-1:1.1 wwan0: register 'cdc_ether' at usb-
cze 24 19:02:38 rd kernel: usbcore: registered new interface driver cdc_ether
cze 24 19:02:38 rd kernel: cdc_ether 7-1:1.1 wwp0s26f7u1i1: renamed from wwan0
cze 24 19:02:38 rd kernel: usbcore: registered new interface driver usbserial
cze 24 19:02:38 rd kernel: usbcore: registered new interface driver usbserial_ge
cze 24 19:02:38 rd kernel: usbserial: USB Serial support registered for generic
cze 24 19:02:38 rd kernel: usbcore: registered new interface driver option
cze 24 19:02:38 rd kernel: usbserial: USB Serial support registered for GSM mode
cze 24 19:02:38 rd kernel: option 7-1:1.0: GSM modem (1-port) converter detected
cze 24 19:02:38 rd kernel: usb 7-1: GSM modem (1-port) converter now attached to
cze 24 19:02:38 rd kernel: option 7-1:1.3: GSM modem (1-port) converter detected
cze 24 19:02:38 rd kernel: usb 7-1: GSM modem (1-port) converter now attached to
cze 24 19:02:38 rd kernel: option 7-1:1.4: GSM modem (1-port) converter detected
cze 24 19:02:38 rd kernel: usb 7-1: GSM modem (1-port) converter now attached to
cze 24 19:02:39 rd kernel: scsi 12:0:0:0: CD-ROM            HUAWEI   Mass Storag
cze 24 19:02:39 rd kernel: scsi 13:0:0:0: Direct-Access     HUAWEI   SD Storage 
cze 24 19:02:39 rd kernel: sr 12:0:0:0: [sr1] scsi-1 drive
cze 24 19:02:39 rd kernel: sr 12:0:0:0: Attached scsi CD-ROM sr1
cze 24 19:02:39 rd kernel: sd 13:0:0:0: [sdb] Attached SCSI removable disk

2. After upgrade (unexpected behaviour - modem is still in usb-storage mode):

cze 24 20:27:54 rd kernel: usb 8-2: new high-speed USB device number 2 using ehc
cze 24 20:27:54 rd kernel: usb-storage 8-2:1.0: USB Mass Storage device detected
cze 24 20:27:54 rd kernel: scsi host4: usb-storage 8-2:1.0
cze 24 20:27:54 rd kernel: usb-storage 8-2:1.1: USB Mass Storage device detected
cze 24 20:27:54 rd kernel: scsi host5: usb-storage 8-2:1.1
cze 24 20:27:54 rd kernel: usbcore: registered new interface driver usb-storage
cze 24 20:27:54 rd kernel: usbcore: registered new interface driver uas
cze 24 20:27:54 rd udisks-daemon[975]: **** scsi_host ADDING /sys/devices/pci000
cze 24 20:27:54 rd udisks-daemon[975]: **** scsi_host IGNORING ADD /sys/devices/
cze 24 20:27:54 rd udisks-daemon[975]: **** scsi_host ADDING /sys/devices/pci000
cze 24 20:27:54 rd udisks-daemon[975]: **** scsi_host IGNORING ADD /sys/devices/
cze 24 20:27:55 rd kernel: scsi 4:0:0:0: CD-ROM            HUAWEI   Mass Storage
cze 24 20:27:55 rd kernel: scsi 5:0:0:0: Direct-Access     HUAWEI   SD Storage  
cze 24 20:27:55 rd kernel: sr 4:0:0:0: [sr1] scsi-1 drive
cze 24 20:27:55 rd kernel: sr 4:0:0:0: Attached scsi CD-ROM sr1
cze 24 20:27:55 rd kernel: sd 5:0:0:0: [sdb] Attached SCSI removable disk

Manual switching works fine:

cze 24 21:17:28 rd sudo[3267]:    radek : TTY=tty1 ; PWD=/home/radek ; USER=root ; COMMAND=/usr/bin/usb_modeswitch -v12d1 -p1446 -c /etc/usb_modeswitch.conf -J
cze 24 21:17:29 rd kernel: usb 8-1: USB disconnect, device number 4
cze 24 21:17:28 rd sudo[3267]: pam_unix(sudo:session): session opened for user root by radek(uid=0)
cze 24 21:17:29 rd sudo[3267]: pam_unix(sudo:session): session closed for user root
cze 24 21:17:33 rd kernel: usb 8-1: new high-speed USB device number 5 using ehci-pci
cze 24 21:17:33 rd kernel: usb-storage 8-1:1.0: USB Mass Storage device detected
cze 24 21:17:33 rd kernel: usb-storage 8-1:1.1: USB Mass Storage device detected
cze 24 21:17:33 rd kernel: usb-storage 8-1:1.2: USB Mass Storage device detected
cze 24 21:17:33 rd kernel: usb-storage 8-1:1.3: USB Mass Storage device detected
cze 24 21:17:33 rd kernel: usb-storage 8-1:1.4: USB Mass Storage device detected
cze 24 21:17:33 rd kernel: usb-storage 8-1:1.5: USB Mass Storage device detected
cze 24 21:17:33 rd kernel: scsi host13: usb-storage 8-1:1.5
cze 24 21:17:33 rd kernel: usb-storage 8-1:1.6: USB Mass Storage device detected
cze 24 21:17:33 rd kernel: scsi host14: usb-storage 8-1:1.6
cze 24 21:17:33 rd kernel: cdc_ether 8-1:1.1 wwan0: register 'cdc_ether' at usb-0000:00:1d.7-1, Mobile Broadband Network Device, 02:50:f3:00:00:00
cze 24 21:17:33 rd kernel: usbcore: registered new interface driver cdc_ether
cze 24 21:17:33 rd kernel: cdc_ether 8-1:1.1 wwp0s29f7u1i1: renamed from wwan0
cze 24 21:17:33 rd kernel: usbcore: registered new interface driver usbserial
cze 24 21:17:33 rd kernel: usbcore: registered new interface driver usbserial_generic
cze 24 21:17:33 rd kernel: usbserial: USB Serial support registered for generic
cze 24 21:17:33 rd kernel: usbcore: registered new interface driver option
cze 24 21:17:33 rd kernel: usbserial: USB Serial support registered for GSM modem (1-port)
cze 24 21:17:33 rd kernel: option 8-1:1.0: GSM modem (1-port) converter detected
cze 24 21:17:33 rd kernel: usb 8-1: GSM modem (1-port) converter now attached to ttyUSB0
cze 24 21:17:33 rd kernel: option 8-1:1.3: GSM modem (1-port) converter detected
cze 24 21:17:33 rd kernel: usb 8-1: GSM modem (1-port) converter now attached to ttyUSB1
cze 24 21:17:33 rd kernel: option 8-1:1.4: GSM modem (1-port) converter detected
cze 24 21:17:33 rd kernel: usb 8-1: GSM modem (1-port) converter now attached to ttyUSB2
cze 24 21:17:34 rd kernel: scsi 13:0:0:0: CD-ROM            HUAWEI   Mass Storage     2.31 PQ: 0 ANSI: 2
cze 24 21:17:34 rd kernel: scsi 14:0:0:0: Direct-Access     HUAWEI   SD Storage       2.31 PQ: 0 ANSI: 2
cze 24 21:17:34 rd kernel: sr 13:0:0:0: [sr1] scsi-1 drive
cze 24 21:17:34 rd kernel: sr 13:0:0:0: Attached scsi CD-ROM sr1
cze 24 21:17:34 rd kernel: sd 14:0:0:0: [sdb] Attached SCSI removable disk

My guess is that new systemd caused the change.

How to investigate the problem further?

Last edited by radnek (2015-07-01 20:53:07)

Offline

#2 2015-06-26 20:10:11

Head_on_a_Stick
Member
From: London
Registered: 2014-02-20
Posts: 7,680
Website

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically

radnek wrote:

My guess is that new systemd caused the change.

Does downgrading systemd bring back the expected behaviour?

Offline

#3 2015-06-27 05:52:33

mauritiusdadd
Member
From: Benevento, Italy
Registered: 2013-10-27
Posts: 776

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically

It seems you are not the only one with this issue: https://bbs.archlinux.org/viewtopic.php … 2#p1539922

Also, I have a spare huawei E1820 ( vendor-id = 12d1 and device-id = 1446), and I can confirm that usb_modeswitch does not work unless you run it manually. For the record, if I change the udev log level to debug:

udevadm control --log-priority=debug

this is what is written in the journal when I connect the device

Jun 27 07:37:47 530U3C kernel: usb 3-1: new high-speed USB device number 2 using xhci_hcd
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1556 queued, 'add' 'usb'
Jun 27 07:37:48 530U3C systemd-udevd[145]: Validate module index
Jun 27 07:37:48 530U3C systemd-udevd[145]: Check if link configuration needs reloading.
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1556 forked new worker [28162]
Jun 27 07:37:48 530U3C systemd-udevd[28162]: seq 1556 running
Jun 27 07:37:48 530U3C systemd-udevd[28162]: IMPORT builtin 'usb_id' /usr/lib/udev/rules.d/40-gphoto.rules:9
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1557 queued, 'add' 'usb'
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1558 queued, 'add' 'usb'
Jun 27 07:37:48 530U3C systemd-udevd[28162]: IMPORT builtin skip 'usb_id' /usr/lib/udev/rules.d/50-udev-default.rules:13
Jun 27 07:37:48 530U3C systemd-udevd[28162]: IMPORT builtin 'hwdb' /usr/lib/udev/rules.d/50-udev-default.rules:13
Jun 27 07:37:48 530U3C systemd-udevd[28162]: MODE 0664 /usr/lib/udev/rules.d/50-udev-default.rules:41
Jun 27 07:37:48 530U3C systemd-udevd[28162]: GROUP 992 /usr/lib/udev/rules.d/51-android.rules:444
Jun 27 07:37:48 530U3C systemd-udevd[28162]: MODE 0660 /usr/lib/udev/rules.d/51-android.rules:444
Jun 27 07:37:48 530U3C systemd-udevd[28162]: RUN '/usr/share/virtualbox/VBoxCreateUSBNode.sh $major $minor $attr{bDeviceClass} vboxusers' /usr/lib/udev/rules.d/60-vboxdrv.rules:6
Jun 27 07:37:48 530U3C systemd-udevd[28162]: PROGRAM '/usr/lib/udev/mtp-probe /sys/devices/pci0000:00/0000:00:14.0/usb3/3-1 3 2' /usr/lib/udev/rules.d/69-libmtp.rules:1757
Jun 27 07:37:48 530U3C systemd-udevd[28163]: starting '/usr/lib/udev/mtp-probe /sys/devices/pci0000:00/0000:00:14.0/usb3/3-1 3 2'
Jun 27 07:37:48 530U3C mtp-probe[28163]: checking bus 3, device 2: "/sys/devices/pci0000:00/0000:00:14.0/usb3/3-1"
Jun 27 07:37:48 530U3C mtp-probe[28163]: bus: 3, device: 2 was not an MTP device
Jun 27 07:37:48 530U3C systemd-udevd[28162]: '/usr/lib/udev/mtp-probe /sys/devices/pci0000:00/0000:00:14.0/usb3/3-1 3 2'(out) '0'
Jun 27 07:37:48 530U3C systemd-udevd[28162]: Process '/usr/lib/udev/mtp-probe /sys/devices/pci0000:00/0000:00:14.0/usb3/3-1 3 2' succeeded.
Jun 27 07:37:48 530U3C systemd-udevd[28162]: IMPORT builtin 'path_id' /usr/lib/udev/rules.d/71-seat.rules:44
Jun 27 07:37:48 530U3C systemd-udevd[28162]: RUN 'uaccess' /usr/lib/udev/rules.d/73-seat-late.rules:15
Jun 27 07:37:48 530U3C systemd-udevd[28162]: handling device node '/dev/bus/usb/003/002', devnum=c189:257, mode=0660, uid=0, gid=992
Jun 27 07:37:48 530U3C systemd-udevd[28162]: set permissions /dev/bus/usb/003/002, 020660, uid=0, gid=992
Jun 27 07:37:48 530U3C systemd-udevd[28162]: creating symlink '/dev/char/189:257' to '../bus/usb/003/002'
Jun 27 07:37:48 530U3C systemd-udevd[28162]: created db file '/run/udev/data/c189:257' for '/devices/pci0000:00/0000:00:14.0/usb3/3-1'
Jun 27 07:37:48 530U3C systemd-udevd[28164]: starting '/usr/share/virtualbox/VBoxCreateUSBNode.sh 189 257 00 vboxusers'
Jun 27 07:37:48 530U3C systemd-udevd[28162]: Process '/usr/share/virtualbox/VBoxCreateUSBNode.sh 189 257 00 vboxusers' succeeded.
Jun 27 07:37:48 530U3C systemd-udevd[28162]: passed device to netlink monitor 0x7fd00ab545b0
Jun 27 07:37:48 530U3C systemd-udevd[28162]: seq 1556 processed
Jun 27 07:37:48 530U3C systemd-udevd[145]: passed 292 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:48 530U3C systemd-udevd[28162]: seq 1557 running
Jun 27 07:37:48 530U3C systemd-udevd[28162]: IMPORT builtin 'usb_id' /usr/lib/udev/rules.d/40-gphoto.rules:9
Jun 27 07:37:48 530U3C systemd-udevd[28175]: seq 1558 running
Jun 27 07:37:48 530U3C systemd-udevd[28162]: unable to access usb_interface device of '/sys/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.0'
Jun 27 07:37:48 530U3C systemd-udevd[28162]: IMPORT builtin 'usb_id' returned non-zero
Jun 27 07:37:48 530U3C systemd-udevd[28175]: IMPORT builtin 'usb_id' /usr/lib/udev/rules.d/40-gphoto.rules:9
Jun 27 07:37:48 530U3C systemd-udevd[28162]: RUN 'usb_modeswitch '%b/%k'' /usr/lib/udev/rules.d/40-usb_modeswitch.rules:24
Jun 27 07:37:48 530U3C systemd-udevd[28162]: IMPORT builtin 'hwdb' /usr/lib/udev/rules.d/50-udev-default.rules:15
Jun 27 07:37:48 530U3C systemd-udevd[28175]: unable to access usb_interface device of '/sys/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.1'
Jun 27 07:37:48 530U3C systemd-udevd[28175]: IMPORT builtin 'usb_id' returned non-zero
Jun 27 07:37:48 530U3C systemd-udevd[28175]: IMPORT builtin 'hwdb' /usr/lib/udev/rules.d/50-udev-default.rules:15
Jun 27 07:37:48 530U3C systemd-udevd[28162]: RUN 'kmod load $env{MODALIAS}' /usr/lib/udev/rules.d/80-drivers.rules:5
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1558 forked new worker [28175]
Jun 27 07:37:48 530U3C systemd-udevd[28162]: created db file '/run/udev/data/+usb:3-1:1.0' for '/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.0'
Jun 27 07:37:48 530U3C systemd-udevd[28177]: starting 'usb_modeswitch '/3-1:1.0''
Jun 27 07:37:48 530U3C systemd-udevd[28175]: RUN 'kmod load $env{MODALIAS}' /usr/lib/udev/rules.d/80-drivers.rules:5
Jun 27 07:37:48 530U3C systemd-udevd[28175]: created db file '/run/udev/data/+usb:3-1:1.1' for '/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.1'
Jun 27 07:37:48 530U3C systemd-udevd[28175]: Execute 'load' 'usb:v12D1p1446d0000dc00dsc00dp00ic08isc06ip50in01'
Jun 27 07:37:48 530U3C systemd-udevd[28162]: Process 'usb_modeswitch '/3-1:1.0'' succeeded.
Jun 27 07:37:48 530U3C systemd-udevd[28162]: Execute 'load' 'usb:v12D1p1446d0000dc00dsc00dp00ic08isc06ip50in00'
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1559 queued, 'add' 'module'
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1559 forked new worker [28183]
Jun 27 07:37:48 530U3C kernel: usb-storage 3-1:1.0: USB Mass Storage device detected
Jun 27 07:37:48 530U3C kernel: scsi host6: usb-storage 3-1:1.0
Jun 27 07:37:48 530U3C kernel: usb-storage 3-1:1.1: USB Mass Storage device detected
Jun 27 07:37:48 530U3C systemd-udevd[28183]: seq 1559 running
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1560 queued, 'add' 'scsi'
Jun 27 07:37:48 530U3C kernel: scsi host7: usb-storage 3-1:1.1
Jun 27 07:37:48 530U3C kernel: usbcore: registered new interface driver usb-storage
Jun 27 07:37:48 530U3C systemd-udevd[28183]: passed device to netlink monitor 0x7fd00ab59050
Jun 27 07:37:48 530U3C systemd-udevd[28183]: seq 1559 processed
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1561 queued, 'add' 'scsi_host'
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1562 queued, 'add' 'scsi'
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1563 queued, 'add' 'scsi_host'
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1564 queued, 'add' 'drivers'
Jun 27 07:37:48 530U3C systemd-udevd[145]: passed 146 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:48 530U3C systemd-udevd[28183]: seq 1564 running
Jun 27 07:37:48 530U3C kernel: usbcore: registered new interface driver uas
Jun 27 07:37:48 530U3C systemd-udevd[28183]: passed device to netlink monitor 0x7fd00ab59050
Jun 27 07:37:48 530U3C systemd-udevd[28183]: seq 1564 processed
Jun 27 07:37:48 530U3C systemd-udevd[28162]: Inserted 'uas'
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1565 queued, 'add' 'module'
Jun 27 07:37:48 530U3C systemd-udevd[28175]: Inserted 'uas'
Jun 27 07:37:48 530U3C systemd-udevd[145]: passed 128 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:48 530U3C systemd-udevd[28162]: Inserted 'usb_storage'
Jun 27 07:37:48 530U3C systemd-udevd[28183]: seq 1565 running
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1566 queued, 'add' 'drivers'
Jun 27 07:37:48 530U3C systemd-udevd[28162]: passed device to netlink monitor 0x7fd00ab545b0
Jun 27 07:37:48 530U3C systemd-udevd[28162]: seq 1557 processed
Jun 27 07:37:48 530U3C systemd-udevd[28183]: passed device to netlink monitor 0x7fd00ab59050
Jun 27 07:37:48 530U3C systemd-udevd[28175]: Inserted 'usb_storage'
Jun 27 07:37:48 530U3C systemd-udevd[28190]: seq 1566 running
Jun 27 07:37:48 530U3C systemd-udevd[145]: seq 1566 forked new worker [28190]
Jun 27 07:37:48 530U3C systemd-udevd[28183]: seq 1565 processed
Jun 27 07:37:48 530U3C systemd-udevd[28175]: passed device to netlink monitor 0x7fd00ab47580
Jun 27 07:37:48 530U3C systemd-udevd[28190]: passed device to netlink monitor 0x7fd00ab4d110
Jun 27 07:37:48 530U3C systemd-udevd[145]: passed 188 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:48 530U3C systemd-udevd[28162]: seq 1560 running
Jun 27 07:37:48 530U3C systemd-udevd[28175]: seq 1558 processed
Jun 27 07:37:48 530U3C systemd-udevd[28162]: passed device to netlink monitor 0x7fd00ab545b0
Jun 27 07:37:48 530U3C systemd-udevd[145]: passed 188 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:48 530U3C systemd-udevd[28183]: seq 1562 running
Jun 27 07:37:48 530U3C systemd-udevd[28190]: seq 1566 processed
Jun 27 07:37:48 530U3C systemd-udevd[28183]: passed device to netlink monitor 0x7fd00ab59050
Jun 27 07:37:48 530U3C systemd-udevd[28183]: seq 1562 processed
Jun 27 07:37:48 530U3C systemd-udevd[28162]: seq 1560 processed
Jun 27 07:37:48 530U3C systemd-udevd[145]: passed 191 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:48 530U3C systemd-udevd[28190]: seq 1563 running
Jun 27 07:37:48 530U3C systemd-udevd[145]: passed 191 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:48 530U3C systemd-udevd[28183]: seq 1561 running
Jun 27 07:37:48 530U3C systemd-udevd[28190]: passed device to netlink monitor 0x7fd00ab4d110
Jun 27 07:37:48 530U3C systemd-udevd[28190]: seq 1563 processed
Jun 27 07:37:48 530U3C systemd-udevd[28183]: passed device to netlink monitor 0x7fd00ab59050
Jun 27 07:37:48 530U3C systemd-udevd[28183]: seq 1561 processed
Jun 27 07:37:48 530U3C systemd-udevd[145]: cleanup idle workers
Jun 27 07:37:48 530U3C systemd-udevd[28183]: Unload module index
Jun 27 07:37:48 530U3C systemd-udevd[28190]: Unload module index
Jun 27 07:37:48 530U3C systemd-udevd[28162]: Unload module index
Jun 27 07:37:48 530U3C systemd-udevd[28175]: Unload module index
Jun 27 07:37:48 530U3C systemd-udevd[28183]: Unloaded link configuration context.
Jun 27 07:37:48 530U3C systemd-udevd[28190]: Unloaded link configuration context.
Jun 27 07:37:48 530U3C systemd-udevd[28162]: Unloaded link configuration context.
Jun 27 07:37:48 530U3C systemd-udevd[28175]: Unloaded link configuration context.
Jun 27 07:37:48 530U3C systemd-udevd[145]: worker [28183] exited
Jun 27 07:37:48 530U3C systemd-udevd[145]: worker [28162] exited
Jun 27 07:37:48 530U3C systemd-udevd[145]: worker [28175] exited
Jun 27 07:37:48 530U3C systemd-udevd[145]: worker [28190] exited
Jun 27 07:37:49 530U3C kernel: scsi 6:0:0:0: CD-ROM            HUAWEI   Mass Storage     2.31 PQ: 0 ANSI: 2
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1567 queued, 'add' 'scsi'
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1567 forked new worker [28195]
Jun 27 07:37:49 530U3C kernel: scsi 7:0:0:0: Direct-Access     HUAWEI   TF CARD Storage       PQ: 0 ANSI: 2
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1568 queued, 'add' 'scsi'
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1569 queued, 'add' 'scsi_device'
Jun 27 07:37:49 530U3C systemd-udevd[28195]: seq 1567 running
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1570 queued, 'add' 'bsg'
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1571 queued, 'add' 'scsi'
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1571 forked new worker [28197]
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1572 queued, 'add' 'scsi'
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1573 queued, 'add' 'scsi_disk'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: seq 1571 running
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1574 queued, 'add' 'scsi_device'
Jun 27 07:37:49 530U3C kernel: sd 7:0:0:0: [sdc] Attached SCSI removable disk
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1575 queued, 'add' 'bsg'
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1576 queued, 'add' 'bdi'
Jun 27 07:37:49 530U3C systemd-udevd[28195]: passed device to netlink monitor 0x7fd00ab545b0
Jun 27 07:37:49 530U3C systemd-udevd[28195]: seq 1567 processed
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1576 forked new worker [28198]
Jun 27 07:37:49 530U3C systemd-udevd[145]: passed 231 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:49 530U3C systemd-udevd[28195]: seq 1568 running
Jun 27 07:37:49 530U3C systemd-udevd[28197]: passed device to netlink monitor 0x7fd00ab2c070
Jun 27 07:37:49 530U3C systemd-udevd[28198]: seq 1576 running
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1577 queued, 'add' 'block'
Jun 27 07:37:49 530U3C systemd-udevd[28195]: IMPORT builtin 'hwdb' /usr/lib/udev/rules.d/50-udev-default.rules:15
Jun 27 07:37:49 530U3C systemd-udevd[28197]: seq 1571 processed
Jun 27 07:37:49 530U3C systemd-udevd[28198]: passed device to netlink monitor 0x7fd00ab4eac0
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1578 queued, 'change' 'block'
Jun 27 07:37:49 530U3C systemd-udevd[28195]: IMPORT builtin 'hwdb' returned non-zero
Jun 27 07:37:49 530U3C systemd-udevd[28198]: seq 1576 processed
Jun 27 07:37:49 530U3C systemd-udevd[145]: passed 231 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:49 530U3C systemd-udevd[28197]: seq 1572 running
Jun 27 07:37:49 530U3C systemd-udevd[28195]: RUN 'kmod load $env{MODALIAS}' /usr/lib/udev/rules.d/80-drivers.rules:5
Jun 27 07:37:49 530U3C systemd-udevd[28197]: IMPORT builtin 'hwdb' /usr/lib/udev/rules.d/50-udev-default.rules:15
Jun 27 07:37:49 530U3C systemd-udevd[28195]: Execute 'load' 'scsi:t-0x05'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: IMPORT builtin 'hwdb' returned non-zero
Jun 27 07:37:49 530U3C systemd-udevd[28197]: RUN 'kmod load $env{MODALIAS}' /usr/lib/udev/rules.d/80-drivers.rules:5
Jun 27 07:37:49 530U3C systemd-udevd[28197]: Execute 'load' 'scsi:t-0x00'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: Inserted 'sd_mod'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: passed device to netlink monitor 0x7fd00ab2c070
Jun 27 07:37:49 530U3C systemd-udevd[28197]: seq 1572 processed
Jun 27 07:37:49 530U3C systemd-udevd[145]: passed 213 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:49 530U3C systemd-udevd[28197]: seq 1573 running
Jun 27 07:37:49 530U3C systemd-udevd[145]: passed 217 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:49 530U3C systemd-udevd[28198]: seq 1574 running
Jun 27 07:37:49 530U3C systemd-udevd[28199]: seq 1575 running
Jun 27 07:37:49 530U3C kernel: sr 6:0:0:0: [sr0] scsi-1 drive
Jun 27 07:37:49 530U3C kernel: cdrom: Uniform CD-ROM driver Revision: 3.20
Jun 27 07:37:49 530U3C kernel: sr 6:0:0:0: Attached scsi CD-ROM sr0
Jun 27 07:37:49 530U3C systemd-udevd[28199]: handling device node '/dev/bsg/7:0:0:0', devnum=c252:3, mode=0600, uid=0, gid=0
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1575 forked new worker [28199]
Jun 27 07:37:49 530U3C systemd-udevd[28198]: passed device to netlink monitor 0x7fd00ab4eac0
Jun 27 07:37:49 530U3C systemd-udevd[28199]: preserve permissions /dev/bsg/7:0:0:0, 020600, uid=0, gid=0
Jun 27 07:37:49 530U3C systemd-udevd[28197]: passed device to netlink monitor 0x7fd00ab2c070
Jun 27 07:37:49 530U3C systemd-udevd[28198]: seq 1574 processed
Jun 27 07:37:49 530U3C systemd-udevd[28199]: creating symlink '/dev/char/252:3' to '../bsg/7:0:0:0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: seq 1573 processed
Jun 27 07:37:49 530U3C systemd-udevd[28199]: created empty file '/run/udev/data/c252:3' for '/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.1/host7/target7:0:0/7:0:0:0/bsg/7:0:0:0'
Jun 27 07:37:49 530U3C systemd-udevd[28199]: passed device to netlink monitor 0x7fd00ab4e8a0
Jun 27 07:37:49 530U3C systemd-udevd[28199]: seq 1575 processed
Jun 27 07:37:49 530U3C systemd-udevd[28195]: Inserted 'sr_mod'
Jun 27 07:37:49 530U3C systemd-udevd[28195]: passed device to netlink monitor 0x7fd00ab545b0
Jun 27 07:37:49 530U3C systemd-udevd[28195]: seq 1568 processed
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1577 forked new worker [28200]
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1579 queued, 'add' 'module'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: seq 1577 running
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1579 forked new worker [28201]
Jun 27 07:37:49 530U3C systemd-udevd[145]: passed 217 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:49 530U3C systemd-udevd[28197]: seq 1569 running
Jun 27 07:37:49 530U3C systemd-udevd[28198]: seq 1570 running
Jun 27 07:37:49 530U3C systemd-udevd[28201]: seq 1579 running
Jun 27 07:37:49 530U3C systemd-udevd[145]: passed 244 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:49 530U3C systemd-udevd[28201]: passed device to netlink monitor 0x7fd00ab4f400
Jun 27 07:37:49 530U3C systemd-udevd[28201]: seq 1579 processed
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1580 queued, 'add' 'module'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: passed device to netlink monitor 0x7fd00ab2c070
Jun 27 07:37:49 530U3C systemd-udevd[28197]: seq 1569 processed
Jun 27 07:37:49 530U3C systemd-udevd[28195]: seq 1580 running
Jun 27 07:37:49 530U3C systemd-udevd[145]: passed 131 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:49 530U3C systemd-udevd[28198]: handling device node '/dev/bsg/6:0:0:0', devnum=c252:2, mode=0600, uid=0, gid=0
Jun 27 07:37:49 530U3C systemd-udevd[28198]: preserve permissions /dev/bsg/6:0:0:0, 020600, uid=0, gid=0
Jun 27 07:37:49 530U3C systemd-udevd[28195]: passed device to netlink monitor 0x7fd00ab545b0
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1581 queued, 'add' 'bdi'
Jun 27 07:37:49 530U3C systemd-udevd[28198]: creating symlink '/dev/char/252:2' to '../bsg/6:0:0:0'
Jun 27 07:37:49 530U3C systemd-udevd[28195]: seq 1580 processed
Jun 27 07:37:49 530U3C systemd-udevd[145]: passed 139 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:49 530U3C systemd-udevd[28199]: seq 1581 running
Jun 27 07:37:49 530U3C systemd-udevd[28198]: created empty file '/run/udev/data/c252:2' for '/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.0/host6/target6:0:0/6:0:0:0/bsg/6:0:0:0'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: GROUP 6 /usr/lib/udev/rules.d/50-udev-default.rules:55
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1582 queued, 'add' 'block'
Jun 27 07:37:49 530U3C systemd-udevd[28199]: passed device to netlink monitor 0x7fd00ab4e8a0
Jun 27 07:37:49 530U3C systemd-udevd[28198]: passed device to netlink monitor 0x7fd00ab4eac0
Jun 27 07:37:49 530U3C systemd-udevd[145]: passed 248 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:49 530U3C systemd-udevd[28200]: IMPORT builtin 'usb_id' /usr/lib/udev/rules.d/60-persistent-storage.rules:31
Jun 27 07:37:49 530U3C systemd-udevd[28197]: seq 1582 running
Jun 27 07:37:49 530U3C systemd-udevd[28199]: seq 1581 processed
Jun 27 07:37:49 530U3C systemd-udevd[28198]: seq 1570 processed
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1583 queued, 'add' 'drivers'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: /sys/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.1: if_class 8 protocol 6
Jun 27 07:37:49 530U3C systemd-udevd[145]: passed 138 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:49 530U3C systemd-udevd[28195]: seq 1583 running
Jun 27 07:37:49 530U3C systemd-udevd[145]: seq 1584 queued, 'change' 'block'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: LINK 'disk/by-id/usb-HUAWEI_TF_CARD_Storage-0:0' /usr/lib/udev/rules.d/60-persistent-storage.rules:36
Jun 27 07:37:49 530U3C systemd-udevd[28195]: passed device to netlink monitor 0x7fd00ab545b0
Jun 27 07:37:49 530U3C systemd-udevd[28200]: IMPORT builtin 'path_id' /usr/lib/udev/rules.d/60-persistent-storage.rules:54
Jun 27 07:37:49 530U3C systemd-udevd[28195]: seq 1583 processed
Jun 27 07:37:49 530U3C systemd-udevd[28200]: LINK 'disk/by-path/pci-0000:00:14.0-usb-0:1:1.1-scsi-0:0:0:0' /usr/lib/udev/rules.d/60-persistent-storage.rules:55
Jun 27 07:37:49 530U3C systemd-udevd[28197]: GROUP 6 /usr/lib/udev/rules.d/50-udev-default.rules:55
Jun 27 07:37:49 530U3C systemd-udevd[28200]: IMPORT builtin 'blkid' /usr/lib/udev/rules.d/60-persistent-storage.rules:66
Jun 27 07:37:49 530U3C systemd-udevd[28197]: GROUP 93 /usr/lib/udev/rules.d/50-udev-default.rules:56
Jun 27 07:37:49 530U3C systemd-udevd[28197]: IMPORT 'cdrom_id --lock-media /dev/sr0' /usr/lib/udev/rules.d/60-cdrom_id.rules:16
Jun 27 07:37:49 530U3C systemd-udevd[28202]: starting 'cdrom_id --lock-media /dev/sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: Failure opening block device /dev/sdc: No medium found
Jun 27 07:37:49 530U3C systemd-udevd[28200]: IMPORT builtin 'blkid' returned non-zero
Jun 27 07:37:49 530U3C systemd-udevd[28200]: ATTR '/sys/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.1/host7/target7:0:0/7:0:0:0/block/sdc/queue/scheduler' writing 'cfq' /etc/udev/rules.d/60-schedulers.rules:5
Jun 27 07:37:49 530U3C systemd-udevd[28200]: handling device node '/dev/sdc', devnum=b8:32, mode=0660, uid=0, gid=6
Jun 27 07:37:49 530U3C systemd-udevd[28200]: set permissions /dev/sdc, 060660, uid=0, gid=6
Jun 27 07:37:49 530U3C systemd-udevd[28200]: creating symlink '/dev/block/8:32' to '../sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: creating link '/dev/disk/by-id/usb-HUAWEI_TF_CARD_Storage-0:0' to '/dev/sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: creating symlink '/dev/disk/by-id/usb-HUAWEI_TF_CARD_Storage-0:0' to '../../sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: creating link '/dev/disk/by-path/pci-0000:00:14.0-usb-0:1:1.1-scsi-0:0:0:0' to '/dev/sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: creating symlink '/dev/disk/by-path/pci-0000:00:14.0-usb-0:1:1.1-scsi-0:0:0:0' to '../../sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: created db file '/run/udev/data/b8:32' for '/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.1/host7/target7:0:0/7:0:0:0/block/sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: adding watch on '/dev/sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: created db file '/run/udev/data/b8:32' for '/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.1/host7/target7:0:0/7:0:0:0/block/sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: passed device to netlink monitor 0x7fd00ab559f0
Jun 27 07:37:49 530U3C systemd-udevd[28200]: seq 1577 processed
Jun 27 07:37:49 530U3C systemd-udevd[145]: passed 271 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:49 530U3C systemd-udevd[28200]: seq 1578 running
Jun 27 07:37:49 530U3C systemd-udevd[28200]: removing watch on '/dev/sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: IMPORT builtin 'usb_id' /usr/lib/udev/rules.d/60-persistent-storage.rules:31
Jun 27 07:37:49 530U3C systemd-udevd[28200]: /sys/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.1: if_class 8 protocol 6
Jun 27 07:37:49 530U3C systemd-udevd[28200]: LINK 'disk/by-id/usb-HUAWEI_TF_CARD_Storage-0:0' /usr/lib/udev/rules.d/60-persistent-storage.rules:36
Jun 27 07:37:49 530U3C systemd-udevd[28197]: 'cdrom_id --lock-media /dev/sr0'(out) 'ID_CDROM=1'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: IMPORT builtin 'path_id' /usr/lib/udev/rules.d/60-persistent-storage.rules:54
Jun 27 07:37:49 530U3C systemd-udevd[28200]: LINK 'disk/by-path/pci-0000:00:14.0-usb-0:1:1.1-scsi-0:0:0:0' /usr/lib/udev/rules.d/60-persistent-storage.rules:55
Jun 27 07:37:49 530U3C systemd-udevd[28200]: IMPORT builtin 'blkid' /usr/lib/udev/rules.d/60-persistent-storage.rules:66
Jun 27 07:37:49 530U3C systemd-udevd[28197]: Process 'cdrom_id --lock-media /dev/sr0' succeeded.
Jun 27 07:37:49 530U3C systemd-udevd[28197]: LINK 'cdrom' /usr/lib/udev/rules.d/60-cdrom_id.rules:23
Jun 27 07:37:49 530U3C systemd-udevd[28197]: IMPORT builtin 'usb_id' /usr/lib/udev/rules.d/60-persistent-storage.rules:31
Jun 27 07:37:49 530U3C systemd-udevd[28197]: /sys/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.0: if_class 8 protocol 6
Jun 27 07:37:49 530U3C systemd-udevd[28197]: LINK 'disk/by-id/usb-HUAWEI_Mass_Storage-0:0' /usr/lib/udev/rules.d/60-persistent-storage.rules:36
Jun 27 07:37:49 530U3C systemd-udevd[28197]: IMPORT builtin 'path_id' /usr/lib/udev/rules.d/60-persistent-storage.rules:54
Jun 27 07:37:49 530U3C systemd-udevd[28197]: LINK 'disk/by-path/pci-0000:00:14.0-usb-0:1:1.0-scsi-0:0:0:0' /usr/lib/udev/rules.d/60-persistent-storage.rules:55
Jun 27 07:37:49 530U3C systemd-udevd[28200]: Failure opening block device /dev/sdc: No medium found
Jun 27 07:37:49 530U3C systemd-udevd[28200]: IMPORT builtin 'blkid' returned non-zero
Jun 27 07:37:49 530U3C systemd-udevd[28197]: RUN 'uaccess' /usr/lib/udev/rules.d/73-seat-late.rules:15
Jun 27 07:37:49 530U3C systemd-udevd[28200]: ATTR '/sys/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.1/host7/target7:0:0/7:0:0:0/block/sdc/queue/scheduler' writing 'cfq' /etc/udev/rules.d/60-schedulers.rules:5
Jun 27 07:37:49 530U3C systemd-udevd[28197]: handling device node '/dev/sr0', devnum=b11:0, mode=0660, uid=0, gid=93
Jun 27 07:37:49 530U3C systemd-udevd[28197]: set permissions /dev/sr0, 060660, uid=0, gid=93
Jun 27 07:37:49 530U3C systemd-udevd[28197]: creating symlink '/dev/block/11:0' to '../sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: creating link '/dev/cdrom' to '/dev/sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: handling device node '/dev/sdc', devnum=b8:32, mode=0600, uid=0, gid=0
Jun 27 07:37:49 530U3C systemd-udevd[28197]: creating symlink '/dev/cdrom' to 'sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: preserve already existing symlink '/dev/block/8:32' to '../sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: creating link '/dev/disk/by-id/usb-HUAWEI_Mass_Storage-0:0' to '/dev/sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: found 'b8:32' claiming '/run/udev/links/\x2fdisk\x2fby-id\x2fusb-HUAWEI_TF_CARD_Storage-0:0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: creating symlink '/dev/disk/by-id/usb-HUAWEI_Mass_Storage-0:0' to '../../sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: creating link '/dev/disk/by-id/usb-HUAWEI_TF_CARD_Storage-0:0' to '/dev/sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: creating link '/dev/disk/by-path/pci-0000:00:14.0-usb-0:1:1.0-scsi-0:0:0:0' to '/dev/sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: preserve already existing symlink '/dev/disk/by-id/usb-HUAWEI_TF_CARD_Storage-0:0' to '../../sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: creating symlink '/dev/disk/by-path/pci-0000:00:14.0-usb-0:1:1.0-scsi-0:0:0:0' to '../../sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: found 'b8:32' claiming '/run/udev/links/\x2fdisk\x2fby-path\x2fpci-0000:00:14.0-usb-0:1:1.1-scsi-0:0:0:0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: created db file '/run/udev/data/b11:0' for '/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.0/host6/target6:0:0/6:0:0:0/block/sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: creating link '/dev/disk/by-path/pci-0000:00:14.0-usb-0:1:1.1-scsi-0:0:0:0' to '/dev/sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: preserve already existing symlink '/dev/disk/by-path/pci-0000:00:14.0-usb-0:1:1.1-scsi-0:0:0:0' to '../../sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: created db file '/run/udev/data/b8:32' for '/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.1/host7/target7:0:0/7:0:0:0/block/sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: adding watch on '/dev/sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28200]: created db file '/run/udev/data/b8:32' for '/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.1/host7/target7:0:0/7:0:0:0/block/sdc'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: passed device to netlink monitor 0x7fd00ab2c070
Jun 27 07:37:49 530U3C systemd-udevd[28197]: seq 1582 processed
Jun 27 07:37:49 530U3C systemd-udevd[145]: passed 271 byte device to netlink monitor 0x7fd00ab594e0
Jun 27 07:37:49 530U3C systemd-udevd[28197]: seq 1584 running
Jun 27 07:37:49 530U3C systemd-udevd[28200]: passed device to netlink monitor 0x7fd00ab559f0
Jun 27 07:37:49 530U3C systemd-udevd[28200]: seq 1578 processed
Jun 27 07:37:49 530U3C systemd-udevd[28197]: IMPORT 'cdrom_id --lock-media /dev/sr0' /usr/lib/udev/rules.d/60-cdrom_id.rules:16
Jun 27 07:37:49 530U3C systemd-udevd[28204]: starting 'cdrom_id --lock-media /dev/sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: 'cdrom_id --lock-media /dev/sr0'(out) 'ID_CDROM=1'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: Process 'cdrom_id --lock-media /dev/sr0' succeeded.
Jun 27 07:37:49 530U3C systemd-udevd[28197]: LINK 'cdrom' /usr/lib/udev/rules.d/60-cdrom_id.rules:23
Jun 27 07:37:49 530U3C systemd-udevd[28197]: IMPORT builtin 'usb_id' /usr/lib/udev/rules.d/60-persistent-storage.rules:31
Jun 27 07:37:49 530U3C systemd-udevd[28197]: /sys/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.0: if_class 8 protocol 6
Jun 27 07:37:49 530U3C systemd-udevd[28197]: LINK 'disk/by-id/usb-HUAWEI_Mass_Storage-0:0' /usr/lib/udev/rules.d/60-persistent-storage.rules:36
Jun 27 07:37:49 530U3C systemd-udevd[28197]: IMPORT builtin 'path_id' /usr/lib/udev/rules.d/60-persistent-storage.rules:54
Jun 27 07:37:49 530U3C systemd-udevd[28197]: LINK 'disk/by-path/pci-0000:00:14.0-usb-0:1:1.0-scsi-0:0:0:0' /usr/lib/udev/rules.d/60-persistent-storage.rules:55
Jun 27 07:37:49 530U3C systemd-udevd[28197]: RUN 'uaccess' /usr/lib/udev/rules.d/73-seat-late.rules:15
Jun 27 07:37:49 530U3C systemd-udevd[28197]: handling device node '/dev/sr0', devnum=b11:0, mode=0600, uid=0, gid=0
Jun 27 07:37:49 530U3C systemd-udevd[28197]: preserve already existing symlink '/dev/block/11:0' to '../sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: found 'b11:0' claiming '/run/udev/links/\x2fcdrom'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: creating link '/dev/cdrom' to '/dev/sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: preserve already existing symlink '/dev/cdrom' to 'sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: found 'b11:0' claiming '/run/udev/links/\x2fdisk\x2fby-id\x2fusb-HUAWEI_Mass_Storage-0:0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: creating link '/dev/disk/by-id/usb-HUAWEI_Mass_Storage-0:0' to '/dev/sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: preserve already existing symlink '/dev/disk/by-id/usb-HUAWEI_Mass_Storage-0:0' to '../../sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: found 'b11:0' claiming '/run/udev/links/\x2fdisk\x2fby-path\x2fpci-0000:00:14.0-usb-0:1:1.0-scsi-0:0:0:0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: creating link '/dev/disk/by-path/pci-0000:00:14.0-usb-0:1:1.0-scsi-0:0:0:0' to '/dev/sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: preserve already existing symlink '/dev/disk/by-path/pci-0000:00:14.0-usb-0:1:1.0-scsi-0:0:0:0' to '../../sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: created db file '/run/udev/data/b11:0' for '/devices/pci0000:00/0000:00:14.0/usb3/3-1/3-1:1.0/host6/target6:0:0/6:0:0:0/block/sr0'
Jun 27 07:37:49 530U3C systemd-udevd[28197]: passed device to netlink monitor 0x7fd00ab2c070
Jun 27 07:37:49 530U3C systemd-udevd[28197]: seq 1584 processed
Jun 27 07:37:49 530U3C systemd-udevd[145]: cleanup idle workers
Jun 27 07:37:49 530U3C systemd-udevd[28198]: Unload module index
Jun 27 07:37:49 530U3C systemd-udevd[28195]: Unload module index
Jun 27 07:37:49 530U3C systemd-udevd[28198]: Unloaded link configuration context.
Jun 27 07:37:49 530U3C systemd-udevd[145]: worker [28198] exited
Jun 27 07:37:49 530U3C systemd-udevd[28195]: Unloaded link configuration context.
Jun 27 07:37:49 530U3C systemd-udevd[28199]: Unload module index
Jun 27 07:37:49 530U3C systemd-udevd[28201]: Unload module index
Jun 27 07:37:49 530U3C systemd-udevd[28197]: Unload module index
Jun 27 07:37:49 530U3C systemd-udevd[145]: cleanup idle workers
Jun 27 07:37:49 530U3C systemd-udevd[28200]: Unload module index
Jun 27 07:37:49 530U3C systemd-udevd[28199]: Unloaded link configuration context.
Jun 27 07:37:49 530U3C systemd-udevd[28201]: Unloaded link configuration context.
Jun 27 07:37:49 530U3C systemd-udevd[28197]: Unloaded link configuration context.
Jun 27 07:37:49 530U3C systemd-udevd[145]: worker [28195] exited
Jun 27 07:37:49 530U3C systemd-udevd[28200]: Unloaded link configuration context.
Jun 27 07:37:49 530U3C systemd-udevd[145]: worker [28199] exited
Jun 27 07:37:49 530U3C systemd-udevd[145]: worker [28197] exited
Jun 27 07:37:49 530U3C systemd-udevd[145]: worker [28200] exited
Jun 27 07:37:49 530U3C systemd-udevd[145]: worker [28201] exited

As you can see, when the device is inserted udev correctly applies the rule 40-usb_modeswitch.rules, in particular it calls the line 24 of the rule (general command for the Huawei devices):

# Generic entry for all Huawei devices
ATTRS{idVendor}=="12d1", ATTR{bInterfaceNumber}=="00", ATTR{bInterfaceClass}=="08", RUN+="usb_modeswitch '%b/%k'"

However the rule itself does not invoke /usr/bin/usb_modeswitch but the wrapper script /usr/lib/udev/usb_modeswitch which in turn calls the script /usr/bin/usb_modeswitch_dispatcher. The arguments %b and %k should be replaced respectively with the bus id and the kernel name for the device but from the log it seems that one of the two is missing:

Jun 27 07:37:48 530U3C systemd-udevd[28177]: starting 'usb_modeswitch '/3-1:1.0''

I mean if the slash in '/3-1:1.0' is the slash in '%b/%k' haven't %k been replaced with the bus id instead of the kernel name?

@HoaS: At the moment I cannot downgrade.

@Radnek: if you can downgrade, can you also provide the output of the journal with udev log level set to debug?


About me - github

-- When you have eliminated the impossible, whatever remains, however improbable, must be the truth -- Spock | Sherlock Holmes

Offline

#4 2015-06-28 09:12:39

mar04
Member
From: Poland
Registered: 2010-02-08
Posts: 117

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically

Same here. I have Huawei E173. Manual switching works.

Offline

#5 2015-06-28 10:29:48

mar04
Member
From: Poland
Registered: 2010-02-08
Posts: 117

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically

mauritiusdadd wrote:

from the log it seems that one of the two is missing:

Jun 27 07:37:48 530U3C systemd-udevd[28177]: starting 'usb_modeswitch '/3-1:1.0''

I mean if the slash in '/3-1:1.0' is the slash in '%b/%k' haven't %k been replaced with the bus id instead of the kernel name?

I can't downgrade but I checked on my Fedora 21 installation (it has systemd version 216 I think and a bit older version of usb_modeswitch) and compared it with up-to-date Arch.

Arch:

starting 'usb_modeswitch '/2-1.2:1.0''

Fedora:

starting 'usb_modeswitch '/2-1.2''

Now if I run

sudo /usr/lib/udev/usb_modeswitch '/2-1.2'

manually on Arch, it works.
So it seems it's executed on wrong device? I don't know what that ':1.0' suffix means.

Offline

#6 2015-06-28 18:15:26

mauritiusdadd
Member
From: Benevento, Italy
Registered: 2013-10-27
Posts: 776

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically

@mar04: thanks for the info.

I've played a bit with the wrapper script /usr/lib/udev/usb_modeswitch, for the record this is its content:

#!/bin/sh
# part of usb_modeswitch 2.2.1
device_in()
{
	if [ ! -e /var/lib/usb_modeswitch/$1 ]; then
		return 0
	fi
	while read line
	do
		if [ $(expr "$line" : "$2:$3") != 0 ]; then
			return 1
		fi
	done </var/lib/usb_modeswitch/$1
	if [ $(expr "$line" : "$2:$3") != 0 ]; then
		return 1
	fi
	return 0
}

if [ $(expr "$1" : "--.*") ]; then
	p_id=$4
	if [ -z $p_id ]; then
		prod=$5
		if [ -z $prod ]; then
			prod=$3
		fi
		prod=${prod%/*}
		v_id=0x${prod%/*}
		p_id=0x${prod#*/}
		if [ "$v_id" = "0x" ]; then
			v_id="0"
			p_id="0"
		fi
		v_id="$(printf %04x $(($v_id)))"
		p_id="$(printf %04x $(($p_id)))"
	else
		v_id=$3
	fi
fi
PATH=/sbin:/usr/sbin:$PATH
case "$1" in
	--driver-bind)
		(
		dir=$(ls -d /sys$2/ttyUSB* 2>/dev/null)
		sleep 1
		if [ ! -z "$dir" ]; then
			exit 0
		fi
		set +e
		device_in "bind_list" $v_id $p_id
		if [ "$?" = "1" ]; then
			id_attr="/sys/bus/usb-serial/drivers/option1/new_id"
			if [ ! -e "$id_attr" ]; then
				modprobe option 2>/dev/null || true
			fi
			if [ -e "$id_attr" ]; then
				echo "$v_id $p_id ff" > $id_attr
			else
				modprobe -r usbserial 2>/dev/null
				modprobe usbserial "vendor=0x$v_id" "product=0x$p_id" 2>/dev/null
			fi
		fi
		) &
		exit 0
		;;
	--symlink-name)
		device_in "link_list" $v_id $p_id
		if [ "$?" = "1" ]; then
			if [ -e "/usr/bin/usb_modeswitch_dispatcher" ]; then
				exec usb_modeswitch_dispatcher $1 $2 2>>/dev/null
			fi
		fi
		exit 0
		;;
esac
exec 1<&- 2<&- 5<&- 7<&-
(
count=20
while [ $count != 0 ]; do
	if [ ! -e "/usr/bin/usb_modeswitch_dispatcher" ]; then
		sleep 1
		count=$(($count - 1))
	else
		if [ -e "/etc/init/usb-modeswitch-upstart.conf" ]; then
			exec /sbin/initctl emit --no-wait usb-modeswitch-upstart UMS_PARAM=$1
		elif [ -e "/etc/systemd/system/usb_modeswitch@.service" ]; then
			exec /usr/bin/systemctl --no-block start usb_modeswitch@$1.service
		else
			exec /usr/bin/usb_modeswitch_dispatcher --switch-mode $1 &
		fi
		exit 0
	fi
done
) &
exit 0

What causes trouble is the exec command on linke 76:

exec 1<&- 2<&- 5<&- 7<&-

which basically (if my understanding of the exec command is correct)  is needed to close the stdin, stdout and the file descriptors 5 and 7 (that however are not open here) and prevents the journal to be flooded by usb_modeswitch messages. However, It seems that usb_modeswitch_dispatcher needs at least the stdin or stdout opend in order to work and if I comment out that line all works as expected. Can anyone confirm this behaviour? However I still don't understand why that worked on systemd 219


About me - github

-- When you have eliminated the impossible, whatever remains, however improbable, must be the truth -- Spock | Sherlock Holmes

Offline

#7 2015-06-29 06:35:52

mauritiusdadd
Member
From: Benevento, Italy
Registered: 2013-10-27
Posts: 776

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically

I installed and tested also the newly relased version of usb_modeswitch (v2.2.2) and the result is the same. As this seems to be a bug in usb_modeswitch I've filed a report on their forum: http://www.draisberghof.de/usb_modeswit … f=2&t=2329

---edit:

The author of usb_modeswitch just released a new version which fix this issue. The Archlinux package has already been marked as out-of-date so we have just to wait for the new package.

Last edited by mauritiusdadd (2015-06-30 06:21:24)


About me - github

-- When you have eliminated the impossible, whatever remains, however improbable, must be the truth -- Spock | Sherlock Holmes

Offline

#8 2015-06-30 10:50:05

mauritiusdadd
Member
From: Benevento, Italy
Registered: 2013-10-27
Posts: 776

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically

The new version of usb_modeswitch is no avalilable in [community]. To everyone who had problems, please install it and see if it solves the issue.


About me - github

-- When you have eliminated the impossible, whatever remains, however improbable, must be the truth -- Spock | Sherlock Holmes

Offline

#9 2015-06-30 10:57:51

mar04
Member
From: Poland
Registered: 2010-02-08
Posts: 117

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically

It works, thanks mauritiusdadd!

Offline

#10 2015-06-30 11:00:15

mauritiusdadd
Member
From: Benevento, Italy
Registered: 2013-10-27
Posts: 776

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically

@mar04: You're welcome smile

@radnek: If it works for you too, please remember to mark the thread as [SOLVED]


About me - github

-- When you have eliminated the impossible, whatever remains, however improbable, must be the truth -- Spock | Sherlock Holmes

Offline

#11 2015-07-01 20:52:25

radnek
Member
From: Poland
Registered: 2015-06-25
Posts: 3

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically

Works for me, too. Thanks.

Offline

#12 2015-07-16 18:56:11

mkkot
Member
From: Poland
Registered: 2009-12-20
Posts: 287

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically

Does it work for everyone? I have the same problem with version 2.2.3, and with 2.2.4 modem device isn't detected at all.

http://www.draisberghof.de/usb_modeswit … 48&p=15342

Last edited by mkkot (2015-07-16 18:56:23)

Offline

#13 2015-07-17 07:30:19

mauritiusdadd
Member
From: Benevento, Italy
Registered: 2013-10-27
Posts: 776

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically

mkkot, yours seems a slightly different issue: looking at the log you posted on the usb_modeswitch forum, your modem is recognized and switched successfully and your issue appears only at boot. I cannot test it right now, but a new version of usb_modeswitch is out (2.2.5) so try to see if that works for you.


About me - github

-- When you have eliminated the impossible, whatever remains, however improbable, must be the truth -- Spock | Sherlock Holmes

Offline

#14 2015-07-17 16:38:29

mkkot
Member
From: Poland
Registered: 2009-12-20
Posts: 287

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically

Jupp, the new version solves the problem smile

Offline

#15 2017-01-17 12:27:15

MiguelAngelLV
Member
Registered: 2013-01-19
Posts: 5

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically

Hi,  I have the same problem (now, two years after). When connect the modem I don't thing anything by executing the commands manualy works fine. I execute this

sudo usb_modeswitch -v 05c6 -p 1000 -M 5553424312345678000000000000061b000000020000000000000000000000
sudo modprobe usbserial vendor=0x05c6 product=0x1000

Offline

#16 2017-01-17 12:57:12

WorMzy
Forum Moderator
From: Scotland
Registered: 2010-06-16
Posts: 11,787
Website

Re: [SOLVED] After sys upgrade usb_modeswitch not run automatically


Sakura:-
Mobo: MSI MAG X570S TORPEDO MAX // Processor: AMD Ryzen 9 5950X @4.9GHz // GFX: AMD Radeon RX 5700 XT // RAM: 32GB (4x 8GB) Corsair DDR4 (@ 3000MHz) // Storage: 1x 3TB HDD, 6x 1TB SSD, 2x 120GB SSD, 1x 275GB M2 SSD

Making lemonade from lemons since 2015.

Online

Board footer

Powered by FluxBB