udev rule unable to rename network interface name

gh_tintinp · December 19, 2019, 1:39am

Dear Balena dev,

I’m using Resin OS 2.9.7+rev4 (I have to use this particular version) on SKX2. I would like to rename wifi dongle network interface from wlan1 to my custom name. The udev rule worked fine if I insert the wifi dongle before I turn the TX2 on. However, if I didn’t insert wifi dongle before I turn TX2 on, then I insert it after, the renaming will fail. I dig deeper and it seems to be problem that udev cannot change the name because networkd has a hold of the network interface.

I tested with udevadm test /sys/class/net/wlx000f0201fd9b and found this error below

NAME 'ralinkwifi' /lib/udev/rules.d/81-ralinkwifi.rules:1
RUN '/lib/systemd/systemd-sysctl --prefix=/net/ipv4/conf/$name --prefix=/net/ipv4/neigh/$name --prefix=/net/ipv6/conf/$name --prefix=/net/ipv6/neigh/$name' /lib/udev/rules.d/99-systemd.rules:51
Error changing net interface name 'wlx000f0201fd9b' to 'ralinkwifi': Device or resource busy
could not rename interface '15' from 'wlx000f0201fd9b' to 'ralinkwifi': Device or resource busy

I also test udev rule on jetson nano and it worked fine if I insert the wifi dongle after boot.

Thank you in advance,
Tin-tin

anujdeshpande · December 19, 2019, 10:18am

Hi
Can you try taking the network interface down and then making the changes, before bringing it back again?
One other thing that you could potentially do is change up the sequence in which these are called by changing the number at the start of the filename (lower gets called first). So you can move the network rules file to a number higher than the rules file where you change the name of the interface.

dtischler · December 26, 2019, 10:47pm

Hi Tin-tan,

Where you able to get the network interface renamed via either of the options provided by Anuj? Let us know if that worked, thanks!

gh_tintinp · December 28, 2019, 6:58pm

Hey David,

Thanks for the following up. I now have the opposite problem. The wifi dongle wont be renamed when it is inserted before boot. But if I insert it after boot, it will rename.

Best regards,

Tin-tin

anujdeshpande · December 30, 2019, 10:29am

Just to confirm Tin-tin, this is after you reordered the rules in udev right? What order do you follow now?

gh_tintinp · January 16, 2020, 10:11pm

I did 70 (lower than network rules) then change to 81 (higher than network rules) but still seeing the same behavior: it only renames if I insert wifi dongle after boot, if insert before boot, it will be name wlan1.

anujdeshpande · January 17, 2020, 8:32am

Hi Tin-tin
Can you share the content of the udev rules file that you are using? Would like to take a closer look.

gh_tintinp · January 17, 2020, 9:45pm

Yes,

'SUBSYSTEM=="net", ACTION=="add", ATTRS{idVendor}=="148f", ATTRS{idProduct}=="5370", NAME="ralinkwifi"'

rahul-thakoor · January 20, 2020, 10:54am

Hey @gh_tintinp,

It seems like the order in which the rules are loaded is causing some conflicts. Can you try to boot the device with the dongle attached and running

udevadm control --reload-rules && udevadm trigger to see if the interface name changes?

Thanks

gh_tintinp · January 21, 2020, 5:49pm

Hey Rahul,

Thanks for the suggestions. When the interface show up, it show up as wlan1 (I have another wifi at wlan0). Running udevadm control --reload-rules && udevadm trigger doesn’t change wlan1 to ralinkwifi

Thanks

alexgg · January 21, 2020, 7:05pm

Hi Tin-tin, you could try to increase udev’s logging verbosity by modifying /etc/udev/udev.conf and adding:

udev_log=debug

You will need to re-mount the file system read-write with:

mount -o remount,rw /

Before doing the change.
After booting, doing:

journalctl -u systemd-udevd

Might give us some clues.

gh_tintinp · January 21, 2020, 7:17pm

I’m not sure what is useful for here. Let me know what I should grep

Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: handling device node '/dev/nvhost-vic', devnum=c241:0, mode=0600, uid=0, gid=0
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4257 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: preserve permissions /dev/nvhost-vic, 020600, uid=0, gid=0
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: preserve already existing symlink '/dev/char/241:0' to '../nvhost-vic'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: created empty file '/run/udev/data/c241:0' for '/devices/13e10000.host1x/15340000.vic/13e10000.host1x/nvhost-vic'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: passed device to netlink monitor 0x5564f9f250
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4237 processed
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: IMPORT builtin 'hwdb' /lib/udev/rules.d/50-udev-default.rules:15
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: IMPORT builtin 'hwdb' returned non-zero
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4258 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: RUN 'kmod load $env{MODALIAS}' /lib/udev/rules.d/80-drivers.rules:5
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: Execute 'load' 'of:NnvdisplayT<NULL>Cnvidia,tegra186-dc'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: No module matches 'of:NnvdisplayT<NULL>Cnvidia,tegra186-dc'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: passed device to netlink monitor 0x5564fa3e70
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4233 processed
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4220 running
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: passed 207 byte device to netlink monitor 0x5564f8c860
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: passed 230 byte device to netlink monitor 0x5564f8c860
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4259 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: handling device node '/dev/nvhost-ctrl-nvdec', devnum=c242:1, mode=0600, uid=0, gid=0
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: preserve permissions /dev/nvhost-ctrl-nvdec, 020600, uid=0, gid=0
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: preserve already existing symlink '/dev/char/242:1' to '../nvhost-ctrl-nvdec'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: created empty file '/run/udev/data/c242:1' for '/devices/13e10000.host1x/15480000.nvdec/13e10000.host1x/nvhost-ctrl-nvdec'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: passed device to netlink monitor 0x5564fa2930
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4241 processed
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4260 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: Inserted 'pci_tegra'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: passed device to netlink monitor 0x5564fbf180
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4206 processed
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: passed 420 byte device to netlink monitor 0x5564f8c860
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: passed 305 byte device to netlink monitor 0x5564f8c860
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4261 queued, 'add' 'devfreq'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4262 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4263 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4264 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4265 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4266 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4267 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4268 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4269 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4207 running
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: IMPORT builtin 'hwdb' /lib/udev/rules.d/50-udev-default.rules:15
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4270 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4215 running
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: passed device to netlink monitor 0x5564fbf180
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4215 processed
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4271 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: passed 236 byte device to netlink monitor 0x5564f8c860
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4272 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4273 queued, 'add' 'gpio'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4274 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4275 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4235 running
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4231 running
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4276 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: IMPORT builtin 'hwdb' /lib/udev/rules.d/50-udev-default.rules:15
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: handling device node '/dev/tegra_dc_0', devnum=c238:1, mode=0600, uid=0, gid=0
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: preserve permissions /dev/tegra_dc_0, 020600, uid=0, gid=0
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: preserve already existing symlink '/dev/char/238:1' to '../tegra_dc_0'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4277 queued, 'add' 'platform'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: created empty file '/run/udev/data/c238:1' for '/devices/13e10000.host1x/15210000.nvdisplay/tegra_dc_ext/tegra_dc_0'
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: IMPORT builtin 'hwdb' returned non-zero
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: passed device to netlink monitor 0x5564f9f250
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: seq 4235 processed
Jan 21 19:09:57 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:58 28f6f6a systemd-udevd[54]: RUN 'kmod load $env{MODALIAS}' /lib/udev/rules.d/80-drivers.rules:5
Jan 21 19:09:58 28f6f6a systemd-udevd[54]: seq 4234 running
Jan 21 19:09:58 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:58 28f6f6a systemd-udevd[54]: Execute 'load' 'of:NtsecbT<NULL>Cnvidia,tegra186-tsec'
Jan 21 19:09:58 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:58 28f6f6a systemd-udevd[54]: No module matches 'of:NtsecbT<NULL>Cnvidia,tegra186-tsec'
Jan 21 19:09:58 28f6f6a systemd-udevd[54]: passed 323 byte device to netlink monitor 0x5564f8c860
Jan 21 19:09:58 28f6f6a systemd-udevd[54]: passed device to netlink monitor 0x5564fa12c0
Jan 21 19:09:58 28f6f6a systemd-udevd[54]: maximum number (20) of children reached
Jan 21 19:09:58 28f6f6a systemd-udevd[54]: seq 4231 processed
Jan 21 19:09:58 28f6f6a systemd-udevd[54]: GROUP 44 /lib/udev/rules.d/50-udev-default.rules:34
Jan 21 19:09:58 28f6f6a systemd-udevd[54]: maximum number (20) of children reached

wrboyce · January 21, 2020, 7:25pm

Hi, did you run udevadm control --reload-rules && udevadm trigger whilst the debug logging was enabled? udev is not my forte but I suspect that may produce interesting logs. Otherwise, grepping for ralink from the boot logs may yield something more interesting. Thanks.

wrboyce · January 21, 2020, 7:31pm

Could you show me the output of dmesg too, please?

gh_tintinp · January 21, 2020, 7:32pm

This is when i grep ralink (+/- 50 lines before/after)

Jan 21 19:28:23 28f6f6a systemd-udevd[54]: === trie on-disk ===
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: tool version:          232
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: file size:         8440991 bytes
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: header size             80 bytes
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: strings            1846911 bytes
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: nodes              6594000 bytes
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Load module index
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: timestamp of '/etc/systemd/network' changed
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: timestamp of '/lib/systemd/network' changed
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Parsed configuration file /lib/systemd/network/99-default.link
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Created link configuration context.
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: timestamp of '/etc/udev/rules.d' changed
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: timestamp of '/lib/udev/rules.d' changed
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/40-i2c-tools.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/50-firmware.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/50-udev-default.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/55-dm.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/60-block.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/60-cdrom_id.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/60-drm.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/60-evdev.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/60-fuse.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/60-i2c-tools.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/60-persistent-alsa.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/60-persistent-input.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/60-persistent-storage-dm.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/60-persistent-storage-tape.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/60-persistent-storage.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/60-persistent-v4l.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/60-serial.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/64-btrfs.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/70-debian-uaccess.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/70-mouse.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/70-piksi.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/70-power-switch.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/70-sdcard-mount.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/70-touchpad.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/70-uaccess.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/70-ubloxlte.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/71-seat.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/73-seat-late.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/73-special-net-names.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/73-usb-net-by-mac.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/75-net-description.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/75-probe_mtd.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/78-sound-card.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/80-debian-compat.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/80-drivers.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/80-ifupdown.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/80-net-setup-link.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/81-ralinkwifi.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/85-hdparm.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/85-hwclock.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/97-hid2hci.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/99-systemd.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Reading rules file: /lib/udev/rules.d/usbmount.rules
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: rules contain 24576 bytes tokens (2048 * 12 bytes), 12382 bytes strings
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: 1755 strings (21927 bytes), 1127 de-duplicated (10174 bytes), 629 trie nodes used
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5989 forked new worker [9922]
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5990 queued, 'change' 'pci'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5991 queued, 'change' 'pci_express'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5992 queued, 'change' 'pci_express'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5993 queued, 'change' 'pci'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5994 queued, 'change' 'nvme'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5995 queued, 'change' 'block'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5996 queued, 'change' 'block'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5997 queued, 'change' 'pci_bus'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5998 queued, 'change' 'pci_bus'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5999 queued, 'change' 'platform'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5989 running
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5999 forked new worker [9923]
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: IMPORT builtin 'hwdb' /lib/udev/rules.d/50-udev-default.rules:15
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 6000 queued, 'change' 'platform'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: IMPORT builtin 'hwdb' returned non-zero
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: RUN 'kmod load $env{MODALIAS}' /lib/udev/rules.d/80-drivers.rules:5
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Execute 'load' 'of:Npcie-controllerTpciCnvidia,tegra186-pcie'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Inserted 'pci_tegra'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 6000 forked new worker [9924]
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: passed device to netlink monitor 0x5564f89aa0
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5989 processed
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 6001 queued, 'change' '13e10000.host1x'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5990 running
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5999 running
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: IMPORT builtin 'hwdb' /lib/udev/rules.d/50-udev-default.rules:15
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: IMPORT builtin 'hwdb' /lib/udev/rules.d/50-udev-default.rules:15
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: RUN 'kmod load $env{MODALIAS}' /lib/udev/rules.d/80-drivers.rules:5
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: IMPORT builtin 'hwdb' returned non-zero
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: created db file '/run/udev/data/+pci:0000:00:01.0' for '/devices/10003000.pcie-controller/pci0000:00/0000:00:01.0'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Execute 'load' 'pci:v000010DEd000010E5sv000010DEsd00000000bc06sc04i00'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: No module matches 'pci:v000010DEd000010E5sv000010DEsd00000000bc06sc04i00'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: passed device to netlink monitor 0x5564f89aa0
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5990 processed
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: passed 425 byte device to netlink monitor 0x5564f8c860
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5998 forked new worker [9925]
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 6002 queued, 'change' 'platform'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5991 running
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: RUN 'kmod load $env{MODALIAS}' /lib/udev/rules.d/80-drivers.rules:5
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: Execute 'load' 'of:NiommuT<NULL>Carm,mmu-500'
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: passed device to netlink monitor 0x5564f89aa0
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: seq 5991 processed
Jan 21 19:28:23 28f6f6a systemd-udevd[54]: passed 218 byte device to netlink monitor 0x5564f8c860

gh_tintinp · January 21, 2020, 7:39pm

dmesg

[    8.140022] dhd_module_init in
[    8.143272] systemd-journald[614]: Successfully sent stream file descriptor to service manager.
[    8.143451] tegra_net_perf_init: cannot get wifi sclk
[    8.151859] found wifi platform device bcmdhd_wlan
[    8.160198] gpio tegra-gpio-aon wake69 for gpio=59(FF:3)
[    8.165539] wifi_platform_get_country_code_map: could not get country_code_map
[    8.172770] wifi_plat_dev_drv_probe:platform country code map is not available
[    8.172781] Power-up adapter 'DHD generic adapter'
[    8.172788] wifi_platform_set_power = 1
[    8.204333] systemd-journald[614]: Successfully sent stream file descriptor to service manager.
[    8.240528] usb 1-3.3: New USB device found, idVendor=148f, idProduct=5370
[    8.241662] gpio tegra-gpio-aon wake30 for gpio=60(FF:4)
[    8.252870] usb 1-3.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    8.260198] usb 1-3.3: Product: 802.11 n WLAN
[    8.264568] usb 1-3.3: Manufacturer: Ralink
[    8.268756] usb 1-3.3: SerialNumber: 1.0
[    8.374721] wifi_platform_bus_enumerate device present 1
[    8.397949] wifi_platform_bus_enumerate device present 0
[    8.403794] F1 signature read @0x18000000=0x17214354
[    8.415984] F1 signature OK, socitype:0x1 chip:0x4354 rev:0x1 pkg:0x2
[    8.423009] DHD: dongle ram size is set to 786432(orig 786432) at 0x180000
[    8.430039] wifi_platform_prealloc: failed to alloc static mem section 7
[    8.436767] wifi_platform_get_mac_addr
[    8.442153] CFG80211-ERROR) wl_setup_wiphy : Registering Vendor80211
[    8.446746] wl_create_event_handler(): thread:wl_event_handler:30f started
[    8.446751] CFG80211-ERROR) wl_event_handler : tsk Enter, tsk = 0xffffffc1e2181a70
[    8.446866] dhd_attach(): thread:dhd_watchdog_thread:310 started
[    8.446896] dhd_attach(): thread:dhd_dpc:311 started
[    8.446948] dhd_attach(): thread:dhd_rxf:312 started
[    8.446953] dhd_deferred_work_init: work queue initialized 
[    8.484984] Dongle Host Driver, version 1.201.82 (r)
           Compiled in drivers/net/wireless/bcmdhd on Jan 25 2018 at 00:51:44
[    8.497820] tegra_sysfs_register
[    8.504323] Register interface [wlan0]  MAC: 00:04:4b:c6:ec:c3

[    8.504727] tegra-pcie 10003000.pcie-controller: link 2 down, retrying
[    8.515635] xhci-tegra 3530000.xhci: tegra_xhci_mbox_work mailbox command 5
[    8.515639] xhci-tegra 3530000.xhci: tegra_xhci_mbox_work ignore firmware MBOX_CMD_DEC_SSPI_CLOCK request
[    8.536268] dhd_prot_ioctl : bus is down. we have nothing to do
[    8.543590] sdhci-tegra 3440000.sdhci: Tuning already done, restoring the best tap value : 65
[    8.553162] wifi_platform_set_power = 0
[    8.585671] systemd-journald[614]: Successfully sent stream file descriptor to service manager.
[    8.591362] EXT4-fs (mmcblk0p14): mounted filesystem with ordered data mode. Opts: (null)
[    8.613658] systemd-journald[614]: Successfully sent stream file descriptor to service manager.
[    8.625252] systemd-journald[614]: Successfully sent stream file descriptor to service manager.
[    8.637816] cfg80211: World regulatory domain updated:
[    8.643005] cfg80211:  DFS Master region: unset
[    8.643005] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[    8.643010] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[    8.643014] cfg80211:   (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[    8.643020] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
[    8.643023] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[    8.643026] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[    8.643029] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
[    8.643031] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
[    8.643034] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
[    8.735916] FAT-fs (mmcblk0p12): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[    8.787792] systemd-journald[614]: Successfully sent stream file descriptor to service manager.
[    8.798280] systemd-journald[614]: Successfully sent stream file descriptor to service manager.
[    8.851286] systemd-journald[614]: Successfully sent stream file descriptor to service manager.
[    8.927441] EXT4-fs (mmcblk0p16): mounted filesystem with ordered data mode. Opts: (null)
[    8.984796] tegra-pcie 10003000.pcie-controller: link 2 down, retrying

majorz · January 22, 2020, 12:51pm

Hi, can you please paste the full output of journalctl. The error you see usually indicates a racing problem between udev and other service - possibly NetworkManager. The full output will provide insight into this.

Also can you please paste the fragment of usb-devices which is for the dongle. Also please provide its model.

I see numerous errors in the kernel logs related to WiFi and this could also be related.

Thanks,
Zahari

gh_tintinp · January 24, 2020, 10:08pm

Full journalctl log since reboot

-- Logs begin at Tue 2020-01-21 17:40:50 UTC, end at Fri 2020-01-24 22:01:47 UTC. --
Jan 24 21:54:09 28f6f6a dockerd[211]: time="2020-01-24T21:54:09.005858335Z" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
Jan 24 21:54:09 28f6f6a kernel: docker0: port 2(veth4b6f346) entered disabled state
Jan 24 21:54:09 28f6f6a kernel: vethe4f4c5d: renamed from eth0
Jan 24 21:54:09 28f6f6a systemd-journald[50]: Suppressed 21966 messages from /system.slice/systemd-udevd.service
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5131 queued, 'add' 'net'
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5131 forked new worker [1063]
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5132 queued, 'move' 'net'
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5131 running
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: IMPORT builtin 'net_id' /lib/udev/rules.d/75-net-description.rules:6
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: RUN 'ifupdown-hotplug' /lib/udev/rules.d/80-ifupdown.rules:5
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: IMPORT builtin 'path_id' /lib/udev/rules.d/80-net-setup-link.rules:5
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: IMPORT builtin 'path_id' returned non-zero
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: IMPORT builtin 'net_setup_link' /lib/udev/rules.d/80-net-setup-link.rules:9
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: Assertion 'udev_device' failed at ../src/libudev/libudev-device.c:128, function udev_device_get_driver(). Ignoring.
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: Config file /lib/systemd/network/99-default.link applies to device vethe4f4c5d
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: RUN '/lib/systemd/systemd-sysctl --prefix=/net/ipv4/conf/$name --prefix=/net/ipv4/neigh/$name --prefix=/net/ipv6/conf/$name --prefix=/net/ipv6/neigh/$name' /lib/udev/rules.d/99-systemd.rules:56
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: created db file '/run/udev/data/n14' for '/devices/virtual/net/vethe4f4c5d'
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: starting 'ifupdown-hotplug'
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: Process 'ifupdown-hotplug' succeeded.
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: starting '/lib/systemd/systemd-sysctl --prefix=/net/ipv4/conf/vethe4f4c5d --prefix=/net/ipv4/neigh/vethe4f4c5d --prefix=/net/ipv6/conf/vethe4f4c5d --prefix=/net/ipv6/neigh/vethe4f4c5d'
Jan 24 21:54:09 28f6f6a kernel: docker0: port 2(veth4b6f346) entered disabled state
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5133 queued, 'remove' 'queues'
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5134 queued, 'remove' 'queues'
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: Process '/lib/systemd/systemd-sysctl --prefix=/net/ipv4/conf/vethe4f4c5d --prefix=/net/ipv4/neigh/vethe4f4c5d --prefix=/net/ipv6/conf/vethe4f4c5d --prefix=/net/ipv6/neigh/vethe4f4c5d' succeeded.
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: passed device to netlink monitor 0x556babde10
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5131 processed
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5135 queued, 'remove' 'net'
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: passed 223 byte device to netlink monitor 0x556bab2870
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5132 running
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: IMPORT builtin 'net_id' /lib/udev/rules.d/75-net-description.rules:6
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: IMPORT builtin 'net_id' returned non-zero
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: IMPORT builtin 'path_id' /lib/udev/rules.d/80-net-setup-link.rules:5
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: IMPORT builtin 'path_id' returned non-zero
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: created db file '/run/udev/data/n14' for '/devices/virtual/net/vethe4f4c5d'
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: passed device to netlink monitor 0x556babde10
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5132 processed
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: passed 162 byte device to netlink monitor 0x556bab2870
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5133 running
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: passed device to netlink monitor 0x556babde10
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5133 processed
Jan 24 21:54:09 28f6f6a kernel: device veth4b6f346 left promiscuous mode
Jan 24 21:54:09 28f6f6a kernel: docker0: port 2(veth4b6f346) entered disabled state
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5134 forked new worker [1068]
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5134 running
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: passed device to netlink monitor 0x556bac6930
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5134 processed
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: passed 180 byte device to netlink monitor 0x556bab2870
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5135 running
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: RUN 'ifupdown-hotplug' /lib/udev/rules.d/80-ifupdown.rules:5
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: IMPORT builtin 'path_id' /lib/udev/rules.d/80-net-setup-link.rules:5
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: IMPORT builtin 'path_id' returned non-zero
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: starting 'ifupdown-hotplug'
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5136 queued, 'remove' 'queues'
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: passed 162 byte device to netlink monitor 0x556bab2870
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5137 queued, 'remove' 'queues'
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5136 running
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: passed device to netlink monitor 0x556bac6930
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5136 processed
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5137 forked new worker [1070]
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5138 queued, 'remove' 'net'
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: Process 'ifupdown-hotplug' succeeded.
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5137 running
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: passed device to netlink monitor 0x556babde10
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5135 processed
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: passed device to netlink monitor 0x556bac7f90
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5137 processed
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: passed 180 byte device to netlink monitor 0x556bab2870
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5138 running
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: RUN 'ifupdown-hotplug' /lib/udev/rules.d/80-ifupdown.rules:5
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: IMPORT builtin 'path_id' /lib/udev/rules.d/80-net-setup-link.rules:5
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: IMPORT builtin 'path_id' returned non-zero
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: starting 'ifupdown-hotplug'
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: Process 'ifupdown-hotplug' succeeded.
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: passed device to netlink monitor 0x556babde10
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5138 processed
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: cleanup idle workers
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: Unload module index
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: Unload module index
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: Unloaded link configuration context.
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: Unloaded link configuration context.
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: Unload module index
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: Unloaded link configuration context.
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: worker [1063] exited
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: cleanup idle workers
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: worker [1068] exited
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: cleanup idle workers
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: worker [1070] exited
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5145 queued, 'remove' 'slab'
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5145 forked new worker [1076]
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5145 running
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: passed device to netlink monitor 0x556babde10
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: seq 5145 processed
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: cleanup idle workers
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: Unload module index
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: Unloaded link configuration context.
Jan 24 21:54:09 28f6f6a systemd-udevd[56]: worker [1076] exited
Jan 24 21:54:10 28f6f6a systemd-journald[636]: Compressed data object 792 -> 528 using XZ
Jan 24 21:54:13 28f6f6a systemd-journald[636]: Compressed data object 716 -> 436 using XZ
Jan 24 21:55:02 28f6f6a CRON[3150]: pam_unix(cron:session): session opened for user root by (uid=0)
Jan 24 21:55:02 28f6f6a CRON[3153]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
Jan 24 21:55:02 28f6f6a CRON[3150]: pam_unix(cron:session): session closed for user root
Jan 24 21:55:02 28f6f6a systemd-journald[636]: Sent WATCHDOG=1 notification.
Jan 24 21:56:32 28f6f6a systemd-journald[636]: Sent WATCHDOG=1 notification.
Jan 24 21:58:02 28f6f6a systemd-journald[636]: Sent WATCHDOG=1 notification.
Jan 24 21:58:40 28f6f6a redis-server[154]: 154:M 24 Jan 2020 21:58:40.043 * 10 changes in 300 seconds. Saving...
Jan 24 21:58:40 28f6f6a redis-server[154]: 154:M 24 Jan 2020 21:58:40.044 * Background saving started by pid 11348
Jan 24 21:58:40 28f6f6a redis-server[154]: 11348:C 24 Jan 2020 21:58:40.069 * DB saved on disk
Jan 24 21:58:40 28f6f6a redis-server[154]: 11348:C 24 Jan 2020 21:58:40.071 * RDB: 0 MB of memory used by copy-on-write
Jan 24 21:58:40 28f6f6a redis-server[154]: 154:M 24 Jan 2020 21:58:40.145 * Background saving terminated with success
Jan 24 21:58:40 28f6f6a systemd-journald[636]: Data hash table of /run/log/journal/16bafa5897eb4251a27ccebf7c4921af/system.journal has a fill level at 75.1 (1537 of 2047 items, 1048576 file size, 682 bytes per hash table item), suggesting rotation.
Jan 24 21:58:40 28f6f6a systemd-journald[636]: /run/log/journal/16bafa5897eb4251a27ccebf7c4921af/system.journal: Journal header limits reached or header out-of-date, rotating.
Jan 24 21:58:40 28f6f6a systemd-journald[636]: Rotating...
Jan 24 21:58:40 28f6f6a systemd-journald[636]: Reserving 2047 entries in hash table.
Jan 24 21:58:40 28f6f6a systemd-journald[636]: Vacuuming...
Jan 24 21:58:40 28f6f6a systemd-journald[636]: Deleted archived journal /run/log/journal/16bafa5897eb4251a27ccebf7c4921af/system@3e708492bad34726a4baab4076858b25-0000000000000aea-00059ce9c9d4789e.journal (1.0M).
Jan 24 21:58:40 28f6f6a systemd-journald[636]: Vacuuming done, freed 1.0M of archived journals from /run/log/journal/16bafa5897eb4251a27ccebf7c4921af.
Jan 24 21:59:20 28f6f6a kernel: IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
Jan 24 21:59:32 28f6f6a systemd-journald[636]: Sent WATCHDOG=1 notification.
Jan 24 22:01:02 28f6f6a systemd-journald[636]: Sent WATCHDOG=1 notification.

usb-devices output

T:  Bus=01 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#=  1 Spd=480 MxCh= 4
D:  Ver= 2.00 Cls=09(hub  ) Sub=00 Prot=01 MxPS=64 #Cfgs=  1
P:  Vendor=1d6b ProdID=0002 Rev=04.04
S:  Manufacturer=Linux 4.4.38-l4t-r28.1+g79e4600 xhci-hcd
S:  Product=xHCI Host Controller
S:  SerialNumber=3530000.xhci
C:  #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr=0mA
I:  If#= 0 Alt= 0 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=00 Driver=hub

T:  Bus=01 Lev=01 Prnt=01 Port=02 Cnt=01 Dev#=  2 Spd=480 MxCh= 4
D:  Ver= 2.00 Cls=09(hub  ) Sub=00 Prot=02 MxPS=64 #Cfgs=  1
P:  Vendor=0424 ProdID=2514 Rev=0b.b3
C:  #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr=2mA
I:  If#= 0 Alt= 1 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=02 Driver=hub

T:  Bus=01 Lev=02 Prnt=02 Port=00 Cnt=01 Dev#=  3 Spd=480 MxCh= 0
D:  Ver= 2.00 Cls=ef(misc ) Sub=02 Prot=01 MxPS=64 #Cfgs=  1
P:  Vendor=1546 ProdID=1146 Rev=01.00
S:  Manufacturer=u-blox
S:  Product=MODEM-LTE
S:  SerialNumber=000000000100
C:  #Ifs= 4 Cfg#= 1 Atr=e0 MxPwr=500mA
I:  If#= 0 Alt= 0 #EPs= 1 Cls=e0(wlcon) Sub=01 Prot=03 Driver=rndis_host
I:  If#= 1 Alt= 0 #EPs= 2 Cls=0a(data ) Sub=00 Prot=00 Driver=rndis_host
I:  If#= 2 Alt= 0 #EPs= 1 Cls=02(commc) Sub=02 Prot=01 Driver=cdc_acm
I:  If#= 3 Alt= 0 #EPs= 2 Cls=0a(data ) Sub=00 Prot=00 Driver=cdc_acm

T:  Bus=01 Lev=02 Prnt=02 Port=01 Cnt=02 Dev#=  4 Spd=480 MxCh= 0
D:  Ver= 2.00 Cls=00(>ifc ) Sub=00 Prot=00 MxPS=64 #Cfgs=  1
P:  Vendor=2e69 ProdID=1001 Rev=04.06
S:  Manufacturer=Swift Navigation
S:  Product=Piksi Multi
S:  SerialNumber=2af5cecdfc584a5b819b603282e266e1
C:  #Ifs= 4 Cfg#= 1 Atr=80 MxPwr=2mA
I:  If#= 0 Alt= 0 #EPs= 1 Cls=02(commc) Sub=02 Prot=01 Driver=cdc_acm
I:  If#= 1 Alt= 0 #EPs= 2 Cls=0a(data ) Sub=00 Prot=00 Driver=cdc_acm
I:  If#= 2 Alt= 0 #EPs= 1 Cls=02(commc) Sub=02 Prot=01 Driver=cdc_acm
I:  If#= 3 Alt= 0 #EPs= 2 Cls=0a(data ) Sub=00 Prot=00 Driver=cdc_acm

T:  Bus=01 Lev=02 Prnt=02 Port=02 Cnt=03 Dev#=  5 Spd=480 MxCh= 0
D:  Ver= 2.00 Cls=00(>ifc ) Sub=00 Prot=00 MxPS=64 #Cfgs=  1
P:  Vendor=148f ProdID=5370 Rev=01.01
S:  Manufacturer=Ralink
S:  Product=802.11 n WLAN
S:  SerialNumber=1.0
C:  #Ifs= 1 Cfg#= 1 Atr=80 MxPwr=450mA
I:  If#= 0 Alt= 0 #EPs= 7 Cls=ff(vend.) Sub=ff Prot=ff Driver=rt2800usb

T:  Bus=02 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#=  1 Spd=5000 MxCh= 3
D:  Ver= 3.00 Cls=09(hub  ) Sub=00 Prot=03 MxPS= 9 #Cfgs=  1
P:  Vendor=1d6b ProdID=0003 Rev=04.04
S:  Manufacturer=Linux 4.4.38-l4t-r28.1+g79e4600 xhci-hcd
S:  Product=xHCI Host Controller
S:  SerialNumber=3530000.xhci
C:  #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr=0mA
I:  If#= 0 Alt= 0 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=00 Driver=hub

majorz · January 27, 2020, 11:32am

Hi, due to log rotation I do not see the logs from the boot of the device. I need to see the initialization sequences there.

Can you please reboot the device, grab the logs and send them over again? BTW, from the dashboard it is easiest to upload those to a service like file.io or transfer.sh:

cd /mnt/data
journalctl > logs.txt
curl -F "file=@logs.txt" https://file.io

Thanks,
Zahari

gh_tintinp · January 31, 2020, 7:39pm

Hi,

This is all it is to journalctl right after boot
https://file.io/iPRs3e