0

Good morning!

I have a Gprs USB module which used to work just fine. Now on boot, the device is not detected. However, when the OS is booted, if I unplug and re-plug in the device, it is detected and works just fine.

What do you guys think? Why do you think the module is not detected anymore on boot time as it used to be?

What can I check?

Thank you very much!

0

The first thing you should check is dmesg.

Right after you plug your GPS in a booted system, type dmesg on the console and take note of the last 10 lines or so. They should mention the steps your system took when the device was plugged, like loading the driver.

Then, reboot with the GPS plugged in, and check dmesg again. Try to find the lines you have seen the first time. There will most likely be messages in the output describing what went wrong.

Of course, the usual advice for situations where something used to work but doesn't work anymore is to roll back the latest changes you have done to your system. If you make backups, all it takes is to restore one.

0

Here I attach dmesg output after booting raspberry with my USB GPRs plugged and not detected.

pi@raspberrypi:~ $ dmesg
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.4.38-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611) ) #938 SMP Thu Dec 15 15:22:21 GMT 2016
[    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine model: Raspberry Pi 3 Model B Rev 1.2
[    0.000000] cma: Reserved 8 MiB at 0x3a800000
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] On node 0 totalpages: 241664
[    0.000000] free_area_init_node: node 0, pgdat 808c4f40, node_mem_map b9fa6000
[    0.000000]   Normal zone: 2124 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 241664 pages, LIFO batch:31
[    0.000000] [bcm2709_smp_init_cpus] enter (9520->f3003010)
[    0.000000] [bcm2709_smp_init_cpus] ncores=4
[    0.000000] PERCPU: Embedded 13 pages/cpu @b9f62000 s22592 r8192 d22464 u53248
[    0.000000] pcpu-alloc: s22592 r8192 d22464 u53248 alloc=13*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 239540
[    0.000000] Kernel command line: 8250.nr_uarts=0 dma.dmachans=0x7f35 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2709.boardrev=0xa02082 bcm2709.serial=0x633b4be4 smsc95xx.macaddr=B8:27:EB:3B:4B:E4 bcm2708_fb.fbswap=1 bcm2709.uart_clock=48000000 vc_mem.mem_base=0x3dc00000 vc_mem.mem_size=0x3f000000  dwc_otg.lpm_enable=0 console=tty9 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait logo.nologo quiet loglevel=0
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 939064K/966656K available (6357K kernel code, 432K rwdata, 1716K rodata, 476K init, 764K bss, 19400K reserved, 8192K cma-reserved)
[    0.000000] Virtual kernel memory layout:
    vector  : 0xffff0000 - 0xffff1000   (   4 kB)
    fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
    vmalloc : 0xbb800000 - 0xff800000   (1088 MB)
    lowmem  : 0x80000000 - 0xbb000000   ( 944 MB)
    modules : 0x7f000000 - 0x80000000   (  16 MB)
      .text : 0x80008000 - 0x807ea688   (8074 kB)
      .init : 0x807eb000 - 0x80862000   ( 476 kB)
      .data : 0x80862000 - 0x808ce250   ( 433 kB)
       .bss : 0x808d1000 - 0x809901ec   ( 765 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 32.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] Architected cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000009] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000027] Switching to timer-based delay loop, resolution 52ns
[    0.000293] Console: colour dummy device 80x30
[    0.000493] console [tty9] enabled
[    0.000517] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.000537] pid_max: default: 32768 minimum: 301
[    0.000849] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000865] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001783] Disabling cpuset control group subsystem
[    0.001815] Initializing cgroup subsys io
[    0.001845] Initializing cgroup subsys memory
[    0.001888] Initializing cgroup subsys devices
[    0.001908] Initializing cgroup subsys freezer
[    0.001928] Initializing cgroup subsys net_cls
[    0.001976] CPU: Testing write buffer coherency: ok
[    0.002039] ftrace: allocating 21237 entries in 63 pages
[    0.050212] CPU0: update cpu_capacity 1024
[    0.050246] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.050257] [bcm2709_smp_prepare_cpus] enter
[    0.050404] Setting up static identity map for 0x8240 - 0x8274
[    0.052058] [bcm2709_boot_secondary] cpu:1 started (0) 18
[    0.052244] [bcm2709_secondary_init] enter cpu:1
[    0.052287] CPU1: update cpu_capacity 1024
[    0.052293] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.052675] [bcm2709_boot_secondary] cpu:2 started (0) 16
[    0.052839] [bcm2709_secondary_init] enter cpu:2
[    0.052860] CPU2: update cpu_capacity 1024
[    0.052866] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.053230] [bcm2709_boot_secondary] cpu:3 started (0) 17
[    0.053361] [bcm2709_secondary_init] enter cpu:3
[    0.053381] CPU3: update cpu_capacity 1024
[    0.053387] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.053448] Brought up 4 CPUs
[    0.053471] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.053480] CPU: All CPU(s) started in HYP mode.
[    0.053487] CPU: Virtualization extensions available.
[    0.054106] devtmpfs: initialized
[    0.065076] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.065408] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.066109] pinctrl core: initialized pinctrl subsystem
[    0.066634] NET: Registered protocol family 16
[    0.071829] DMA: preallocated 4096 KiB pool for atomic coherent allocations
[    0.078809] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.078821] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.078973] Serial: AMBA PL011 UART driver
[    0.079106] uart-pl011 3f201000.uart: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
[    0.079265] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.142376] bcm2835-dma 3f007000.dma: DMA legacy API manager at f3007000, dmachans=0x1
[    0.142942] SCSI subsystem initialized
[    0.143132] usbcore: registered new interface driver usbfs
[    0.143229] usbcore: registered new interface driver hub
[    0.143328] usbcore: registered new device driver usb
[    0.149766] raspberrypi-firmware soc:firmware: Attached to firmware from 2016-12-30 15:16
[    0.176922] clocksource: Switched to clocksource arch_sys_counter
[    0.221909] FS-Cache: Loaded
[    0.222178] CacheFiles: Loaded
[    0.234498] NET: Registered protocol family 2
[    0.235353] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.235467] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.235657] TCP: Hash tables configured (established 8192 bind 8192)
[    0.235751] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.235798] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.236026] NET: Registered protocol family 1
[    0.236344] RPC: Registered named UNIX socket transport module.
[    0.236355] RPC: Registered udp transport module.
[    0.236363] RPC: Registered tcp transport module.
[    0.236372] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.237482] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    0.238801] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.252023] VFS: Disk quotas dquot_6.6.0
[    0.252333] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.254558] FS-Cache: Netfs 'nfs' registered for caching
[    0.255463] NFS: Registering the id_resolver key type
[    0.255514] Key type id_resolver registered
[    0.255524] Key type id_legacy registered
[    0.257864] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    0.257982] io scheduler noop registered
[    0.257999] io scheduler deadline registered (default)
[    0.258050] io scheduler cfq registered
[    0.260575] BCM2708FB: allocated DMA memory fac10000
[    0.260601] BCM2708FB: allocated DMA channel 0 @ f3007000
[    0.272289] Console: switching to colour frame buffer device 100x30
[    0.280304] bcm2835-rng 3f104000.rng: hwrng registered
[    0.280398] vc-cma: Videocore CMA driver
[    0.280408] vc-cma: vc_cma_base      = 0x00000000
[    0.280418] vc-cma: vc_cma_size      = 0x00000000 (0 MiB)
[    0.280426] vc-cma: vc_cma_initial   = 0x00000000 (0 MiB)
[    0.280631] vc-mem: phys_addr:0x00000000 mem_base=0x3dc00000 mem_size:0x3f000000(1008 MiB)
[    0.295521] brd: module loaded
[    0.304053] loop: module loaded
[    0.304934] vchiq: vchiq_init_state: slot_zero = 0xbac80000, is_master = 0
[    0.306335] Loading iSCSI transport class v2.0-870.
[    0.306995] usbcore: registered new interface driver smsc95xx
[    0.307048] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    0.507369] Core Release: 2.80a
[    0.507382] Setting default values for core params
[    0.507415] Finished setting default values for core params
[    0.707785] Using Buffer DMA mode
[    0.707796] Periodic Transfer Interrupt Enhancement - disabled
[    0.707804] Multiprocessor Interrupt Enhancement - disabled
[    0.707813] OTG VER PARAM: 0, OTG VER FLAG: 0
[    0.707831] Dedicated Tx FIFOs mode
[    0.708135] WARN::dwc_otg_hcd_init:1047: FIQ DMA bounce buffers: virt = 0xbac04000 dma = 0xfac04000 len=9024
[    0.708166] FIQ FSM acceleration enabled for :
Non-periodic Split Transactions
Periodic Split Transactions
High-Speed Isochronous Endpoints
Interrupt/Control Split Transaction hack enabled
[    0.708188] dwc_otg: Microframe scheduler enabled
[    0.708235] WARN::hcd_init_fiq:413: FIQ on core 1 at 0x8044844c
[    0.708250] WARN::hcd_init_fiq:414: FIQ ASM at 0x804487bc length 36
[    0.708266] WARN::hcd_init_fiq:439: MPHI regs_base at 0xbb87e000
[    0.708329] dwc_otg 3f980000.usb: DWC OTG Controller
[    0.708366] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    0.708401] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
[    0.708451] Init: Port Power? op_state=1
[    0.708460] Init: Power Port (0)
[    0.708659] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    0.708673] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.708686] usb usb1: Product: DWC OTG Controller
[    0.708698] usb usb1: Manufacturer: Linux 4.4.38-v7+ dwc_otg_hcd
[    0.708710] usb usb1: SerialNumber: 3f980000.usb
[    0.709522] hub 1-0:1.0: USB hub found
[    0.709564] hub 1-0:1.0: 1 port detected
[    0.710267] dwc_otg: FIQ enabled
[    0.710276] dwc_otg: NAK holdoff enabled
[    0.710285] dwc_otg: FIQ split-transaction FSM enabled
[    0.710318] Module dwc_common_port init
[    0.710548] usbcore: registered new interface driver usb-storage
[    0.710815] mousedev: PS/2 mouse device common for all mice
[    0.711545] bcm2835-cpufreq: min=600000 max=1200000
[    0.711843] sdhci: Secure Digital Host Controller Interface driver
[    0.711852] sdhci: Copyright(c) Pierre Ossman
[    0.712168] sdhost: log_buf @ bac07000 (fac07000)
[    0.766955] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    0.769228] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
[    0.769242] mmc-bcm2835 3f300000.mmc: DMA channel allocated
[    0.823995] mmc0: host does not support reading read-only switch, assuming write-enable
[    0.825954] mmc0: new high speed SDHC card at address 59b4
[    0.826522] mmcblk0: mmc0:59b4 USD   15.0 GiB
[    0.827048] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.827505] ledtrig-cpu: registered to indicate activity on CPUs
[    0.827628] hidraw: raw HID events driver (C) Jiri Kosina
[    0.827788]  mmcblk0: p1 p2
[    0.827831] usbcore: registered new interface driver usbhid
[    0.827840] usbhid: USB HID core driver
[    0.828387] Initializing XFRM netlink socket
[    0.828421] NET: Registered protocol family 17
[    0.828559] Key type dns_resolver registered
[    0.829098] Registering SWP/SWPB emulation handler
[    0.829880] registered taskstats version 1
[    0.830057] vc-sm: Videocore shared memory driver
[    0.830073] [vc_sm_connected_init]: start
[    0.830596] [vc_sm_connected_init]: end - returning 0
[    0.832928] 3f201000.uart: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
[    0.833318] of_cfs_init
[    0.833396] of_cfs_init: OK
[    0.844923] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    0.846516] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    0.848070] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    0.848865] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    0.848909] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    0.850047] devtmpfs: mounted
[    0.850788] Freeing unused kernel memory: 476K (807eb000 - 80862000)
[    0.850858] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    0.907059] Indeed it is in host mode hprt0 = 00021501
[    0.941161] mmc1: new high speed SDIO card at address 0001
[    1.086990] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    1.087161] Indeed it is in host mode hprt0 = 00001101
[    1.185573] random: systemd: uninitialized urandom read (16 bytes read, 29 bits of entropy available)
[    1.189403] systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR)
[    1.189743] systemd[1]: Detected architecture 'arm'.
[    1.287245] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
[    1.287265] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    1.288042] hub 1-1:1.0: USB hub found
[    1.288145] hub 1-1:1.0: 5 ports detected
[    1.326171] NET: Registered protocol family 10
[    1.327634] systemd[1]: Inserted module 'ipv6'
[    1.329531] systemd[1]: Set hostname to <raspberrypi>.
[    1.448915] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 64 bits of entropy available)
[    1.566978] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    1.600035] random: systemd: uninitialized urandom read (16 bytes read, 71 bits of entropy available)
[    1.601342] random: systemd: uninitialized urandom read (16 bytes read, 71 bits of entropy available)
[    1.602746] random: systemd: uninitialized urandom read (16 bytes read, 71 bits of entropy available)
[    1.618649] random: systemd: uninitialized urandom read (16 bytes read, 71 bits of entropy available)
[    1.619174] random: systemd: uninitialized urandom read (16 bytes read, 71 bits of entropy available)
[    1.619333] random: systemd: uninitialized urandom read (16 bytes read, 71 bits of entropy available)
[    1.662083] random: systemd: uninitialized urandom read (16 bytes read, 72 bits of entropy available)
[    1.667319] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
[    1.667340] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    1.670243] smsc95xx v1.0.4
[    1.679031] random: systemd: uninitialized urandom read (16 bytes read, 73 bits of entropy available)
[    1.730663] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:3b:4b:e4
[    1.802032] systemd[1]: Starting Forward Password Requests to Wall Directory Watch.
[    1.802384] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    1.802466] systemd[1]: Starting Remote File Systems (Pre).
[    1.802617] systemd[1]: Reached target Remote File Systems (Pre).
[    1.802780] systemd[1]: Starting Arbitrary Executable File Formats File System Automount Point.
[    1.803211] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    1.803291] systemd[1]: Starting Encrypted Volumes.
[    1.803359] systemd[1]: Reached target Encrypted Volumes.
[    1.803426] systemd[1]: Starting Swap.
[    1.803493] systemd[1]: Reached target Swap.
[    1.803564] systemd[1]: Expecting device dev-mmcblk0p1.device...
[    1.803634] systemd[1]: Starting Root Slice.
[    1.803777] systemd[1]: Created slice Root Slice.
[    1.803848] systemd[1]: Starting Delayed Shutdown Socket.
[    1.804008] systemd[1]: Listening on Delayed Shutdown Socket.
[    1.804079] systemd[1]: Starting /dev/initctl Compatibility Named Pipe.
[    1.804257] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[    1.804330] systemd[1]: Starting Journal Socket (/dev/log).
[    1.804516] systemd[1]: Listening on Journal Socket (/dev/log).
[    1.804591] systemd[1]: Starting User and Session Slice.
[    1.804830] systemd[1]: Created slice User and Session Slice.
[    1.804919] systemd[1]: Starting udev Control Socket.
[    1.805070] systemd[1]: Listening on udev Control Socket.
[    1.805155] systemd[1]: Starting udev Kernel Socket.
[    1.805275] systemd[1]: Listening on udev Kernel Socket.
[    1.805367] systemd[1]: Starting Journal Socket.
[    1.805554] systemd[1]: Listening on Journal Socket.
[    1.805686] systemd[1]: Starting System Slice.
[    1.805888] systemd[1]: Created slice System Slice.
[    1.805968] systemd[1]: Starting system-systemd\x2dfsck.slice.
[    1.806266] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    1.806419] systemd[1]: Starting Increase datagram queue length...
[    1.807028] usb 1-1.4: new high-speed USB device number 4 using dwc_otg
[    1.847651] systemd[1]: Starting Restore / save the current clock...
[    1.852447] systemd[1]: Mounted Huge Pages File System.
[    1.853248] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[    1.873500] systemd[1]: Starting Load Kernel Modules...
[    1.922055] systemd[1]: Started Set Up Additional Binary Formats.
[    1.922458] usb 1-1.4: New USB device found, idVendor=1546, idProduct=1104
[    1.922474] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    1.922488] usb 1-1.4: Product: u-blox Wireless Module
[    1.922498] systemd[1]: Mounting Debug File System...
[    1.922502] usb 1-1.4: Manufacturer: u-blox
[    1.922511] usb 1-1.4: SerialNumber: 352253063231798
[    1.925295] systemd[1]: Starting udev Coldplug all Devices...
[    1.928638] systemd[1]: Mounting POSIX Message Queue File System...
[    1.931971] systemd[1]: Starting system-autologin.slice.
[    1.932671] systemd[1]: Created slice system-autologin.slice.
[    1.932915] systemd[1]: Starting File System Check on Root Device...
[    1.936055] systemd[1]: Starting LSB: Show custom splashscreen...
[    1.938870] systemd[1]: Starting Slices.
[    1.939040] systemd[1]: Reached target Slices.
[    1.942682] systemd[1]: Started Create list of required static device nodes for the current kernel.
[    1.973051] systemd[1]: Started Increase datagram queue length.
[    1.982786] fuse init (API version 7.23)
[    2.008903] systemd[1]: Starting Syslog Socket.
[    2.009139] systemd[1]: Listening on Syslog Socket.
[    2.009244] systemd[1]: Starting Journal Service...
[    2.058416] systemd[1]: Started Journal Service.
[    2.064680] i2c /dev entries driver
[    2.402720] systemd-udevd[142]: starting version 215
[    3.113099] cdc_acm 1-1.4:1.0: ttyACM0: USB ACM device
[    3.114183] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    3.137052] cdc_acm 1-1.4:1.2: ttyACM1: USB ACM device
[    3.144507] cdc_acm 1-1.4:1.4: ttyACM2: USB ACM device
[    3.149973] cdc_acm 1-1.4:1.6: ttyACM3: USB ACM device
[    3.151183] usbcore: registered new interface driver cdc_acm
[    3.151202] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    3.161804] cdc_ether 1-1.4:1.8 usb0: register 'cdc_ether' at usb-3f980000.usb-1.4, CDC Ethernet Device, 5e:50:8e:e1:db:03
[    3.162024] usbcore: registered new interface driver cdc_ether
[    3.198475] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
[    3.205761] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    3.218772] bcm2708_i2c 3f804000.i2c: BSC1 Controller at 0x3f804000 (irq 83) (baudrate 100000)
[    3.455468] usbcore: registered new interface driver brcmfmac
[    3.579861] random: nonblocking pool is initialized
[    3.590138] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: May 27 2016 00:13:38 version 7.45.41.26 (r640327) FWID 01-df77e4a7
[    3.615583] brcmfmac: brcmf_cfg80211_reg_notifier: not a ISO3166 code
[    3.624807] brcmfmac: brcmf_cfg80211_reg_notifier: not a ISO3166 code
[    3.624839] cfg80211: World regulatory domain updated:
[    3.624854] cfg80211:  DFS Master region: unset
[    3.624872] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[    3.624896] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[    3.624927] cfg80211:   (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[    3.624941] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
[    3.624955] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[    3.624969] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[    3.624982] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
[    3.624994] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
[    3.625007] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
[    3.848542] systemd-journald[119]: Received request to flush runtime journal from PID 1
[    3.974331] rtc-ds1307: probe of 1-006f failed with error -5
[    3.974355] i2c i2c-1: new_device: Instantiated device mcp7941x at 0x6f
[    4.739499] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
[    4.740013] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    5.003026] brcmfmac: brcmf_add_if: ERROR: netdev:wlan0 already exists
[    5.003053] brcmfmac: brcmf_add_if: ignore IF event
[    5.008546] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[    5.008579] brcmfmac: power management disabled
[    5.171083] uart-pl011 3f201000.uart: no DMA platform data
[    5.639746] Adding 102396k swap on /var/swap.  Priority:-1 extents:1 across:102396k SSFS
[    5.764919] cfg80211: Regulatory domain changed to country: GB
[    5.764936] cfg80211:  DFS Master region: ETSI
[    5.764941] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[    5.764949] cfg80211:   (2402000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[    5.764958] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[    5.764965] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[    5.764972] cfg80211:   (5490000 KHz - 5710000 KHz @ 160000 KHz), (N/A, 2700 mBm), (0 s)
[    5.764978] cfg80211:   (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A)
[    6.371384] Bluetooth: Core ver 2.21
[    6.371447] NET: Registered protocol family 31
[    6.371454] Bluetooth: HCI device and connection manager initialized
[    6.371469] Bluetooth: HCI socket layer initialized
[    6.371479] Bluetooth: L2CAP socket layer initialized
[    6.371503] Bluetooth: SCO socket layer initialized
[    6.374096] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[    6.374665] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0x43E1
[    6.604997] IPv6: ADDRCONF(NETDEV_UP): usb0: link is not ready
[    6.605155] cdc_ether 1-1.4:1.8 usb0: kevent 12 may have been dropped
[    9.980917] Bluetooth: HCI UART driver ver 2.3
[    9.980933] Bluetooth: HCI UART protocol H4 registered
[    9.980938] Bluetooth: HCI UART protocol Three-wire (H5) registered
[    9.981053] Bluetooth: HCI UART protocol BCM registered
[   10.136118] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   10.136134] Bluetooth: BNEP filters: protocol multicast
[   10.136155] Bluetooth: BNEP socket layer initialized
[   10.169283] Bluetooth: RFCOMM TTY layer initialized
[   10.169315] Bluetooth: RFCOMM socket layer initialized
[   10.169330] Bluetooth: RFCOMM ver 1.11

and here dmesg after unplug and plug again the device, with ttyAMC0..4 recognized.

[  195.818866] usb 1-1.4: USB disconnect, device number 4
[  195.835532] cdc_ether 1-1.4:1.8 usb0: unregister 'cdc_ether' usb-3f980000.usb-1.4, CDC Ethernet Device
[  199.896957] usb 1-1.5: new high-speed USB device number 5 using dwc_otg
[  200.007049] usb 1-1.5: New USB device found, idVendor=1546, idProduct=1104
[  200.007062] usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[  200.007070] usb 1-1.5: Product: u-blox Wireless Module
[  200.007076] usb 1-1.5: Manufacturer: u-blox
[  200.007082] usb 1-1.5: SerialNumber: 352253063231798
[  200.025265] cdc_acm 1-1.5:1.0: ttyACM0: USB ACM device
[  200.028350] cdc_acm 1-1.5:1.2: ttyACM1: USB ACM device
[  200.030647] cdc_acm 1-1.5:1.4: ttyACM2: USB ACM device
[  200.033272] cdc_acm 1-1.5:1.6: ttyACM3: USB ACM device
[  200.039459] cdc_ether 1-1.5:1.8 usb0: register 'cdc_ether' at usb-3f980000.usb-1.5, CDC Ethernet Device, 5e:50:8e:e1:db:03

It used to work at boot time. Do you see something strange? thanks!

  • I see no difference. Both logs show your device is being detected (usb 1-1.4 at boot vs. usb 1-1.5 after re-plugging), with ttyACMx devices flies and cdc_ether interface being created in both cases. – Dmitry Grigoryev Feb 6 at 14:40
  • Incidentally, these logs should have been included in the original question, rather than posted as an answer. Both actions bump the question back to the active list. – Dmitry Grigoryev Feb 6 at 14:42

Your Answer

By clicking “Post Your Answer”, you agree to our terms of service, privacy policy and cookie policy

Not the answer you're looking for? Browse other questions tagged or ask your own question.