195463 – Acer E5-575-53EJ laptop: ubuntu boots with all wifi adapters in "device not ready" status

Bug 195463 - Acer E5-575-53EJ laptop: ubuntu boots with all wifi adapters in "device not ready" status

Summary: Acer E5-575-53EJ laptop: ubuntu boots with all wifi adapters in "device not ...

Status:	NEW

Alias:	None

Product:	Networking
Classification:	Unclassified
Component:	Wireless (show other bugs)
Hardware:	Intel Linux

Importance:	P1 normal
Assignee:	networking_wireless@kernel-bugs.osdl.org

URL:
Keywords:

Duplicates (1):	195461 (view as bug list)
Depends on:
Blocks:

Reported:	2017-04-19 12:24 UTC by DX
Modified:	2017-08-08 20:46 UTC (History)
CC List:	1 user (show)

See Also:
Kernel Version:	4.11rc7
Subsystem:
Regression:	No
Bisected commit-id:

Attachments
Add an attachment (proposed patch, testcase, etc.)

Description DX 2017-04-19 12:24:22 UTC

This is a further development of a bug I have filed at ubuntu:
https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1683577

Same problem is observed with Ubuntu's 4.11rc7 kernel build.
http://kernel.ubuntu.com/~kernel-ppa/mainline/v4.11-rc7/
  linux-headers-4.11.0-041100rc7-generic_4.11.0-041100rc7.201704161731_amd64.deb
  linux-image-4.11.0-041100rc7-generic_4.11.0-041100rc7.201704161731_amd64.deb

Two wifi adapters exists on this machine:
Internal QCA9377 and external ralink rt2800usb-based USB wifi adapter.

After boot, network-manager show both adapter in "deice not ready" status.

Restarting network-manager using "sudo systemctl restart network-manager.service" brings back both wifi adapters to normal status and connections are made. Seconds later, Ubuntu choose to disconnect one of the adapters.

Comment 1 DX 2017-04-19 12:26:58 UTC

*** Bug 195461 has been marked as a duplicate of this bug. ***

Comment 2 DX 2017-04-19 12:30:59 UTC

 1775.747903] Suspended for 3.527 seconds
[ 1775.747943] Enabling non-boot CPUs ...
[ 1775.758986] x86: Booting SMP configuration:
[ 1775.758987] smpboot: Booting Node 0 Processor 1 APIC 0x2
[ 1775.759361]  cache: parent cpu1 should not be sleeping
[ 1775.759517] CPU1 is up
[ 1775.771277] smpboot: Booting Node 0 Processor 2 APIC 0x1
[ 1775.772166]  cache: parent cpu2 should not be sleeping
[ 1775.772514] CPU2 is up
[ 1775.787459] smpboot: Booting Node 0 Processor 3 APIC 0x3
[ 1775.788363]  cache: parent cpu3 should not be sleeping
[ 1775.788758] CPU3 is up
[ 1775.795225] ACPI: Waking up from system sleep state S3
[ 1775.818946] acpi LNXPOWER:00: Turning OFF
[ 1775.819226] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
[ 1775.819262] ACPI : EC: interrupt unblocked
[ 1775.858976] PM: noirq resume of devices complete after 39.917 msecs
[ 1775.872600] PM: early resume of devices complete after 13.497 msecs
[ 1775.872829] ACPI : EC: event unblocked
[ 1775.873258] pcieport 0000:00:1d.3: System wakeup disabled by ACPI
[ 1775.873366] rtc_cmos 00:03: System wakeup disabled by ACPI
[ 1775.875387] sd 2:0:0:0: [sda] Starting disk
[ 1775.875595] acer_wmi: Unknown key number - 0x84
[ 1775.880810] [drm] GuC firmware load skipped
[ 1775.893052] r8169 0000:03:00.1 enp3s0f1: link down
[ 1775.938208] xhci_hcd 0000:00:14.0: port 4 resume PLC timeout
[ 1776.175063] usb 1-7: reset high-speed USB device number 4 using xhci_hcd
[ 1776.190612] ata2: SATA link down (SStatus 4 SControl 300)
[ 1776.190725] ata3: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[ 1776.190752] ata1: SATA link down (SStatus 4 SControl 300)
[ 1776.191187] ata3.00: configured for UDMA/133
[ 1776.435190] usb 1-5: reset full-speed USB device number 3 using xhci_hcd
[ 1776.604756] PM: resume of devices complete after 732.164 msecs
[ 1776.604840] usb 1-5:1.0: rebind failed: -517
[ 1776.604842] usb 1-5:1.1: rebind failed: -517
[ 1776.605085] PM: Finishing wakeup.
[ 1776.605086] Restarting tasks ... 
[ 1776.606455] [drm] RC6 on
[ 1776.619613] done.
[ 1776.693133] IPv6: ADDRCONF(NETDEV_UP): enp3s0f1: link is not ready
[ 1776.716823] r8169 0000:03:00.1 enp3s0f1: link down
[ 1776.716908] IPv6: ADDRCONF(NETDEV_UP): enp3s0f1: link is not ready
[ 1776.717661] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
[ 1777.062652] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
[ 1777.477312] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
[ 1777.479139] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
[ 1779.413117] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
[ 1779.862445] r8169 0000:03:00.1 enp3s0f1: link up
[ 1781.347919] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
[ 1781.347944] IPv6: ADDRCONF(NETDEV_CHANGE): enp3s0f1: link becomes ready
[ 1781.540977] systemd[1]: apt-daily.timer: Adding 10h 35min 27.094540s random time.
[ 1781.542294] systemd[1]: snapd.refresh.timer: Adding 17min 53.628889s random time.
[ 1783.563941] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
[ 1785.524984] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
[ 1785.539375] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
[ 1785.616595] systemd[1]: apt-daily.timer: Adding 5h 31min 39.246825s random time.
[ 1785.617842] systemd[1]: snapd.refresh.timer: Adding 4h 27min 37.326694s random time.
[ 1785.888602] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
[ 1786.300726] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
[ 1786.309862] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
[ 1788.249305] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
[ 1788.340589] r8169 0000:03:00.1 enp3s0f1: link down
[ 1788.340650] r8169 0000:03:00.1 enp3s0f1: link down
[ 1788.340662] IPv6: ADDRCONF(NETDEV_UP): enp3s0f1: link is not ready
[ 1788.432385] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
[ 1788.441619] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
[ 1788.468563] r8169 0000:03:00.1 enp3s0f1: link down
[ 1788.468633] IPv6: ADDRCONF(NETDEV_UP): enp3s0f1: link is not ready
[ 1788.588574] ath10k_pci 0000:02:00.0: no channel configured; ignoring frame(s)!
[ 1788.691390] ath10k_pci 0000:02:00.0: no channel configured; ignoring frame(s)!
[ 1788.742944] ath10k_pci 0000:02:00.0: no channel configured; ignoring frame(s)!
[ 1788.794349] ath10k_pci 0000:02:00.0: no channel configured; ignoring frame(s)!
[ 1790.156791] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
[ 1790.230770] wlxe84e0604557e: authenticate with e8:89:2c:f8:96:90
[ 1790.245711] wlxe84e0604557e: send auth to e8:89:2c:f8:96:90 (try 1/3)
[ 1790.247282] wlxe84e0604557e: authenticated
[ 1790.272670] systemd[1]: apt-daily.timer: Adding 2h 20min 41.750701s random time.
[ 1790.274554] systemd[1]: snapd.refresh.timer: Adding 1h 10min 1.022527s random time.
[ 1790.391868] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
[ 1790.452893] ath10k_pci 0000:02:00.0: no channel configured; ignoring frame(s)!
[ 1790.499566] kauditd_printk_skb: 28 callbacks suppressed
[ 1790.499569] audit: type=1400 audit(1492604910.748:39): apparmor="DENIED" operation="open" profile="/sbin/dhclient" name="/var/lib/wicd/dhclient.conf" pid=5445 comm="dhclient" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
[ 1790.577122] systemd[1]: apt-daily.timer: Adding 4h 13min 18.030342s random time.
[ 1790.578439] systemd[1]: snapd.refresh.timer: Adding 5h 4min 5.629766s random time.
[ 1790.984646] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
[ 1791.084718] r8169 0000:03:00.1 enp3s0f1: link down
[ 1791.085018] IPv6: ADDRCONF(NETDEV_UP): enp3s0f1: link is not ready
[ 1794.026652] r8169 0000:03:00.1 enp3s0f1: link up
[ 1794.026674] IPv6: ADDRCONF(NETDEV_CHANGE): enp3s0f1: link becomes ready

Comment 3 DX 2017-04-19 12:32:22 UTC

After wifi is back by restarting network-manager, a sleep/wakeup cycle is attempted. After this, both wifi adapters enters "Device not ready" status again

(In reply to DX from comment #2)
>  1775.747903] Suspended for 3.527 seconds
> [ 1775.747943] Enabling non-boot CPUs ...
> [ 1775.758986] x86: Booting SMP configuration:
> [ 1775.758987] smpboot: Booting Node 0 Processor 1 APIC 0x2
> [ 1775.759361]  cache: parent cpu1 should not be sleeping
> [ 1775.759517] CPU1 is up
> [ 1775.771277] smpboot: Booting Node 0 Processor 2 APIC 0x1
> [ 1775.772166]  cache: parent cpu2 should not be sleeping
> [ 1775.772514] CPU2 is up
> [ 1775.787459] smpboot: Booting Node 0 Processor 3 APIC 0x3
> [ 1775.788363]  cache: parent cpu3 should not be sleeping
> [ 1775.788758] CPU3 is up
> [ 1775.795225] ACPI: Waking up from system sleep state S3
> [ 1775.818946] acpi LNXPOWER:00: Turning OFF
> [ 1775.819226] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
> [ 1775.819262] ACPI : EC: interrupt unblocked
> [ 1775.858976] PM: noirq resume of devices complete after 39.917 msecs
> [ 1775.872600] PM: early resume of devices complete after 13.497 msecs
> [ 1775.872829] ACPI : EC: event unblocked
> [ 1775.873258] pcieport 0000:00:1d.3: System wakeup disabled by ACPI
> [ 1775.873366] rtc_cmos 00:03: System wakeup disabled by ACPI
> [ 1775.875387] sd 2:0:0:0: [sda] Starting disk
> [ 1775.875595] acer_wmi: Unknown key number - 0x84
> [ 1775.880810] [drm] GuC firmware load skipped
> [ 1775.893052] r8169 0000:03:00.1 enp3s0f1: link down
> [ 1775.938208] xhci_hcd 0000:00:14.0: port 4 resume PLC timeout
> [ 1776.175063] usb 1-7: reset high-speed USB device number 4 using xhci_hcd
> [ 1776.190612] ata2: SATA link down (SStatus 4 SControl 300)
> [ 1776.190725] ata3: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
> [ 1776.190752] ata1: SATA link down (SStatus 4 SControl 300)
> [ 1776.191187] ata3.00: configured for UDMA/133
> [ 1776.435190] usb 1-5: reset full-speed USB device number 3 using xhci_hcd
> [ 1776.604756] PM: resume of devices complete after 732.164 msecs
> [ 1776.604840] usb 1-5:1.0: rebind failed: -517
> [ 1776.604842] usb 1-5:1.1: rebind failed: -517
> [ 1776.605085] PM: Finishing wakeup.
> [ 1776.605086] Restarting tasks ... 
> [ 1776.606455] [drm] RC6 on
> [ 1776.619613] done.
> [ 1776.693133] IPv6: ADDRCONF(NETDEV_UP): enp3s0f1: link is not ready
> [ 1776.716823] r8169 0000:03:00.1 enp3s0f1: link down
> [ 1776.716908] IPv6: ADDRCONF(NETDEV_UP): enp3s0f1: link is not ready
> [ 1776.717661] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
> [ 1777.062652] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
> [ 1777.477312] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
> [ 1777.479139] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
> [ 1779.413117] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
> [ 1779.862445] r8169 0000:03:00.1 enp3s0f1: link up
> [ 1781.347919] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
> [ 1781.347944] IPv6: ADDRCONF(NETDEV_CHANGE): enp3s0f1: link becomes ready
> [ 1781.540977] systemd[1]: apt-daily.timer: Adding 10h 35min 27.094540s
> random time.
> [ 1781.542294] systemd[1]: snapd.refresh.timer: Adding 17min 53.628889s
> random time.
> [ 1783.563941] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
> [ 1785.524984] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
> [ 1785.539375] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
> [ 1785.616595] systemd[1]: apt-daily.timer: Adding 5h 31min 39.246825s
> random time.
> [ 1785.617842] systemd[1]: snapd.refresh.timer: Adding 4h 27min 37.326694s
> random time.
> [ 1785.888602] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
> [ 1786.300726] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
> [ 1786.309862] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
> [ 1788.249305] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
> [ 1788.340589] r8169 0000:03:00.1 enp3s0f1: link down
> [ 1788.340650] r8169 0000:03:00.1 enp3s0f1: link down
> [ 1788.340662] IPv6: ADDRCONF(NETDEV_UP): enp3s0f1: link is not ready
> [ 1788.432385] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
> [ 1788.441619] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
> [ 1788.468563] r8169 0000:03:00.1 enp3s0f1: link down
> [ 1788.468633] IPv6: ADDRCONF(NETDEV_UP): enp3s0f1: link is not ready
> [ 1788.588574] ath10k_pci 0000:02:00.0: no channel configured; ignoring
> frame(s)!
> [ 1788.691390] ath10k_pci 0000:02:00.0: no channel configured; ignoring
> frame(s)!
> [ 1788.742944] ath10k_pci 0000:02:00.0: no channel configured; ignoring
> frame(s)!
> [ 1788.794349] ath10k_pci 0000:02:00.0: no channel configured; ignoring
> frame(s)!
> [ 1790.156791] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
> [ 1790.230770] wlxe84e0604557e: authenticate with e8:89:2c:f8:96:90
> [ 1790.245711] wlxe84e0604557e: send auth to e8:89:2c:f8:96:90 (try 1/3)
> [ 1790.247282] wlxe84e0604557e: authenticated
> [ 1790.272670] systemd[1]: apt-daily.timer: Adding 2h 20min 41.750701s
> random time.
> [ 1790.274554] systemd[1]: snapd.refresh.timer: Adding 1h 10min 1.022527s
> random time.
> [ 1790.391868] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
> [ 1790.452893] ath10k_pci 0000:02:00.0: no channel configured; ignoring
> frame(s)!
> [ 1790.499566] kauditd_printk_skb: 28 callbacks suppressed
> [ 1790.499569] audit: type=1400 audit(1492604910.748:39): apparmor="DENIED"
> operation="open" profile="/sbin/dhclient" name="/var/lib/wicd/dhclient.conf"
> pid=5445 comm="dhclient" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
> [ 1790.577122] systemd[1]: apt-daily.timer: Adding 4h 13min 18.030342s
> random time.
> [ 1790.578439] systemd[1]: snapd.refresh.timer: Adding 5h 4min 5.629766s
> random time.
> [ 1790.984646] IPv6: ADDRCONF(NETDEV_UP): wlxe84e0604557e: link is not ready
> [ 1791.084718] r8169 0000:03:00.1 enp3s0f1: link down
> [ 1791.085018] IPv6: ADDRCONF(NETDEV_UP): enp3s0f1: link is not ready
> [ 1794.026652] r8169 0000:03:00.1 enp3s0f1: link up
> [ 1794.026674] IPv6: ADDRCONF(NETDEV_CHANGE): enp3s0f1: link becomes ready

Comment 4 mirh 2017-08-08 20:46:25 UTC

Could you try to disable ipv6?
I have heard it improves situation for QCA9377

Note You need to log in before you can comment on or make changes to this bug.