PinePhone immediately wakes up after entering deep sleep
Since a recent-ish update on the unstable
branch my PinePhone has been constantly waking up the moment it enters suspend. Log attached. I tried switching branches to stable
and downgrading all the packages, but the issue is still there. Haven't tried reflashing yet, but I'm hoping to avoid it.
Whenever it wakes up it also turns on the screen. It then needs two power button presses to turn it off again. This basically means I wake up to a dead phone every day. Can I provide any more logs or information to aid debugging?
May 01 23:24:08 manjaro-arm NetworkManager[3415]: <info> [1619904248.2903] manager: sleep: sleep requested (sleeping: no enabled: yes)
May 01 23:24:08 manjaro-arm NetworkManager[3415]: <info> [1619904248.2909] device (p2p-dev-wlan0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
May 01 23:24:08 manjaro-arm eg25manager[3417]: system is about to suspend
May 01 23:24:08 manjaro-arm eg25manager[3417]: Sending command: AT+QGPSEND
May 01 23:24:08 manjaro-arm NetworkManager[3415]: <info> [1619904248.2959] manager: NetworkManager state is now ASLEEP
May 01 23:24:08 manjaro-arm NetworkManager[3415]: <info> [1619904248.2975] device (wlan0): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
May 01 23:24:08 manjaro-arm eg25manager[3417]: Response: [OK]
May 01 23:24:08 manjaro-arm eg25manager[3417]: Executed suspend sequence
May 01 23:24:08 manjaro-arm eg25manager[3417]: suspend sequence is over, drop inhibitor
May 01 23:24:08 manjaro-arm eg25manager[3417]: dropping systemd sleep delay inhibitor
May 01 23:24:08 manjaro-arm NetworkManager[3415]: <info> [1619904248.3127] device (cdc-wdm0): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
May 01 23:24:08 manjaro-arm systemd-udevd[4534]: blue:indicator: Process '/usr/libexec/fbd-ledctrl -p /sys/devices/platform/leds/leds/blue:indicator -t pattern -G video' failed with exit code 1.
May 01 23:24:08 manjaro-arm dbus-daemon[3407]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.7' (uid=0 pid=3415 comm="/usr/bin/NetworkManager --no-daemon ")
May 01 23:24:08 manjaro-arm systemd-udevd[4534]: blue:indicator: Process '/usr/libexec/fbd-ledctrl -p /sys/devices/platform/leds/leds/blue:indicator -t pattern -G video' failed with exit code 1.
May 01 23:24:08 manjaro-arm systemd[1]: Starting Network Manager Script Dispatcher Service...
May 01 23:24:08 manjaro-arm dbus-daemon[3407]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
May 01 23:24:08 manjaro-arm systemd[1]: Started Network Manager Script Dispatcher Service.
May 01 23:24:08 manjaro-arm wpa_supplicant[3866]: wlan0: CTRL-EVENT-DISCONNECTED bssid=9c:5c:8e:b9:0f:78 reason=3 locally_generated=1
May 01 23:24:08 manjaro-arm PackageKit[4456]: daemon quit
May 01 23:24:08 manjaro-arm systemd-networkd[3404]: wwan0: Link DOWN
May 01 23:24:08 manjaro-arm systemd-networkd[3404]: wwan0: Lost carrier
May 01 23:24:08 manjaro-arm systemd-networkd[3404]: wlan0: Lost carrier
May 01 23:24:08 manjaro-arm systemd-timesyncd[3391]: No network connectivity, watching for changes.
May 01 23:24:08 manjaro-arm wpa_supplicant[3866]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
May 01 23:24:08 manjaro-arm ModemManager[3825]: <info> [modem0] state changed (connected -> disconnecting)
May 01 23:24:08 manjaro-arm NetworkManager[3415]: <info> [1619904248.5875] device (wlan0): supplicant interface state: completed -> disconnected
May 01 23:24:08 manjaro-arm NetworkManager[3415]: <info> [1619904248.5897] device (wlan0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
May 01 23:24:08 manjaro-arm ModemManager[3825]: <info> [modem0] state changed (disconnecting -> registered)
May 01 23:24:08 manjaro-arm ModemManager[3825]: <info> [modem0/bearer1] connection #1 finished: duration 334s, tx: 864 bytes, rx :974 bytes
May 01 23:24:08 manjaro-arm NetworkManager[3415]: <info> [1619904248.6298] dhcp4 (wlan0): canceled DHCP transaction
May 01 23:24:08 manjaro-arm NetworkManager[3415]: <info> [1619904248.6300] dhcp4 (wlan0): state changed bound -> done
May 01 23:24:08 manjaro-arm NetworkManager[3415]: <info> [1619904248.6796] modem["cdc-wdm0"]: modem state changed, 'connected' --> 'disconnecting' (reason: user-requested)
May 01 23:24:08 manjaro-arm NetworkManager[3415]: <info> [1619904248.6802] modem["cdc-wdm0"]: modem state changed, 'disconnecting' --> 'registered' (reason: user-requested)
May 01 23:24:08 manjaro-arm NetworkManager[3415]: <info> [1619904248.6812] device (wlan0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
May 01 23:24:08 manjaro-arm systemd-networkd[3404]: wlan0: Link DOWN
May 01 23:24:08 manjaro-arm NetworkManager[3415]: <info> [1619904248.8420] device (cdc-wdm0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
May 01 23:24:08 manjaro-arm NetworkManager[3415]: <info> [1619904248.8691] device (cdc-wdm0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
May 01 23:24:08 manjaro-arm wpa_supplicant[3866]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
May 01 23:24:08 manjaro-arm systemd[1]: Reached target Sleep.
May 01 23:24:08 manjaro-arm systemd[1]: Starting Suspend...
May 01 23:24:09 manjaro-arm systemd[1]: packagekit.service: Deactivated successfully.
May 01 23:24:09 manjaro-arm systemd[1]: packagekit.service: Consumed 3.791s CPU time.
May 01 23:24:09 manjaro-arm systemd-sleep[4556]: Suspending system...
May 01 23:24:09 manjaro-arm kernel: PM: suspend entry (deep)
May 01 23:24:27 manjaro-arm kernel: Filesystems sync: 0.153 seconds
May 01 23:24:27 manjaro-arm kernel: Freezing user space processes ... (elapsed 0.003 seconds) done.
May 01 23:24:27 manjaro-arm kernel: OOM killer disabled.
May 01 23:24:27 manjaro-arm kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
May 01 23:24:27 manjaro-arm kernel: printk: Suspending console(s) (use no_console_suspend to debug)
May 01 23:24:27 manjaro-arm kernel: musb-sunxi 1c19000.usb: Error unknown readb offset 112
May 01 23:24:27 manjaro-arm kernel: Disabling non-boot CPUs ...
May 01 23:24:27 manjaro-arm kernel: CPU1: shutdown
May 01 23:24:27 manjaro-arm kernel: psci: CPU1 killed (polled 0 ms)
May 01 23:24:27 manjaro-arm kernel: CPU2: shutdown
May 01 23:24:27 manjaro-arm kernel: psci: CPU2 killed (polled 4 ms)
May 01 23:24:27 manjaro-arm kernel: CPU3: shutdown
May 01 23:24:27 manjaro-arm kernel: psci: CPU3 killed (polled 4 ms)
May 01 23:24:27 manjaro-arm kernel: Enabling non-boot CPUs ...
May 01 23:24:27 manjaro-arm kernel: Detected VIPT I-cache on CPU1
May 01 23:24:27 manjaro-arm kernel: arch_timer: CPU1: Trapping CNTVCT access
May 01 23:24:27 manjaro-arm kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
May 01 23:24:27 manjaro-arm kernel: CPU1 is up
May 01 23:24:27 manjaro-arm kernel: Detected VIPT I-cache on CPU2
May 01 23:24:27 manjaro-arm kernel: arch_timer: CPU2: Trapping CNTVCT access
May 01 23:24:27 manjaro-arm kernel: CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
May 01 23:24:27 manjaro-arm kernel: CPU2 is up
May 01 23:24:27 manjaro-arm kernel: Detected VIPT I-cache on CPU3
May 01 23:24:27 manjaro-arm kernel: arch_timer: CPU3: Trapping CNTVCT access
May 01 23:24:27 manjaro-arm kernel: CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
May 01 23:24:27 manjaro-arm kernel: CPU3 is up
May 01 23:24:27 manjaro-arm kernel: sunxi-rsb 1f03400.rsb: RSB running at 4000000 Hz
May 01 23:24:27 manjaro-arm kernel: musb-sunxi 1c19000.usb: Error unknown writeb offset 112
May 01 23:24:27 manjaro-arm kernel: OOM killer enabled.
May 01 23:24:27 manjaro-arm kernel: Restarting tasks ... done.
May 01 23:24:27 manjaro-arm kernel: PM: suspend exit
May 01 23:24:27 manjaro-arm systemd-sleep[4556]: System resumed.
May 01 23:24:27 manjaro-arm systemd[1]: systemd-suspend.service: Deactivated successfully.
May 01 23:24:27 manjaro-arm systemd[1]: Finished Suspend.
May 01 23:24:27 manjaro-arm systemd[1]: Stopped target Sleep.
May 01 23:24:27 manjaro-arm systemd[1]: Reached target Suspend.
May 01 23:24:27 manjaro-arm systemd[1]: Stopped target Suspend.
May 01 23:24:27 manjaro-arm systemd-logind[3422]: Operation 'sleep' finished.
May 01 23:24:27 manjaro-arm systemd-networkd[3404]: lo: Reset carrier
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.1813] manager: sleep: wake requested (sleeping: yes enabled: yes)
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.1818] device (wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
May 01 23:24:27 manjaro-arm systemd-networkd[3404]: wlan0: Link UP
May 01 23:24:27 manjaro-arm eg25manager[3417]: system is resuming
May 01 23:24:27 manjaro-arm eg25manager[3417]: taking systemd sleep inhibitor
May 01 23:24:27 manjaro-arm eg25manager[3417]: Executed resume sequence
May 01 23:24:27 manjaro-arm eg25manager[3417]: Sending command: AT+QGPS=1
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.1918] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.1945] device (cdc-wdm0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.1970] device (cdc-wdm0): modem state 'registered'
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.2024] manager: NetworkManager state is now CONNECTED_LOCAL
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.2040] device (cdc-wdm0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.2068] policy: auto-activating connection 'play' (992da998-c4a9-4992-92b3-db3a3a934006)
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.2137] device (cdc-wdm0): Activation: starting connection 'play' (992da998-c4a9-4992-92b3-db3a3a934006)
May 01 23:24:27 manjaro-arm wpa_supplicant[3866]: nl80211: kernel reports: Authentication algorithm number required
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.2194] device (cdc-wdm0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.2218] manager: NetworkManager state is now CONNECTING
May 01 23:24:27 manjaro-arm eg25manager[3417]: inhibitor sleep fd is 20
May 01 23:24:27 manjaro-arm ModemManager[3825]: <info> [modem0] simple connect started...
May 01 23:24:27 manjaro-arm ModemManager[3825]: <info> [modem0] simple connect state (4/8): wait to get fully enabled
May 01 23:24:27 manjaro-arm ModemManager[3825]: <info> [modem0] simple connect state (5/8): register
May 01 23:24:27 manjaro-arm ModemManager[3825]: <info> [modem0] simple connect state (6/8): bearer
May 01 23:24:27 manjaro-arm ModemManager[3825]: <info> [modem0] simple connect state (7/8): connect
May 01 23:24:27 manjaro-arm ModemManager[3825]: <info> [modem0] state changed (registered -> connecting)
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.3672] device (wlan0): supplicant interface state: internal-starting -> disconnected
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.3675] device (p2p-dev-wlan0): state change: unavailable -> unmanaged (reason 'removed', sys-iface-state: 'removed')
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.3707] Wi-Fi P2P device controlled by interface wlan0 created
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.3723] manager: (p2p-dev-wlan0): new 802.11 Wi-Fi P2P device (/org/freedesktop/NetworkManager/Devices/6)
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.3768] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.3835] modem["cdc-wdm0"]: modem state changed, 'registered' --> 'connecting' (reason: user-requested)
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.3865] device (wlan0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed')
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <info> [1619904267.4101] device (p2p-dev-wlan0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
May 01 23:24:27 manjaro-arm phoc[3788]: [backend/drm/drm.c:971] Skipping pageflip on output 'DSI-1'
May 01 23:24:27 manjaro-arm NetworkManager[3415]: <warn> [1619904267.4816] sup-iface[b176a135c2642f54,1,wlan0]: call-p2p-cancel: failed with P2P cancel failed
May 01 23:24:27 manjaro-arm gsd-xsettings[4171]: Failed to get current UI legacy scaling factor
May 01 23:24:27 manjaro-arm gsd-xsettings[4171]: Failed to get current UI legacy scaling factor
May 01 23:24:27 manjaro-arm gsd-color[4155]: unable to get EDID for xrandr-DSI-1: unable to get EDID for output
May 01 23:24:27 manjaro-arm ModemManager[3825]: <info> [modem0/bearer1] QMI IPv4 Settings:
May 01 23:24:27 manjaro-arm ModemManager[3825]: <info> [modem0/bearer1] address: 100.84.233.168/28
May 01 23:24:27 manjaro-arm ModemManager[3825]: <info> [modem0/bearer1] gateway: 100.84.233.169
May 01 23:24:27 manjaro-arm ModemManager[3825]: <info> [modem0/bearer1] DNS #1: 89.108.202.20
May 01 23:24:27 manjaro-arm ModemManager[3825]: <info> [modem0/bearer1] DNS #2: 185.89.185.1
May 01 23:24:27 manjaro-arm ModemManager[3825]: <info> [modem0/bearer1] MTU: 1500
May 01 23:24:28 manjaro-arm ModemManager[3825]: <info> [modem0/bearer1] couldn't start network: QMI protocol error (14): 'CallFailed'
May 01 23:24:28 manjaro-arm ModemManager[3825]: <info> [modem0/bearer1] call end reason (1018): gsm-wcdma-option-unsubscribed
May 01 23:24:28 manjaro-arm ModemManager[3825]: <info> [modem0/bearer1] verbose call end reason (6,33): [3gpp] option-unsubscribed
May 01 23:24:28 manjaro-arm ModemManager[3825]: <info> [modem0] state changed (connecting -> connected)
May 01 23:24:28 manjaro-arm ModemManager[3825]: <info> [modem0] simple connect state (8/8): all done
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.0328] modem["cdc-wdm0"]: modem state changed, 'connecting' --> 'connected' (reason: user-requested)
May 01 23:24:28 manjaro-arm systemd-networkd[3404]: wwan0: Link UP
May 01 23:24:28 manjaro-arm systemd-networkd[3404]: wwan0: Gained carrier
May 01 23:24:28 manjaro-arm systemd-timesyncd[3391]: Network configuration changed, trying to establish connection.
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.0660] device (cdc-wdm0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.0823] device (cdc-wdm0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.0844] modem["cdc-wdm0"]: IPv6 configuration disabled
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.0848] modem-broadband[cdc-wdm0]: IPv4 static configuration:
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.0851] modem-broadband[cdc-wdm0]: address 100.84.233.168/28
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.0852] modem-broadband[cdc-wdm0]: gateway 100.84.233.169
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.0853] modem-broadband[cdc-wdm0]: DNS 89.108.202.20
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.0853] modem-broadband[cdc-wdm0]: DNS 185.89.185.1
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.0854] modem-broadband[cdc-wdm0]: MTU 1500
May 01 23:24:28 manjaro-arm systemd-timesyncd[3391]: Network configuration changed, trying to establish connection.
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.1216] device (cdc-wdm0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.1708] device (cdc-wdm0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.1724] device (cdc-wdm0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.1748] manager: NetworkManager state is now CONNECTED_LOCAL
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.1822] manager: NetworkManager state is now CONNECTED_SITE
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.1832] policy: set 'play' (wwan0) as default for IPv4 routing and DNS
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.2081] device (cdc-wdm0): Activation: successful, device activated.
May 01 23:24:28 manjaro-arm NetworkManager[3415]: <info> [1619904268.3625] manager: NetworkManager state is now CONNECTED_GLOBAL
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3564] policy: auto-activating connection 'SpartanWorks' (d73fc2e0-43bf-4225-87fd-e3b667c61d0d)
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3623] device (wlan0): Activation: starting connection 'SpartanWorks' (d73fc2e0-43bf-4225-87fd-e3b667c61d0d)
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3654] device (wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3717] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3749] device (wlan0): Activation: (wifi) access point 'SpartanWorks' has security, but secrets are required.
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3751] device (wlan0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3922] device (wlan0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3948] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3971] device (wlan0): Activation: (wifi) connection 'SpartanWorks' has security, and secrets exist. No new secrets needed.
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3973] Config: added 'ssid' value 'SpartanWorks'
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3974] Config: added 'scan_ssid' value '1'
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3975] Config: added 'bgscan' value 'simple:30:-70:86400'
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3975] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK'
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3976] Config: added 'auth_alg' value 'OPEN'
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.3976] Config: added 'psk' value '<hidden>'
May 01 23:24:29 manjaro-arm wpa_supplicant[3866]: wlan0: Trying to associate with 9c:5c:8e:b9:0f:78 (SSID='SpartanWorks' freq=2472 MHz)
May 01 23:24:29 manjaro-arm wpa_supplicant[3866]: nl80211: kernel reports: Authentication algorithm number required
May 01 23:24:29 manjaro-arm wpa_supplicant[3866]: wlan0: CTRL-EVENT-STARTED-CHANNEL-SWITCH freq=2472 ht_enabled=1 ch_offset=0 ch_width=20 MHz cf1=2472 cf2=0
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.4872] device (wlan0): supplicant interface state: disconnected -> associating
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.4873] device (p2p-dev-wlan0): supplicant management interface state: disconnected -> associating
May 01 23:24:29 manjaro-arm wpa_supplicant[3866]: wlan0: Associated with 9c:5c:8e:b9:0f:78
May 01 23:24:29 manjaro-arm wpa_supplicant[3866]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.5790] device (wlan0): supplicant interface state: associating -> associated
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.5792] device (p2p-dev-wlan0): supplicant management interface state: associating -> associated
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.5941] device (wlan0): supplicant interface state: associated -> 4way_handshake
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.5943] device (p2p-dev-wlan0): supplicant management interface state: associated -> 4way_handshake
May 01 23:24:29 manjaro-arm wpa_supplicant[3866]: wlan0: WPA: Key negotiation completed with 9c:5c:8e:b9:0f:78 [PTK=CCMP GTK=CCMP]
May 01 23:24:29 manjaro-arm wpa_supplicant[3866]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:5c:8e:b9:0f:78 completed [id=0 id_str=]
May 01 23:24:29 manjaro-arm wpa_supplicant[3866]: bgscan simple: Failed to enable signal strength monitoring
May 01 23:24:29 manjaro-arm kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
May 01 23:24:29 manjaro-arm systemd-networkd[3404]: wlan0: Gained carrier
May 01 23:24:29 manjaro-arm systemd-timesyncd[3391]: Network configuration changed, trying to establish connection.
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.6403] device (wlan0): supplicant interface state: 4way_handshake -> completed
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.6413] device (wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "SpartanWorks"
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.6415] device (p2p-dev-wlan0): supplicant management interface state: 4way_handshake -> completed
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.6444] device (wlan0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.6470] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.7228] dhcp4 (wlan0): state changed unknown -> bound, address=192.168.1.232
May 01 23:24:29 manjaro-arm systemd-timesyncd[3391]: Network configuration changed, trying to establish connection.
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.7333] device (wlan0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.7526] device (wlan0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.7541] device (wlan0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.7874] device (wlan0): Activation: successful, device activated.
May 01 23:24:29 manjaro-arm NetworkManager[3415]: <info> [1619904269.9449] policy: set 'SpartanWorks' (wlan0) as default for IPv4 routing and DNS
May 01 23:24:31 manjaro-arm systemd-networkd[3404]: wlan0: Gained IPv6LL
May 01 23:24:35 manjaro-arm systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
May 01 23:24:41 manjaro-arm wpa_supplicant[3866]: wlan0: WPA: Group rekeying completed with 9c:5c:8e:b9:0f:78 [GTK=CCMP]
May 01 23:25:00 manjaro-arm systemd-timesyncd[3391]: Initial synchronization to time server 46.175.224.7:123 (1.arch.pool.ntp.org).