Hi!
CPU0 CPU1 CPU2 CPU3 CPU4 CPU5
CPU6 CPU7
143: 0 0 0 0 0 6
0 192 PCI-MSI 4194304-edge bhi, mhi, mhi, mhi, mhiAttaching files with full /proc/interrupts and made a small script with timestamps / logging to see what happens in chronological order. /Oskar On Mon, 17 May 2021 at 11:27, Loic Poulain <loic.poul...@linaro.org> wrote: > Hi Oskar, > > On Wed, 12 May 2021 at 11:10, Oskar Stenman <os...@cetex.se> wrote: > > > > On Wed, 12 May 2021 at 09:35, Aleksander Morgado < > aleksan...@aleksander.es> wrote: > >> > >> Or even easier; just completely skip the name of the port and create a > >> generic "gsm" connection setting not bound to any specific device. > >> > >> $ nmcli conn add type gsm con-name wwan gsm.apn data.tre.se gsm.pin > 7470 > > > > > > I found the issue now. When everything was segfaulting due to garbage in > files i saw (strace) that network-manager was dying after loading > libnm-wwan.so, I thought it was installed by ModemManager so I deleted the > module to get NM to start (and wifi to work again..), this of course broke > wwan support in nm. > > Reinstalled Network-Manager and it put the file back and it detects the > interface. > > > > oskar@oskar-ThinkPad-X1-Nano-Gen-1:~$ nmcli conn up wwan > > Connection successfully activated (D-Bus active path: > /org/freedesktop/NetworkManager/ActiveConnection/399) > > > > (As you can see on connection counter "399", once ModemManager started > NM tried to connect "a few times" before i stopped it and ran the qmicli > command) > > > > As a sidenote I switched operators today, Tele2. When i replaced the > simcard the modem got stuck "unavailable" according to NetworkManager, > After a reboot i have to run that qmicli cuskit command again to enable the > card. (so not just a do-once kinda thing, more a "do every boot" kinda > thing) > > > > Alright, reboot. Clean slate, try again. (There are at least a couple > seconds between runnig each of these commands, often more than a few, i > realize now that I should've put timestamps in there..) > > oskar@oskar-ThinkPad-X1-Nano-Gen-1:~$ sudo qmicli -p -d > /dev/wwan0p2MBIM --device-open-mbim --dms-dell-cuskit-unlock=00 > > [sudo] password for oskar: > > [12 maj 2021, 10:15:43] -Warning ** [/dev/wwan0p2MBIM] couldn't detect > transport type of port: couldn't detect device driver > > [12 maj 2021, 10:15:43] -Warning ** [/dev/wwan0p2MBIM] requested MBIM > mode but unexpected transport type found > > [/dev/wwan0p2MBIM] Successfully run Dell cuskit unlock > > oskar@oskar-ThinkPad-X1-Nano-Gen-1:~$ sudo service ModemManager start > > oskar@oskar-ThinkPad-X1-Nano-Gen-1:~$ nmcli conn up wwan > > Connection successfully activated (D-Bus active path: > /org/freedesktop/NetworkManager/ActiveConnection/2) > > oskar@oskar-ThinkPad-X1-Nano-Gen-1:~$ ping ftp.sunet.se > > PING ftp.sunet.se(tutankhamon.ftp.acc.umu.se (2001:6b0:19::163)) 56 > data bytes > > 64 bytes from tutankhamon.ftp.acc.umu.se (2001:6b0:19::163): icmp_seq=1 > ttl=51 time=57.6 ms > > 64 bytes from tutankhamon.ftp.acc.umu.se (2001:6b0:19::163): icmp_seq=2 > ttl=51 time=1055 ms > > 64 bytes from tutankhamon.ftp.acc.umu.se (2001:6b0:19::163): icmp_seq=3 > ttl=51 time=1053 ms > > 64 bytes from tutankhamon.ftp.acc.umu.se (2001:6b0:19::163): icmp_seq=4 > ttl=51 time=1051 ms > > 64 bytes from tutankhamon.ftp.acc.umu.se (2001:6b0:19::163): icmp_seq=5 > ttl=51 time=1049 ms > > 64 bytes from tutankhamon.ftp.acc.umu.se (2001:6b0:19::163): icmp_seq=6 > ttl=51 time=1048 ms > > 64 bytes from tutankhamon.ftp.acc.umu.se (2001:6b0:19::163): icmp_seq=7 > ttl=51 time=1046 ms > > 64 bytes from tutankhamon.ftp.acc.umu.se (2001:6b0:19::163): icmp_seq=8 > ttl=51 time=1045 ms > > 64 bytes from tutankhamon.ftp.acc.umu.se (2001:6b0:19::163): icmp_seq=9 > ttl=51 time=1042 ms > > 64 bytes from tutankhamon.ftp.acc.umu.se (2001:6b0:19::163): > icmp_seq=10 ttl=51 time=1041 ms > > ^C > > --- ftp.sunet.se ping statistics --- > > 11 packets transmitted, 10 received, 9.09091% packet loss, time 10016ms > > rtt min/avg/max/mdev = 57.648/948.772/1055.277/297.071 ms, pipe 2 > > > > So we have data! > > But i see messages like this in dmesg: > > [ 254.970231] __common_interrupt: 3.34 No irq handler for vector > > [ 255.098499] __common_interrupt: 3.34 No irq handler for vector > > > > With that ~1s latency I'm guessing something resorts to polling instead > of triggering on interrupts? > > Weird, can you share the output of `cat /proc/interrupts | grep mhi`. > > Regards, > Loic >
test.sh
Description: application/shellscript
interrupts
Description: Binary data
+ date
+ journalctl -f
mån 17 maj 2021 12:45:50 CEST
+ cat /proc/interrupts
+ head -n 1
CPU0 CPU1 CPU2 CPU3 CPU4 CPU5 CPU6 CPU7
+ cat /proc/interrupts
+ grep mhi
143: 0 0 0 0 0 5 0 0 PCI-MSI 4194304-edge bhi, mhi, mhi, mhi, mhi
-- Journal begins at Fri 2021-04-30 20:25:03 CEST. --
maj 17 12:45:50 oskar-ThinkPad-X1-Nano-Gen-1 sudo[2882]: pam_unix(sudo:auth): Couldn't open /etc/securetty: No such file or directory
maj 17 12:45:50 oskar-ThinkPad-X1-Nano-Gen-1 sudo[2882]: oskar : TTY=pts/0 ; PWD=/home/oskar ; USER=root ; COMMAND=./test.sh
maj 17 12:45:50 oskar-ThinkPad-X1-Nano-Gen-1 sudo[2882]: pam_unix(sudo:session): session opened for user root by (uid=1000)
+ service ModemManager status
● ModemManager.service - Modem Manager
Loaded: loaded (/lib/systemd/system/ModemManager.service; disabled; vendor preset: enabled)
Active: inactive (dead)
+ nmcli conn
NAME UUID TYPE DEVICE
br-788f489f3789 d340cd7b-5164-47ce-b0a3-3bfe4d7d5982 bridge br-788f489f3789
br-c44f623ec121 3c0e30de-1bb2-4a05-b6ee-0d84746ff61e bridge br-c44f623ec121
docker0 0a53f46d-d403-4e9c-b84a-7c25aa565fc6 bridge docker0
censored 81b3e594-7a42-4e03-aa43-542cc55f1523 wireguard vpn
censored bd7027bf-3219-4a77-a453-9ec139362d75 wifi --
censored eda3984d-623b-4460-88f5-5c8341a2a029 wifi --
censored afac2b80-e0f4-4415-ad16-a092a58a3e3d vpn --
censored e6a6bcec-8f2b-488e-990f-698fd3f19f8e wifi --
wwan 5ae7708f-12b0-403d-97e0-cecdf77d0190 gsm --
+ date
mån 17 maj 2021 12:45:51 CEST
+ qmicli -p -d /dev/wwan0p2MBIM --device-open-mbim --dms-dell-cuskit-unlock=00
[17 maj 2021, 12:45:51] -Warning ** [/dev/wwan0p2MBIM] couldn't detect transport type of port: couldn't detect device driver
[17 maj 2021, 12:45:51] -Warning ** [/dev/wwan0p2MBIM] requested MBIM mode but unexpected transport type found
[/dev/wwan0p2MBIM] Successfully run Dell cuskit unlock
+ cat /proc/interrupts
+ head -n 1
CPU0 CPU1 CPU2 CPU3 CPU4 CPU5 CPU6 CPU7
+ cat /proc/interrupts
+ grep mhi
143: 0 0 0 0 0 6 0 13 PCI-MSI 4194304-edge bhi, mhi, mhi, mhi, mhi
+ date
mån 17 maj 2021 12:45:53 CEST
+ nmcli conn
NAME UUID TYPE DEVICE
br-788f489f3789 d340cd7b-5164-47ce-b0a3-3bfe4d7d5982 bridge br-788f489f3789
br-c44f623ec121 3c0e30de-1bb2-4a05-b6ee-0d84746ff61e bridge br-c44f623ec121
docker0 0a53f46d-d403-4e9c-b84a-7c25aa565fc6 bridge docker0
censored 81b3e594-7a42-4e03-aa43-542cc55f1523 wireguard vpn
censored bd7027bf-3219-4a77-a453-9ec139362d75 wifi --
censored eda3984d-623b-4460-88f5-5c8341a2a029 wifi --
censored afac2b80-e0f4-4415-ad16-a092a58a3e3d vpn --
censored e6a6bcec-8f2b-488e-990f-698fd3f19f8e wifi --
wwan 5ae7708f-12b0-403d-97e0-cecdf77d0190 gsm --
+ service ModemManager start
+ cat /proc/interrupts
+ head -n 1
CPU0 CPU1 CPU2 CPU3 CPU4 CPU5 CPU6 CPU7
+ cat /proc/interrupts
+ grep mhi
143: 0 0 0 0 0 6 0 13 PCI-MSI 4194304-edge bhi, mhi, mhi, mhi, mhi
+ date
mån 17 maj 2021 12:45:53 CEST
+ echo 'Wait a bit for things to stabilize'
Wait a bit for things to stabilize
+ sleep 10
maj 17 12:45:53 oskar-ThinkPad-X1-Nano-Gen-1 systemd[1]: Starting Modem Manager...
maj 17 12:45:53 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> ModemManager (version 1.17.1) starting in system bus...
maj 17 12:45:53 oskar-ThinkPad-X1-Nano-Gen-1 systemd[1]: Started Modem Manager.
maj 17 12:45:53 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248353.8785] modem-manager: ModemManager not available
maj 17 12:45:53 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248353.8962] modem-manager: ModemManager now available
maj 17 12:45:55 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <warn> [wwan0p1QCDM/qcdm] failed to open serial device
maj 17 12:45:55 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <warn> [plugin-manager] task 1,wwan0p1QCDM: error when checking support with plugin 'foxconn': (wwan/wwan0p1QCDM) Failed to open QCDM port: Failed to open QCDM port: -2
maj 17 12:45:55 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <warn> [plugin-manager] task 1,wwan0: error when checking support with plugin 'foxconn': (wwan/wwan0) failed to open port: Could not open serial device wwan0: No such file or directory
maj 17 12:45:55 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: opening device...
maj 17 12:45:55 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Couldn't find descriptors file, possibly not using cdc_mbim
maj 17 12:45:55 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Fallback to default max control message size: 4096
maj 17 12:45:55 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <warn> [wwan0p1QCDM/qcdm] failed to open serial device
maj 17 12:45:55 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <warn> [plugin-manager] task 1,wwan0p1QCDM: error when checking support with plugin 'generic': (wwan/wwan0p1QCDM) Failed to open QCDM port: Failed to open QCDM port: -2
maj 17 12:45:55 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <warn> [plugin-manager] task 1,wwan0: error when checking support with plugin 'generic': (wwan/wwan0) failed to open port: Could not open serial device wwan0: No such file or directory
maj 17 12:45:55 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [wwan0p2MBIM/mbim] MBIM device is not QMI capable
maj 17 12:45:55 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] channel destroyed
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [base-manager] couldn't check support for device '/sys/devices/pci0000:00/0000:00:14.3': not supported by any plugin
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [device /sys/devices/pci0000:00/0000:00:1c.0/0000:08:00.0] creating modem with plugin 'foxconn' and '2' ports
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [base-manager] modem for device '/sys/devices/pci0000:00/0000:00:1c.0/0000:08:00.0' successfully created
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: opening device...
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Couldn't find descriptors file, possibly not using cdc_mbim
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Fallback to default max control message size: 4096
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Opening device with flags 'version-info, proxy, mbim, expect-indications'...
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] couldn't detect transport type of port: couldn't detect device driver
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] requested MBIM mode but unexpected transport type found
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] created endpoint
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] creating MBIM device...
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] MBIM device created
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] opening MBIM device...
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: opening device...
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Couldn't find descriptors file, possibly not using cdc_mbim
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Fallback to default max control message size: 4096
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] MBIM device open
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Checking version info (15 retries)...
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] QMI Device supports 35 services:
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] ctl (1.5)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] wds (1.193)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] dms (1.79)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] nas (1.25)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] qos (1.18)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] wms (1.10)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] auth (1.14)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] at (1.6)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] voice (2.1)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] cat2 (2.24)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] uim (1.77)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] pbm (1.4)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] test (1.0)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] loc (2.126)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] sar (1.0)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] ims (1.91)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] ts (1.0)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] tmd (1.0)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] wda (1.24)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] csvt (1.6)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] imsa (1.44)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] coex (1.0)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] pdc (1.0)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] rfrpe (1.0)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] dsd (1.67)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] unknown [0x2d] (1.3)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] unknown [0x30] (1.12)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] unknown [0x31] (1.0)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] unknown [0x44] (1.4)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] unknown [0x49] (1.7)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] unknown [0x4a] (1.1)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] unknown [0x4b] (1.3)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] unknown [0x4c] (1.0)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] unknown [0x4d] (1.4)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] unknown [0x4e] (1.2)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Enabling QMI indications via MBIM...
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] enabled QMI indications via MBIM
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0/wwan0p2MBIM/mbim] MBIM device is QMI capable
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Allocating new client ID...
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Registered 'dms' (version 1.79) client with ID '1'
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Allocating new client ID...
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Registered 'nas' (version 1.25) client with ID '4'
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Allocating new client ID...
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Registered 'loc' (version 2.126) client with ID '1'
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Allocating new client ID...
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: [/dev/wwan0p2MBIM] Registered 'pdc' (version 1.0) client with ID '1'
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] QMI-based capability and mode switching support enabled
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <warn> [modem0] couldn't query SIM slots: Couldn't peek client for service 'uim'
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <warn> [modem0/sim0] couldn't load list of emergency numbers: No AT port available to run command
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <warn> [modem0/sim0] couldn't load list of preferred networks: No AT port available to run command
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] using fallback carrier configuration 'GCF' (version 0x0a000804, size 62708 bytes)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] carrier config switching not needed: already using 'GCF'
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <warn> [modem0] couldn't load UE mode of operation for EPS: No AT port available to run command
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <warn> [modem0] couldn't open ports during Modem SIM hot swap enabling: Couldn't get primary port
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] state changed (unknown -> disabled)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248356.6448] modem["wwan0p2MBIM"]: modem state changed, 'disabled' --> 'enabling' (reason: user preference)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248356.6453] manager: (wwan0p2MBIM): new Broadband device (/org/freedesktop/NetworkManager/Devices/7)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248356.6460] device (wwan0p2MBIM): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248356.6468] device (wwan0p2MBIM): modem state 'enabling'
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248356.6484] device (wwan0p2MBIM): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] state changed (disabled -> enabling)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] state changed (enabling -> enabled)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248356.6988] modem["wwan0p2MBIM"]: modem state changed, 'enabling' --> 'enabled' (reason: user-requested)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] 3GPP registration state changed (unknown -> registering)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] 3GPP registration state changed (registering -> home)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] state changed (enabled -> registered)
maj 17 12:45:56 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248356.7077] modem["wwan0p2MBIM"]: modem state changed, 'enabled' --> 'registered' (reason: unknown)
+ cat /proc/interrupts
+ head -n 1
CPU0 CPU1 CPU2 CPU3 CPU4 CPU5 CPU6 CPU7
+ cat /proc/interrupts
+ grep mhi
143: 0 0 0 0 0 6 0 170 PCI-MSI 4194304-edge bhi, mhi, mhi, mhi, mhi
+ date
mån 17 maj 2021 12:46:03 CEST
+ nmcli conn up wwan
maj 17 12:46:03 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248363.9176] agent-manager: agent[8e3766801f508290,:1.103/nmcli-connect/0]: agent registered
maj 17 12:46:03 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248363.9195] device (wwan0p2MBIM): Activation: starting connection 'wwan' (5ae7708f-12b0-403d-97e0-cecdf77d0190)
maj 17 12:46:03 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248363.9197] audit: op="connection-activate" uuid="5ae7708f-12b0-403d-97e0-cecdf77d0190" name="wwan" pid=3000 uid=0 result="success"
maj 17 12:46:03 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248363.9198] device (wwan0p2MBIM): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
maj 17 12:46:03 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248363.9204] manager: NetworkManager state is now CONNECTING
maj 17 12:46:03 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] simple connect started...
maj 17 12:46:03 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] simple connect state (4/8): wait to get fully enabled
maj 17 12:46:03 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] simple connect state (5/8): register
maj 17 12:46:03 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] simple connect state (6/8): bearer
maj 17 12:46:03 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] simple connect state (7/8): connect
maj 17 12:46:03 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] state changed (registered -> connecting)
maj 17 12:46:03 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248363.9268] modem["wwan0p2MBIM"]: modem state changed, 'registered' --> 'connecting' (reason: user-requested)
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] state changed (connecting -> connected)
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 ModemManager[2956]: <info> [modem0] simple connect state (8/8): all done
Connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/5)
+ date
mån 17 maj 2021 12:46:04 CEST
+ ping 2001:6b0:19::165 -c 10 -w 10
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3037] modem["wwan0p2MBIM"]: modem state changed, 'connecting' --> 'connected' (reason: user-requested)
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3077] device (wwan0p2MBIM): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3084] device (wwan0p2MBIM): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3088] modem-broadband[wwan0p2MBIM]: IPv4 static configuration:
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3089] modem-broadband[wwan0p2MBIM]: address 77.218.205.1/30
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3089] modem-broadband[wwan0p2MBIM]: gateway 77.218.205.2
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3089] modem-broadband[wwan0p2MBIM]: DNS 130.244.127.161
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3089] modem-broadband[wwan0p2MBIM]: DNS 130.244.127.169
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3089] modem-broadband[wwan0p2MBIM]: MTU 1500
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3100] modem-broadband[wwan0p2MBIM]: IPv6 base configuration:
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3100] modem-broadband[wwan0p2MBIM]: address 2a00:801:2d3:4286:84cf:5f26:5812:3c36/64
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3101] modem-broadband[wwan0p2MBIM]: gateway 2a00:801:2d3:4286:5b2:ed2b:e054:fb4a
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3101] modem-broadband[wwan0p2MBIM]: DNS 2a00:800:1000:3::1
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3101] modem-broadband[wwan0p2MBIM]: DNS 2a00:800:1000:4::1
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3119] device (wwan0p2MBIM): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 dbus-daemon[1242]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.10' (uid=0 pid=1243 comm="/usr/sbin/NetworkManager --no-daemon ")
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 systemd[1]: Starting Network Manager Script Dispatcher Service...
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 dbus-daemon[1242]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 systemd[1]: Started Network Manager Script Dispatcher Service.
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3286] device (wwan0p2MBIM): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3291] device (wwan0p2MBIM): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3298] manager: NetworkManager state is now CONNECTED_LOCAL
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3315] manager: NetworkManager state is now CONNECTED_SITE
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3317] policy: set 'wwan' (mhi_mbim0) as default for IPv4 routing and DNS
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3320] policy: set 'wwan' (mhi_mbim0) as default for IPv6 routing and DNS
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 NetworkManager[1243]: <info> [1621248364.3330] device (wwan0p2MBIM): Activation: successful, device activated.
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:04 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
PING 2001:6b0:19::165(2001:6b0:19::165) 56 data bytes
--- 2001:6b0:19::165 ping statistics ---
10 packets transmitted, 0 received, 100% packet loss, time 9205ms
+ cat /proc/interrupts
+ head -n 1
CPU0 CPU1 CPU2 CPU3 CPU4 CPU5 CPU6 CPU7
+ cat /proc/interrupts
+ grep mhi
143: 0 0 0 0 0 6 0 183 PCI-MSI 4194304-edge bhi, mhi, mhi, mhi, mhi
+ sleep 10
maj 17 12:46:14 oskar-ThinkPad-X1-Nano-Gen-1 systemd-resolved[1181]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 130.244.127.161.
maj 17 12:46:14 oskar-ThinkPad-X1-Nano-Gen-1 systemd[1]: NetworkManager-dispatcher.service: Succeeded.
maj 17 12:46:15 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:15 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:15 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:15 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:15 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:15 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:15 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:15 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:16 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:16 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
+ date
mån 17 maj 2021 12:46:24 CEST
+ ping 2001:6b0:19::165 -c 10 -w 10
maj 17 12:46:33 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 3 callbacks suppressed
maj 17 12:46:33 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:33 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:33 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:33 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:33 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:33 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:33 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
maj 17 12:46:33 oskar-ThinkPad-X1-Nano-Gen-1 kernel: __common_interrupt: 5.34 No irq handler for vector
PING 2001:6b0:19::165(2001:6b0:19::165) 56 data bytes
64 bytes from 2001:6b0:19::165: icmp_seq=4 ttl=51 time=6781 ms
64 bytes from 2001:6b0:19::165: icmp_seq=3 ttl=51 time=7805 ms
64 bytes from 2001:6b0:19::165: icmp_seq=2 ttl=51 time=8829 ms
64 bytes from 2001:6b0:19::165: icmp_seq=5 ttl=51 time=5757 ms
64 bytes from 2001:6b0:19::165: icmp_seq=1 ttl=51 time=9831 ms
64 bytes from 2001:6b0:19::165: icmp_seq=7 ttl=51 time=3709 ms
64 bytes from 2001:6b0:19::165: icmp_seq=9 ttl=51 time=1661 ms
64 bytes from 2001:6b0:19::165: icmp_seq=6 ttl=51 time=4733 ms
64 bytes from 2001:6b0:19::165: icmp_seq=8 ttl=51 time=2685 ms
--- 2001:6b0:19::165 ping statistics ---
10 packets transmitted, 9 received, 10% packet loss, time 9194ms
rtt min/avg/max/mdev = 1661.235/5754.782/9831.163/2640.158 ms, pipe 10
+ cat /proc/interrupts
+ head -n 1
CPU0 CPU1 CPU2 CPU3 CPU4 CPU5 CPU6 CPU7
+ cat /proc/interrupts
+ grep mhi
143: 0 0 0 0 0 6 0 188 PCI-MSI 4194304-edge bhi, mhi, mhi, mhi, mhi
+ date
mån 17 maj 2021 12:46:34 CEST
+ pkill journalctl
+ echo DONE
DONE
_______________________________________________ ModemManager-devel mailing list ModemManager-devel@lists.freedesktop.org https://lists.freedesktop.org/mailman/listinfo/modemmanager-devel
