Resume slow - how to debug

Questions about Grub, UEFI,the liveCD and the installer
Forum rules
Before you post please read how to get help
Post Reply
michaeldjcox
Level 1
Level 1
Posts: 43
Joined: Mon Feb 10, 2014 10:31 am

Resume slow - how to debug

Post by michaeldjcox »

I'm trying to debug why resuming on my laptop is so slow.

Its actually quicker to shutdown and restart than suspend and resume.

I suspect it something to do with device on the laptop,

Typically the login screen is presented instantly but then its about a minute before the fingerprint-gui appears.

If I try to just type in a password to login its says "Checking..." in the text field for that full minute.

It would be really great if I could see syslog in background to so I could montor in real time what the system is up to during that minute.

Is that possible?

gittiest personITW
Level 6
Level 6
Posts: 1182
Joined: Tue May 28, 2019 4:27 pm

Re: Resume slow - how to debug

Post by gittiest personITW »

You should know by now that you are unlikely to get help unless you give details about your system.
At the very least ------

Code: Select all

inxi -Fxz

michaeldjcox
Level 1
Level 1
Posts: 43
Joined: Mon Feb 10, 2014 10:31 am

Re: Resume slow - how to debug

Post by michaeldjcox »

Code: Select all

inxi -Fxz
System:    Host: nice Kernel: 5.0.0-37-generic x86_64 bits: 64 compiler: gcc v: 7.4.0 Desktop: Cinnamon 4.4.8 
           Distro: Linux Mint 19.3 Tricia base: Ubuntu 18.04 bionic 
Machine:   Type: Laptop System: LENOVO product: 20AQCTO1WW v: ThinkPad T440s serial: <filter> 
           Mobo: LENOVO model: 20AQCTO1WW v: 0B98405 STD serial: <filter> UEFI: LENOVO v: GJET67WW (2.17 ) date: 12/10/2013 
Battery:   ID-1: BAT0 charge: 15.9 Wh condition: 15.9/23.2 Wh (69%) model: SANYO 45N1773 status: Full 
           ID-2: BAT1 charge: 50.0 Wh condition: 64.4/71.3 Wh (90%) model: SANYO 45N1737 status: Discharging 
CPU:       Topology: Dual Core model: Intel Core i7-4600U bits: 64 type: MT MCP arch: Haswell rev: 1 L2 cache: 4096 KiB 
           flags: lm nx pae sse sse2 sse3 sse4_1 sse4_2 ssse3 vmx bogomips: 21549 
           Speed: 1196 MHz min/max: 800/3300 MHz Core speeds (MHz): 1: 1568 2: 1572 3: 1442 4: 1564 
Graphics:  Device-1: Intel Haswell-ULT Integrated Graphics vendor: Lenovo driver: i915 v: kernel bus ID: 00:02.0 
           Display: x11 server: X.Org 1.20.4 driver: modesetting unloaded: fbdev,vesa resolution: 1920x1080~60Hz 
           OpenGL: renderer: Mesa DRI Intel Haswell Mobile v: 4.5 Mesa 19.0.8 direct render: Yes 
Audio:     Device-1: Intel Haswell-ULT HD Audio vendor: Lenovo driver: snd_hda_intel v: kernel bus ID: 00:03.0 
           Device-2: Intel 8 Series HD Audio vendor: Lenovo driver: snd_hda_intel v: kernel bus ID: 00:1b.0 
           Sound Server: ALSA v: k5.0.0-37-generic 
Network:   Device-1: Intel Ethernet I218-LM vendor: Lenovo ThinkPad X240 driver: e1000e v: 3.2.6-k port: 3080 bus ID: 00:19.0 
           IF: enp0s25 state: down mac: <filter> 
           Device-2: Intel Wireless 7260 driver: iwlwifi v: kernel port: efa0 bus ID: 03:00.0 
           IF: wlp3s0 state: up mac: <filter> 
           IF-ID-1: wwp0s20u4i6 state: down mac: <filter> 
Drives:    Local Storage: total: 1.14 TiB used: 468.64 GiB (40.1%) 
           ID-1: /dev/sda vendor: Samsung model: MZ7TD256HAFV-000L9 size: 238.47 GiB 
           ID-2: /dev/sdb type: USB vendor: Western Digital model: WD10TMVV-11BG7S0 size: 931.49 GiB 
Partition: ID-1: / size: 27.38 GiB used: 6.99 GiB (25.5%) fs: ext4 dev: /dev/sda2 
           ID-2: /home size: 191.07 GiB used: 8.74 GiB (4.6%) fs: ext4 dev: /dev/sda3 
           ID-3: swap-1 size: 14.90 GiB used: 0 KiB (0.0%) fs: swap dev: /dev/sda4 
Sensors:   System Temperatures: cpu: 56.0 C mobo: N/A 
           Fan Speeds (RPM): cpu: 2479 
Info:      Processes: 234 Uptime: 8h 48m Memory: 7.67 GiB used: 2.43 GiB (31.7%) Init: systemd runlevel: 5 Compilers: 
           gcc: 7.4.0 Shell: bash v: 4.4.20 inxi: 3.0.32 


michaeldjcox
Level 1
Level 1
Posts: 43
Joined: Mon Feb 10, 2014 10:31 am

Re: Resume slow - how to debug

Post by michaeldjcox »

And for good measure as I see some devices missing from that.

I suspect from my look into syslog that it is probbaly something to do with devices. I suspect the fingerprint reader or mobile network

Code: Select all

michael@nice:/var/log$ lsusb
Bus 001 Device 002: ID 8087:8000 Intel Corp. 
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 003 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 002 Device 005: ID 5986:026a Acer, Inc 
Bus 002 Device 006: ID 8087:07dc Intel Corp. 
Bus 002 Device 003: ID 138a:0017 Validity Sensors, Inc. Fingerprint Reader
Bus 002 Device 014: ID 0bdb:193e Ericsson Business Mobile Networks BV 
Bus 002 Device 015: ID 1058:071a Western Digital Technologies, Inc. My Passport Essential (WDBAAA)
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
and

Code: Select all

michael@nice:/var/log$ lspci
00:00.0 Host bridge: Intel Corporation Haswell-ULT DRAM Controller (rev 0b)
00:02.0 VGA compatible controller: Intel Corporation Haswell-ULT Integrated Graphics Controller (rev 0b)
00:03.0 Audio device: Intel Corporation Haswell-ULT HD Audio Controller (rev 0b)
00:14.0 USB controller: Intel Corporation 8 Series USB xHCI HC (rev 04)
00:16.0 Communication controller: Intel Corporation 8 Series HECI #0 (rev 04)
00:16.3 Serial controller: Intel Corporation 8 Series HECI KT (rev 04)
00:19.0 Ethernet controller: Intel Corporation Ethernet Connection I218-LM (rev 04)
00:1b.0 Audio device: Intel Corporation 8 Series HD Audio Controller (rev 04)
00:1c.0 PCI bridge: Intel Corporation 8 Series PCI Express Root Port 6 (rev e4)
00:1c.1 PCI bridge: Intel Corporation 8 Series PCI Express Root Port 3 (rev e4)
00:1d.0 USB controller: Intel Corporation 8 Series USB EHCI #1 (rev 04)
00:1f.0 ISA bridge: Intel Corporation 8 Series LPC Controller (rev 04)
00:1f.2 SATA controller: Intel Corporation 8 Series SATA Controller 1 [AHCI mode] (rev 04)
00:1f.3 SMBus: Intel Corporation 8 Series SMBus Controller (rev 04)
02:00.0 Unassigned class [ff00]: Realtek Semiconductor Co., Ltd. RTS5227 PCI Express Card Reader (rev 01)
03:00.0 Network controller: Intel Corporation Wireless 7260 (rev 83)

michaeldjcox
Level 1
Level 1
Posts: 43
Joined: Mon Feb 10, 2014 10:31 am

Re: Resume slow - how to debug

Post by michaeldjcox »

Heres a sample of the syslog on resume.

There is a whole 50seconds lost with no entries at Jan 16 17:09:15

Code: Select all

Jan 16 17:09:13 nice systemd-resolved[714]: Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.
Jan 16 17:09:13 nice systemd-resolved[714]: Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.
Jan 16 17:09:14 nice NetworkManager[828]: <info>  [1579194554.0216] connectivity: (wlp3s0) response shorter than expected 'NetworkManager is online'; assuming captive portal.
Jan 16 17:09:14 nice nm-dispatcher: req:4 'connectivity-change': new request (1 scripts)
Jan 16 17:09:14 nice nm-dispatcher: req:4 'connectivity-change': start running ordered scripts...
Jan 16 17:09:15 nice systemd-resolved[714]: Using degraded feature set (UDP) for DNS server 10.101.2.1.
Jan 16 17:10:05 nice kernel: [ 3266.835525] usbhid 2-4:1.0: can't add hid device: -110
Jan 16 17:10:05 nice kernel: [ 3266.835549] usbhid: probe of 2-4:1.0 failed with error -110
Jan 16 17:10:05 nice kernel: [ 3266.835981] usb 2-4: USB disconnect, device number 13
Jan 16 17:10:05 nice kernel: [ 3267.107384] usb 2-4: new high-speed USB device number 14 using xhci_hcd
Jan 16 17:10:05 nice kernel: [ 3267.260335] usb 2-4: New USB device found, idVendor=0bdb, idProduct=193e, bcdDevice= 0.00
Jan 16 17:10:05 nice kernel: [ 3267.260343] usb 2-4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 16 17:10:05 nice kernel: [ 3267.260347] usb 2-4: Product: N5321 gw
Jan 16 17:10:05 nice kernel: [ 3267.260351] usb 2-4: Manufacturer: Lenovo
Jan 16 17:10:05 nice kernel: [ 3267.260354] usb 2-4: SerialNumber: 44E937E3B0D02BM0
Jan 16 17:10:05 nice kernel: [ 3267.297231] cdc_acm 2-4:1.1: ttyACM0: USB ACM device
Jan 16 17:10:05 nice kernel: [ 3267.298095] cdc_acm 2-4:1.3: ttyACM1: USB ACM device
Jan 16 17:10:05 nice kernel: [ 3267.301516] cdc_wdm 2-4:1.5: cdc-wdm0: USB WDM device
Jan 16 17:10:05 nice kernel: [ 3267.340764] cdc_mbim 2-4:1.6: cdc-wdm1: USB WDM device
Jan 16 17:10:05 nice kernel: [ 3267.341590] cdc_mbim 2-4:1.6 wwan0: register 'cdc_mbim' at usb-0000:00:14.0-4, CDC MBIM, 2a:03:58:9c:b8:8b
Jan 16 17:10:05 nice kernel: [ 3267.343111] cdc_wdm 2-4:1.8: cdc-wdm2: USB WDM device
Jan 16 17:10:05 nice upowerd[1058]: unhandled action 'bind' on /sys/devices/pci0000:00/0000:00:14.0/usb2/2-7/2-7:1.1
another 50 seconds here:

Code: Select all

Jan 16 17:10:47 nice nm-dispatcher: req:1 'connectivity-change': start running ordered scripts...
Jan 16 17:11:35 nice kernel: [ 3357.149406] usb 2-1: new high-speed USB device number 15 using xhci_hcd

:

michaeldjcox
Level 1
Level 1
Posts: 43
Joined: Mon Feb 10, 2014 10:31 am

Re: Resume slow - how to debug

Post by michaeldjcox »

Ok I think suspending while connected to mobile internet caused the slowness for one of those periods.

Much quicker if I disconnect from the internet first

michaeldjcox
Level 1
Level 1
Posts: 43
Joined: Mon Feb 10, 2014 10:31 am

Re: Resume slow - how to debug

Post by michaeldjcox »

The answer to my original question - how to debug it.


1. Do ctrl-altF1 to flip from GUI to terminal
2. Login
3. tail -100f /var/log/syslog &
4. systemctl suspend
5. Resume from suspend hitting the power button or whatever it is you do
6. It will come back in terminal mode tailing the syslog
7. Its ctrl-alt-F7 to get back to the GUI

You can then see where resume progress falters.

For me its the Ericcson N5321gw mobile broadband that seems to cause the issues and fingerprint reader

Post Reply

Return to “Installation & Boot”