PinePhone: Phosh 0.21 crashes sometimes on resume from suspend
Describe your issue
What's the expected behaviour?
Phosh should show its lock screen after the phone has woken up.
What's the current behaviour?
Sometimes Phosh crashes (I briefly see the terminal login prompt) and then restarts when the phone wakes up from suspend. Aside from the crash and killing any open applications this also breaks sound until manually forcing Pulseaudio to restart.
How to reproduce your issue?
I haven't found a reliable way to reproduce the problem. It happens sometimes when the phone is resuming from suspend, so far it looks random to me.
What device are you using?
pine64-pinephone (1.2)
On what postmarketOS version did you encounter the issue?
-
edge ( master
branch) -
v22.06
-
v21.12
(supported until 2022-07-12) -
I confirm that the issue still is present after running sudo apk upgrade -a
On what environment did you encounter the issue?
Phone environments
-
Phosh -
Plasma Mobile -
Sxmo (Wayland/Sway)
Other
-
No environment ("none" in pmbootstrap) -
GNOME -
KDE Plasma -
Kodi -
MATE -
Shelli -
Sway -
Weston -
Xfce4 -
LXQt -
fbkeyboard -
i3wm -
Sxmo (Xorg/Dwm) -
console UI -
framebufferphone
How did you get postmarketOS image?
-
from https://images.postmarketos.org -
I built it using pmbootstrap -
It was preinstalled on my device
Additional information
These are the first lines from tinydm.log
after resume:
(phoc:5078): phoc-wlroots-CRITICAL **: 22:08:51.598: [types/output/output.c:634] Basic output test failed for DSI-1
(phoc:5078): phoc-output-WARNING **: 22:08:51.598: Failed to commit power mode change to 0 for 0xffffa4670040
(phoc:5078): phoc-wlroots-CRITICAL **: 22:08:51.613: [types/output/output.c:634] Basic output test failed for DSI-1
Full timydm.log including 3 lines from before suspend
(gsd-xsettings:5256): xsettings-plugin-WARNING **: 19:39:54.760: Failed to get current UI legacy scaling factor
(gsd-xsettings:5256): xsettings-plugin-WARNING **: 19:39:54.761: Failed to get current UI legacy scaling factor
(gsd-color:5227): color-plugin-WARNING **: 19:39:54.806: unable to get EDID for xrandr-DSI-1: unable to get EDID for output
(phoc:5078): phoc-wlroots-CRITICAL **: 22:08:51.598: [types/output/output.c:634] Basic output test failed for DSI-1
(phoc:5078): phoc-output-WARNING **: 22:08:51.598: Failed to commit power mode change to 0 for 0xffffa4670040
(phoc:5078): phoc-wlroots-CRITICAL **: 22:08:51.613: [types/output/output.c:634] Basic output test failed for DSI-1
(gsd-xsettings:5256): xsettings-plugin-WARNING **: 22:08:51.730: Failed to get current UI legacy scaling factor
(gsd-xsettings:5256): xsettings-plugin-WARNING **: 22:08:51.730: Failed to get current UI legacy scaling factor
Assertion failed: output->back_buffer == NULL (types/output/render.c: output_attach_back_buffer: 103)
(gsd-color:5227): color-plugin-WARNING **: 22:08:51.765: unable to get EDID for xrandr-DSI-1: unable to get EDID for output
Gdk-Message: 22:08:51.798: Error reading events from display: Broken pipe
20:08:51:0821 Gdk Error reading events from display: Broken pipe
Gdk-Message: 22:08:51.855: Error reading events from display: Broken pipe
(EE) failed to read Wayland events: Broken pipe
Gdk-Message: 22:08:51.859: Error reading events from display: Broken pipe
Gdk-Message: 22:08:51.860: Error reading events from display: Broken pipe
Gdk-Message: 22:08:51.861: Error reading events from display: Broken pipe
Gdk-Message: 22:08:51.862: Error reading events from display: Broken pipe
Gdk-Message: 22:08:51.862: Error reading events from display: Broken pipe
Gdk-Message: 22:08:51.866: Error reading events from display: Broken pipe
Gdk-Message: 22:08:51.867: Error reading events from display: Broken pipe
Gdk-Message: 22:08:51.869: Error reading events from display: Broken pipe
gnome-session-binary[5087]: WARNING: App 'org.gnome.SettingsDaemon.Wacom.desktop' exited with code 1
Gdk-Message: 22:08:51.907: Error reading events from display: Connection reset by peer
Gdk-Message: 22:08:51.923: Error flushing display: Broken pipe
gnome-session-binary[5087]: WARNING: App 'org.gnome.SettingsDaemon.Power.desktop' exited with code 1
gnome-session-binary[5087]: WARNING: App 'org.gnome.SettingsDaemon.MediaKeys.desktop' exited with code 1
gnome-session-binary[5087]: WARNING: App 'sm.puri.OSK0.desktop' exited with code 1
gnome-session-binary[5087]: WARNING: App 'org.gnome.SettingsDaemon.Keyboard.desktop' exited with code 1
Exiting due to channel error.
Exiting due to channel error.
Exiting due to channel error.
Exiting due to channel error.
Exiting due to channel error.
Exiting due to channel error.
Exiting due to channel error.
Unable to init server: Verbindung ist gescheitert: Connection refused
** (squeekboard:20743): WARNING **: 22:08:52.301: Anzeige kann nicht geöffnet werden:
gnome-session-binary[5087]: WARNING: App 'sm.puri.OSK0.desktop' exited with code 1
Unable to init server: Verbindung ist gescheitert: Connection refused
Anzeige kann nicht geöffnet werden:
gnome-session-binary[5087]: WARNING: App 'org.gnome.SettingsDaemon.Keyboard.desktop' exited with code 1
Unable to init server: Verbindung ist gescheitert: Connection refused
Anzeige kann nicht geöffnet werden:
gnome-session-binary[5087]: WARNING: App 'org.gnome.SettingsDaemon.MediaKeys.desktop' exited with code 1
gnome-session-binary[5087]: WARNING: App 'sm.puri.Phosh.desktop' exited with code 1
Gdk-Message: 22:08:52.638: gsd-xsettings: Fatal IO error 11 (Resource temporarily unavailable) on X server :0.
Gdk-Message: 22:08:52.707: Error reading events from display: Broken pipe
gnome-session-binary[5087]: WARNING: App 'org.gnome.SettingsDaemon.Color.desktop' exited with code 1
gnome-session-binary[5087]: CODE_FILE=../gnome-session/gsm-manager.c:CODE_LINE=318:on_display_server_failure:Unrecoverable failure in required component sm.puri.Phosh.desktop
Unable to init server: Verbindung ist gescheitert: Connection refused
Anzeige kann nicht geöffnet werden:
Unable to init server: Verbindung ist gescheitert: Connection refused
Anzeige kann nicht geöffnet werden:
(/usr/libexec/xdg-document-portal:5516): GLib-GIO-WARNING **: 22:08:53.118: Error releasing name org.freedesktop.portal.Documents: Verbindung ist geschlossen
Unable to init server: Verbindung ist gescheitert: Connection refused
22:08:53.1336 Gtk[20734]: WARNING: cannot open display: :0
Connection was disconnected before a reply was received
** (mmsdtng:6049): CRITICAL **: 22:08:53.149: Lost Dbus Connection! Exiting....
(evolution-calendar-factory:6152): libedbus-private-WARNING **: 22:08:53.200: Error setting property 'ConnectionStatus' on interface org.gnome.evolution.dataserver.Source: Verbindung ist geschlossen (g-io-error-quark, 18)
(evolution-calendar-factory:6152): libedbus-private-WARNING **: 22:08:53.204: Error setting property 'ConnectionStatus' on interface org.gnome.evolution.dataserver.Source: Verbindung ist geschlossen (g-io-error-quark, 18)
Unable to init server: Verbindung ist gescheitert: Connection refused
22:08:53.2822 Gtk[20740]: WARNING: cannot open display: :0
Kernel log, though nothing looks relevant to me
[46922.349127] PM: suspend entry (deep)
[46922.354085] Filesystems sync: 0.004 seconds
[46922.355465] Freezing user space processes ... (elapsed 0.006 seconds) done.
[46922.362147] OOM killer disabled.
[46922.362158] Freezing remaining freezable tasks ... (elapsed 0.002 seconds) done.
[46922.364298] printk: Suspending console(s) (use no_console_suspend to debug)
[46922.366376] musb-sunxi 1c19000.usb: sunxi-musb does not have ULPI bus control register
[46922.455216] Disabling non-boot CPUs ...
[46922.457459] psci: CPU1 killed (polled 0 ms)
[46922.461032] psci: CPU2 killed (polled 0 ms)
[46922.464353] psci: CPU3 killed (polled 0 ms)
[46922.465567] Enabling non-boot CPUs ...
[46922.466617] Detected VIPT I-cache on CPU1
[46922.466704] arch_timer: CPU1: Trapping CNTVCT access
[46922.466724] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[46922.467446] CPU1 is up
[46922.467982] Detected VIPT I-cache on CPU2
[46922.468031] arch_timer: CPU2: Trapping CNTVCT access
[46922.468044] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
[46922.468557] CPU2 is up
[46922.469082] Detected VIPT I-cache on CPU3
[46922.469129] arch_timer: CPU3: Trapping CNTVCT access
[46922.469142] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
[46922.469822] CPU3 is up
[46922.471233] sunxi-rsb 1f03400.rsb: RSB running at 4000000 Hz
[46922.633473] musb-sunxi 1c19000.usb: sunxi-musb does not have ULPI bus control register
[46922.711076] OOM killer enabled.
[46922.711095] Restarting tasks ... done.
[46922.730825] PM: suspend exit
[46923.497944] Bluetooth: hci0: RTL: examining hci_ver=07 hci_rev=000b lmp_ver=07 lmp_subver=8703
[46923.502622] Bluetooth: hci0: RTL: chip_type status=0 type=5
[46923.506814] Bluetooth: hci0: RTL: rom_version status=0 version=1
[46923.506856] Bluetooth: hci0: RTL: loading rtl_bt/rtl8723cs_xx_fw.bin
[46923.506938] Bluetooth: hci0: RTL: loading rtl_bt/rtl8723cs_xx_config.bin
[46923.531447] Bluetooth: hci0: RTL: cfg_sz 63, total sz 19427
[46923.873646] Bluetooth: hci0: RTL: fw version 0xaa5ca4dc
[46923.905470] rcu: INFO: rcu_preempt detected expedited stalls on CPUs/tasks: { P5123 } 6 jiffies s: 7125 root: 0x0/T
[46923.905530] rcu: blocking rcu_node structures (internal RCU debug):
[46923.953452] rcu: INFO: rcu_preempt detected expedited stalls on CPUs/tasks: { P5123 } 6 jiffies s: 7133 root: 0x0/T
[46923.953507] rcu: blocking rcu_node structures (internal RCU debug):
[46923.993653] rcu: INFO: rcu_preempt detected expedited stalls on CPUs/tasks: { P5077 } 6 jiffies s: 7145 root: 0x0/T
[46923.993718] rcu: blocking rcu_node structures (internal RCU debug):
[46924.225205] rfkill: input handler enabled
[46929.035148] ov5640 4-004c: ov5640_set_ctrl_focus: can't set focus when not powered