boot gets stuck shortly after disk decryption
#1
I have a Pinephone 1.2 convergence edition. I've been running bookworm for a long time and just today after a reboot, the system just hangs after decrypting the disk. I even re-installed Mobian fresh and it was booting OK until the most recent reboot (after I got all the software setup again). The messages printed to the boot screen (recorded over serial) are

Code:
U-Boot SPL 2021.10 (Oct 04 2021 - 15:09:26 +0000)
DRAM: 3072 MiB
Trying to boot from MMC2
NOTICE:  BL31: v2.6(release):
NOTICE:  BL31: Built : 00:00:00, Jan  1 1980
NOTICE:  BL31: Detected Allwinner A64/H64/R18 SoC (1689)
NOTICE:  BL31: Found U-Boot DTB at 0x209cf40, model: Pine64 PinePhone (1.2)


Tow-Boot 2021.10 (Oct 04 2021 - 15:09:26 +0000)005 [variant: mmcboot]

CPU:   Allwinner A64 (SUN50I)
Model: Pine64 PinePhone (1.2)
DRAM:  3 GiB
MMC:   mmc@1c0f000: 0, mmc@1c10000: 2, mmc@1c11000: 1
Loading Environment from nowhere... OK
In:    serial
Out:   serial
Err:   serial
Net:   No ethernet found.
Please press [ESCAPE] or [CTRL+C] to enter the boot menu.
off
off
switch to partitions #0, OK
mmc1(part 0) is current device
Scanning mmc 1:1...
Found /extlinux/extlinux.conf
Retrieving file: /extlinux/extlinux.conf
534 bytes read in 3 ms (173.8 KiB/s)
1:    Mobian GNU/Linux 5.15-sunxi64
Retrieving file: /initrd.img-5.15-sunxi64
81754277 bytes read in 1742 ms (44.8 MiB/s)
Retrieving file: /vmlinuz-5.15-sunxi64
7448010 bytes read in 161 ms (44.1 MiB/s)
append: root=/dev/mapper/calamares_crypt console=ttyS0,115200 consoleblank=0 loglevel=7 rw plymouth.ignore-serial-consoles vt.global_cursor_default=0 console=tty
Retrieving file: /dtb-5.15-sunxi64/allwinner/sun50i-a64-pinephone-1.2.dtb
52083 bytes read in 9 ms (5.5 MiB/s)
   Uncompressing Kernel Image
Moving Image from 0x40080000 to 0x40200000, end=415b0000
## Flattened Device Tree blob at 4fa00000
   Booting using the fdt blob at 0x4fa00000
   Loading Ramdisk to 45208000, end 49fff8a5 ... OK
   Loading Device Tree to 00000000451f8000, end 0000000045207b72 ... OK

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 5.15-sunxi64 (salsaci@runner-gg5xhzcb-project-17289439-concurrent-1) (gcc (Debian 11.3.0-3) 11.3.0, GNU ld (GNU Binutils for Debian) 2.38.50.20220622) #1 SMP Thu Jun 30 09:17:08 UTC 2022
[    0.000000] Machine model: Pine64 PinePhone (1.2)
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]
[    0.000000]   DMA32    empty
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000040000000-0x00000000ffffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000000ffffffff]
[    0.000000] cma: Reserved 256 MiB at 0x00000000ec800000
[    0.000000] psci: probing for conduit method from DT.
[    0.000000] psci: PSCIv1.1 detected in firmware.
[    0.000000] psci: Using standard PSCI v0.2 function IDs
[    0.000000] psci: MIGRATE_INFO_TYPE not supported.
[    0.000000] psci: SMC Calling Convention v1.2
[    0.000000] percpu: Embedded 29 pages/cpu s80472 r8192 d30120 u118784
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: kernel page table isolation disabled by kernel configuration
[    0.000000] CPU features: detected: ARM erratum 843419
[    0.000000] CPU features: detected: ARM erratum 845719
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 774144
[    0.000000] Kernel command line: root=/dev/mapper/calamares_crypt console=ttyS0,115200 consoleblank=0 loglevel=7 rw plymouth.ignore-serial-consoles vt.global_cursor_default=0 console=tty
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:on, heap free:off
[    0.000000] Memory: 63856K/3145728K available (10496K kernel code, 1824K rwdata, 3296K rodata, 3904K init, 523K bss, 162292K reserved, 262144K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 35369 entries in 139 pages
[    0.000000] ftrace: allocated 139 pages with 4 groups
[    0.000000] trace event string verifier disabled
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4.
[    0.000000]     Rude variant of Tasks RCU enabled.
[    0.000000]     Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] Root IRQ handler: gic_handle_irq
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] arch_timer: Enabling global workaround for Allwinner erratum UNKNOWN1
[    0.000000] arch_timer: CPU0: Trapping CNTVCT access
[    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[    0.000001] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
[    0.000236] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[    0.000789] Console: colour dummy device 80x25
[    0.001190] printk: console [tty0] enabled
[    0.001246] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
[    0.001274] pid_max: default: 32768 minimum: 301
[    0.001566] LSM: Security Framework initializing
[    0.001657] Yama: becoming mindful.
[    0.001834] AppArmor: AppArmor initialized
[    0.001855] TOMOYO Linux initialized
[    0.002093] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.002143] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.004973] rcu: Hierarchical SRCU implementation.
[    0.006951] smp: Bringing up secondary CPUs ...
[    0.008777] Detected VIPT I-cache on CPU1
[    0.008843] arch_timer: CPU1: Trapping CNTVCT access
[    0.008859] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[    0.010039] Detected VIPT I-cache on CPU2
[    0.010075] arch_timer: CPU2: Trapping CNTVCT access
[    0.010084] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
[    0.011145] Detected VIPT I-cache on CPU3
[    0.011177] arch_timer: CPU3: Trapping CNTVCT access
[    0.011186] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
[    0.011275] smp: Brought up 1 node, 4 CPUs
[    0.011366] SMP: Total of 4 processors activated.
[    0.011380] CPU features: detected: 32-bit EL0 Support
[    0.011391] CPU features: detected: 32-bit EL1 Support
[    0.011405] CPU features: detected: CRC32 instructions
[    0.011487] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching
[    0.011775] CPU: All CPU(s) started at EL2
[    0.011822] alternatives: patching kernel code
[    0.132061] node 0 deferred pages initialised in 116ms
[    0.134101] devtmpfs: initialized
[    0.148063] Registered cp15_barrier emulation handler
[    0.148138] KASLR disabled due to lack of seed
[    0.148431] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.148492] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.159782] pinctrl core: initialized pinctrl subsystem
[    0.161671] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.165005] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations
[    0.165389] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.166046] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.166127] audit: initializing netlink subsys (disabled)
[    0.166346] audit: type=2000 audit(0.164:1): state=initialized audit_enabled=0 res=1
[    0.167021] thermal_sys: Registered thermal governor 'fair_share'
[    0.167029] thermal_sys: Registered thermal governor 'bang_bang'
[    0.167046] thermal_sys: Registered thermal governor 'step_wise'
[    0.167060] thermal_sys: Registered thermal governor 'user_space'
[    0.167073] thermal_sys: Registered thermal governor 'power_allocator'
[    0.167648] cpuidle: using governor ladder
[    0.167691] cpuidle: using governor menu
[    0.167870] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.168007] ASID allocator initialised with 65536 entries
[    0.186495] platform 1ca0000.dsi: Fixing up cyclic dependency with 1c0c000.lcd-controller
[    0.187615] platform 1ee0000.hdmi: Fixing up cyclic dependency with 1c0d000.lcd-controller
[    0.190609] platform hdmi-connector: Fixing up cyclic dependency with 1ee0000.hdmi
[    0.201059] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.201101] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
[    0.201118] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.201133] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
[    1.039063] fbcon: Taking over console
[    1.040496] iommu: Default domain type: Translated
[    1.040526] iommu: DMA domain TLB invalidation policy: strict mode
[    1.041048] usbcore: registered new interface driver usbfs
[    1.041104] usbcore: registered new interface driver hub
[    1.041148] usbcore: registered new device driver usb
[    1.041363] power_supply_init_attrs: Property 37 skipped because it is missing from power_supply_attrs
[    1.042786] NetLabel: Initializing
[    1.042803] NetLabel:  domain hash size = 128
[    1.042815] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    1.042906] NetLabel:  unlabeled traffic allowed by default
[    1.043590] clocksource: Switched to clocksource arch_sys_counter
[    1.096673] VFS: Disk quotas dquot_6.6.0
[    1.096801] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.097718] AppArmor: AppArmor Filesystem Enabled
[    1.107216] NET: Registered PF_INET protocol family
[    1.107614] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    1.110254] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
[    1.110440] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    1.110561] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    1.111059] TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)
[    1.111739] TCP: Hash tables configured (established 32768 bind 32768)
[    1.112115] MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear)
[    1.112356] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    1.112502] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    1.112869] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    1.112910] NET: Registered PF_XDP protocol family
[    1.113349] Trying to unpack rootfs image as initramfs...
[    1.125721] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
[    1.126408] kvm [1]: IPA Size Limit: 40 bits
[    1.130030] kvm [1]: vgic interrupt IRQ9
[    1.130396] kvm [1]: Hyp mode initialized successfully
[    1.137602] Initialise system trusted keyrings
[    1.137723] Key type blacklist registered
[    1.138025] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    1.145993] zbud: loaded
[    1.147354] integrity: Platform Keyring initialized
[    1.147416] Key type asymmetric registered
[    1.147432] Asymmetric key parser 'x509' registered
[    1.147545] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[    1.147923] io scheduler mq-deadline registered
[    1.156956] sun50i-a64-r-pinctrl 1f02c00.pinctrl: initialized sunXi PIO driver
[    1.158475] sun50i-a64-r-pinctrl 1f02c00.pinctrl: supply vcc-pl not found, using dummy regulator
[    1.169714] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    1.175371] Error: Driver 'sun8i-hdmi-phy' is already registered, aborting...
[    1.180942] lima 1c40000.gpu: gp - mali400 version major 1 minor 1
[    1.181062] lima 1c40000.gpu: pp0 - mali400 version major 1 minor 1
[    1.181120] lima 1c40000.gpu: pp1 - mali400 version major 1 minor 1
[    1.181184] lima 1c40000.gpu: l2 cache 64K, 4-way, 64byte cache line, 64bit external bus
[    1.181847] lima 1c40000.gpu: bus rate = 200000000
[    1.181870] lima 1c40000.gpu: mod rate = 500000000
[    1.182407] [drm] Initialized lima 1.1.0 20191231 for 1c40000.gpu on minor 0
[    1.185423] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.185478] ehci-platform: EHCI generic platform driver
[    1.185735] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    1.185780] ohci-platform: OHCI generic platform driver
[    1.186320] mousedev: PS/2 mouse device common for all mice
[    1.187424] sun6i-rtc 1f00000.rtc: registered as rtc0
[    1.187502] sun6i-rtc 1f00000.rtc: setting system clock to 2022-08-24T21:55:20 UTC (1661378120)
[    1.187532] sun6i-rtc 1f00000.rtc: RTC enabled
[    1.189574] sunxi-wdt 1c20ca0.watchdog: Watchdog enabled (timeout=16 sec, nowayout=0)
[    1.192108] Synopsys Designware Multimedia Card Interface Driver
[    1.193390] ledtrig-cpu: registered to indicate activity on CPUs
[    1.193582] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ....
[    1.193689] hid: raw HID events driver (C) Jiri Kosina
[    1.194193] usbcore: registered new interface driver usbhid
[    1.194213] usbhid: USB HID core driver
[    1.194754] ashmem: initialized
[    1.199668] NET: Registered PF_INET6 protocol family
[    6.685068] Freeing initrd memory: 79836K
[    6.741009] Segment Routing with IPv6
[    6.741123] In-situ OAM (IOAM) with IPv6
[    6.741233] mip6: Mobile IPv6
[    6.741251] NET: Registered PF_PACKET protocol family
[    6.741436] mpls_gso: MPLS GSO support
[    6.742319] registered taskstats version 1
[    6.742347] Loading compiled-in X.509 certificates
[    6.743878] zswap: loaded using pool lz4hc/z3fold
[    6.744703] Key type ._fscrypt registered
[    6.744720] Key type .fscrypt registered
[    6.744732] Key type fscrypt-provisioning registered
[    6.745047] AppArmor: AppArmor sha1 policy hashing enabled
[    6.745089] ima: No TPM chip found, activating TPM-bypass!
[    6.745128] ima: Allocated hash algorithm: sha256
[    6.745199] ima: No architecture policies found
[    6.760466] platform 1100000.mixer: Fixing up cyclic dependency with 1c0d000.lcd-controller
[    6.760593] platform 1100000.mixer: Fixing up cyclic dependency with 1c0c000.lcd-controller
[    6.761509] platform 1200000.mixer: Fixing up cyclic dependency with 1c0d000.lcd-controller
[    6.761609] platform 1200000.mixer: Fixing up cyclic dependency with 1c0c000.lcd-controller
[    6.762559] sunxi-rsb 1f03400.rsb: RSB running at 4000000 Hz
[    6.763013] axp20x-rsb sunxi-rsb-3a3: AXP20x variant AXP803 found
[    6.765412] axp20x-gpio axp20x-gpio: DMA mask not set
[    6.766023] axp20x-gpio axp20x-gpio: AXP209 pinctrl and GPIO driver loaded
[    6.767624] vcc-3v3: supplied by regulator-dummy
[    6.768156] vdd-cpux: supplied by regulator-dummy
[    6.768960] dcdc4: supplied by regulator-dummy
[    6.769154] vcc-dram: supplied by regulator-dummy
[    6.769619] vdd-sys: supplied by regulator-dummy
[    6.770272] dc1sw: supplied by regulator-dummy
[    6.770429] dovdd-csi: Bringing 3300000uV into 1800000-1800000uV
[    6.770832] dovdd-csi: supplied by regulator-dummy
[    6.771036] vcc-pl: supplied by regulator-dummy
[    6.771526] vcc-pll-avcc: supplied by regulator-dummy
[    6.772246] vcc-dsi-sensor: supplied by regulator-dummy
[    6.772656] vcc-mipi-io: supplied by regulator-dummy
[    6.773113] avdd-csi: supplied by regulator-dummy
[    6.773535] vcc-wifi-io: supplied by regulator-dummy
[    6.773731] vcc-lpddr: supplied by regulator-dummy
[    6.774473] eldo2: supplied by regulator-dummy
[    6.774641] dvdd-1v8-csi: Bringing 700000uV into 1800000-1800000uV
[    6.775056] dvdd-1v8-csi: supplied by regulator-dummy
[    6.775515] vcc-1v2-hsic: supplied by regulator-dummy
[    6.775761] vdd-cpus: supplied by regulator-dummy
[    6.776204] vcc-rtc: supplied by regulator-dummy
[    6.776730] vcc-lcd-ctp-stk: supplied by regulator-dummy
[    6.776902] vcc-1v8-typec: Bringing 3300000uV into 1800000-1800000uV
[    6.777321] vcc-1v8-typec: supplied by regulator-dummy
[    6.777456] axp20x-rsb sunxi-rsb-3a3: AXP20X driver loaded
[    6.782451] sun50i-a64-pinctrl 1c20800.pinctrl: initialized sunXi PIO driver
[    6.784374] printk: console [ttyS0] disabled
[    6.804576] 1c28000.serial: ttyS0 at MMIO 0x1c28000 (irq = 43, base_baud = 1500000) is a U6_16550A
[    8.112354] printk: console [ttyS0] enabled
[    8.138313] 1c28400.serial: ttyS1 at MMIO 0x1c28400 (irq = 44, base_baud = 1500000) is a U6_16550A
[    8.147461] serial serial0: tty port ttyS1 registered
[    8.175735] 1c28c00.serial: ttyS2 at MMIO 0x1c28c00 (irq = 45, base_baud = 1500000) is a U6_16550A
[    8.187410] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[    8.198654] i2c 0-0028: Fixing up cyclic dependency with 1c19400.phy
[    8.219350] sunxi-mmc 1c0f000.mmc: Got CD GPIO
[    8.219479] sunxi-mmc 1c10000.mmc: allocated mmc-pwrseq
[    8.220645] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[    8.221531] phy id_det change to device
[    8.266734] sunxi-mmc 1c11000.mmc: initialized, max. request size: 2048 KB, uses new timings mode
[    8.266804] sunxi-mmc 1c0f000.mmc: initialized, max. request size: 16384 KB, uses new timings mode
[    8.267048] sunxi-mmc 1c10000.mmc: initialized, max. request size: 16384 KB, uses new timings mode
[    8.291299] mmc1: new high speed SDIO card at address 0001
[    8.342990] mmc0: new high speed SDXC card at address aaaa
[    8.350395] mmcblk0: mmc0:aaaa SL128 119 GiB
[    8.376974]  mmcblk0: p1
[    8.395461] mmc2: new DDR MMC card at address 0001
[    8.401882] mmcblk2: mmc2:0001 DA4032 29.1 GiB
[    8.413515]  mmcblk2: p1 p2
[    8.421336] mmcblk2boot0: mmc2:0001 DA4032 4.00 MiB
[    8.432471] mmcblk2boot1: mmc2:0001 DA4032 4.00 MiB
[    9.251676] phy phy-1c19400.phy.1: Changing dr_mode to 1
[    9.257064] phy id_det change to host
[    9.467698] ehci-platform 1c1a000.usb: EHCI Host Controller
[    9.473691] ehci-platform 1c1a000.usb: new USB bus registered, assigned bus number 1
[    9.481644] ehci-platform 1c1a000.usb: irq 29, io mem 0x01c1a000
[    9.503618] ehci-platform 1c1a000.usb: USB 2.0 started, EHCI 1.00
[    9.509981] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    9.518270] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    9.525513] usb usb1: Product: EHCI Host Controller
[    9.530406] usb usb1: Manufacturer: Linux 5.15-sunxi64 ehci_hcd
[    9.536341] usb usb1: SerialNumber: 1c1a000.usb
[    9.541512] hub 1-0:1.0: USB hub found
[    9.545344] hub 1-0:1.0: 1 port detected
[    9.550407] ehci-platform 1c1b000.usb: EHCI Host Controller
[    9.556236] ehci-platform 1c1b000.usb: new USB bus registered, assigned bus number 2
[    9.564157] ehci-platform 1c1b000.usb: irq 31, io mem 0x01c1b000
[    9.583615] ehci-platform 1c1b000.usb: USB 2.0 started, EHCI 1.00
[    9.589906] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    9.598194] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    9.605427] usb usb2: Product: EHCI Host Controller
[    9.610326] usb usb2: Manufacturer: Linux 5.15-sunxi64 ehci_hcd
[    9.616257] usb usb2: SerialNumber: 1c1b000.usb
[    9.624334] hub 2-0:1.0: USB hub found
[    9.628124] hub 2-0:1.0: 1 port detected
[    9.635395] phy id_det change to host
[    9.847643] ohci-platform 1c1a400.usb: Generic Platform OHCI controller
[    9.854643] ohci-platform 1c1a400.usb: new USB bus registered, assigned bus number 3
[    9.862693] ohci-platform 1c1a400.usb: irq 30, io mem 0x01c1a400
[    9.931928] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.15
[    9.940261] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    9.947516] usb usb3: Product: Generic Platform OHCI controller
[    9.953456] usb usb3: Manufacturer: Linux 5.15-sunxi64 ohci_hcd
[    9.959391] usb usb3: SerialNumber: 1c1a400.usb
[    9.964559] hub 3-0:1.0: USB hub found
[    9.968899] hub 3-0:1.0: 1 port detected
[    9.974077] ohci-platform 1c1b400.usb: Generic Platform OHCI controller
[    9.981006] ohci-platform 1c1b400.usb: new USB bus registered, assigned bus number 4
[    9.988915] ohci-platform 1c1b400.usb: irq 32, io mem 0x01c1b400
[   10.059832] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.15
[   10.068125] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   10.075365] usb usb4: Product: Generic Platform OHCI controller
[   10.081299] usb usb4: Manufacturer: Linux 5.15-sunxi64 ohci_hcd
[   10.087235] usb usb4: SerialNumber: 1c1b400.usb
[   10.092363] hub 4-0:1.0: USB hub found
[   10.096171] hub 4-0:1.0: 1 port detected
[   10.100983] usb0-vbus: supplied by vcc5v0
[   10.105692] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   10.115475] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   10.125383] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[   10.135896] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   10.152946] Freeing unused kernel memory: 3904K
[   10.175843] Run /init as init process
[   11.107441] i2c_gpio: module verification failed: signature and/or required key missing - tainting kernel
[   11.124703] i2c 3-003c: Fixing up cyclic dependency with 1cb0000.csi
[   11.135964] i2c 3-004c: Fixing up cyclic dependency with 1cb0000.csi
[   11.154525] i2c-gpio i2c-csi: using lines 141 (SDA) and 140 (SCL)
[   11.157241] scpi_protocol scpi: SCP Protocol 1.2 Firmware 0.5.10000 version
[   11.168427] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   11.178514] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   11.201709] musb-sunxi 1c19000.usb: Disabling musb host side code due to re-routed phy
[   11.218054] input: 1c21800.lradc as /devices/platform/soc/1c21800.lradc/input/input1
[   11.227798] usb_phy_generic usb_phy_generic.1.auto: supply vcc not found, using dummy regulator
[   11.228156] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   11.237390] usb_phy_generic usb_phy_generic.1.auto: dummy supplies not allowed for exclusive requests
[   11.247397] pinephone-keyboard 2-0015: error -ENXIO: Failed to read device ID
[   11.255396] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   11.271693] axp20x-usb-power-supply axp20x-usb-power-supply: DMA mask not set
[   11.273343] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   11.299133] axp20x-usb-power-supply axp20x-usb-power-supply: Increasing Vbus hold voltage to 4.5V
[   11.317732] cryptd: max_cpu_qlen set to 1000
[   11.328217] Goodix-TS 0-005d: ID 917S, version: 0200
[   11.333668] Goodix-TS 0-005d: Direct firmware load for goodix_917S_cfg.bin failed with error -2
[   11.369024] input: Goodix Capacitive TouchScreen as /devices/platform/soc/1c2ac00.i2c/i2c-0/0-005d/input/input2
[   11.369353] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   11.456027] anx7688 0-0028: Vendor id 0x1f29
[   11.484571] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   11.523040] sun4i-drm display-engine: bound 1100000.mixer (ops 0xffffffc008b2ffc0)
[   11.532162] sun4i-drm display-engine: bound 1200000.mixer (ops 0xffffffc008b2ffc0)
[   11.540699] sun4i-drm display-engine: No panel or bridge found... RGB output disabled
[   11.548622] sun4i-drm display-engine: bound 1c0c000.lcd-controller (ops 0xffffffc008b2b6e0)
[   11.557442] sun4i-drm display-engine: bound 1c0d000.lcd-controller (ops 0xffffffc008b2b6e0)
[   11.565935] sun4i-drm display-engine: bound 1ca0000.dsi (ops 0xffffffc008b2ef58)
[   11.574920] sun8i-dw-hdmi 1ee0000.hdmi: Detected HDMI TX controller v1.32a with HDCP (sun8i_dw_hdmi_phy)
[   11.585108] sun8i-dw-hdmi 1ee0000.hdmi: registered DesignWare HDMI I2C bus driver
[   11.593301] sun4i-drm display-engine: bound 1ee0000.hdmi (ops 0xffffffc008b2f2f8)
[   11.603703] [drm] Initialized sun4i-drm 1.0.0 20150629 for display-engine on minor 1
[   11.611804] sun4i-drm display-engine: [drm] Cannot find any crtc or sizes
[   11.841353] Console: switching to colour frame buffer device 90x90
[   11.893523] sun4i-drm display-engine: [drm] fb0: sun4i-drmdrmfb frame buffer device
[   11.917079] sun6i-mipi-dsi 1ca0000.dsi: Attached device xbd599
[   11.923724] panel-sitronix-st7703 1ca0000.dsi.0: 720x1440@60 24bpp dsi 4dl - ready
[   11.926778]  mmcblk2: p1 p2
[   12.136768] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
[   12.153078] device-mapper: uevent: version 1.0.3
[   12.161154] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
[   12.325075] random: cryptsetup: uninitialized urandom read (4 bytes read)
[   13.581206] random: osk-sdl: uninitialized urandom read (16 bytes read)
[   14.104667] random: osk-sdl: uninitialized urandom read (16 bytes read)
[   22.895305] random: crng init done
[   41.951644] ps: disabling
[   41.954698] dc1sw: disabling
[   41.958063] avdd-csi: disabling
[   41.961716] vcc-1v2-hsic: disabling
[   41.965530] usb0-vbus: disabling


I've been able to eliminate several possible issues.

At least part of the kernel is still running. When I plug in a USB cable between the phone's USB port and my computer, it prints messages indicating it is reacting to it.

It wasn't just something peculiar with my old mobian install. I made a fresh install and the issue came right back after a long session of installing and updating software.

It isn't the hardware. Mobian installer and live SD cards boot and run without problem. And the fresh install worked for a while.

It isn't the root filesystem. Using an SD image, I ran a long fsck on the old filesystem and found no problems. On the new install, I chose a completely different filesystem (BTRFS instead of F2FS like before).

It isn't that the boot is just really slow (unless it is absurdly slow). I've left it at the hanged location in boot for an hour and it did not progress.

It isn't the boot loader. I was originally running straight u-boot. For the preparations for the re-install, I put on the latest tow-boot and I am now having the same problems.






Is anyone else having the same issue or have an idea of where to look.
  Reply
#2
I did another fresh install and have made sure I did not update any packages at all. It now seems to work without the issue, though the state of the software is something to be desired (can't update to get bug fixes). I think I am going to have to wait a long while before doing an update and then hope that that allows updating past whatever the issue is.
  Reply
#3
I did a "apt-get upgrade" today (the first since Aug 13) and now my phone won't boot.  Kernel 5.15.59+sunxi64-1 got installed in the process. Don't know if that has something to do with it. After i enter the FDE passphase there are 5-6 messages displayed, the last of which says "usb0-vbus: disabling". I'm using U-Boot. I also see a reaction after connecting a USB device. Sad Confused Huh
  Reply
#4
I was expecting a wave of "me too" reports as more people did upgrades, but so far it's just us 2.

I restored my system from backups and am contemplating doing just the kernel upgrade.  If that's successful I would upgrade other packages in batches, with reboots in between, until I narrow down the culprit or finish with no incident.
  Reply
#5
FYI, I hit the error again today so I've done an official report.  If I "apt-mark hold osk-sdl" then I avoid the problem.
  Reply
#6
The problem (with osk-sdl v0.67-2) persists for me.  I have filed a new bug report as the old mobian gitlab is being closed.
  Reply
#7
This issue is expected to be fixed in v0.67.1-1, which is in the pipeline.
  Reply
#8
(10-30-2022, 06:39 AM)treebeard Wrote: This issue is expected to be fixed in v0.67.1-1, which is in the pipeline.

osk-sdl 0.67.1-1 is in bookworm now and does fix the problem.
  Reply
#9
This problem is expected to be resolved in v0.67.1-1, which is currently in the works.

subway surfers
  Reply
#10
(08-24-2022, 04:05 PM)vortex Wrote: I have a Pinephone 1.2 convergence edition. I've been running bookworm for a long time and just today after a reboot, the system just hangs after decrypting the disk. I even re-installed Mobian fresh and it was booting OK until the most recent reboot (after I got all the software setup again). The messages printed to the boot screen (recorded over serial) are

Code:
U-Boot SPL 2021.10 (Oct 04 2021 - 15:09:26 +0000)
DRAM: 3072 MiB
Trying to boot from MMC2
NOTICE:  BL31: v2.6(release):
NOTICE:  BL31: Built : 00:00:00, Jan  1 1980
NOTICE:  BL31: Detected Allwinner A64/H64/R18 SoC (1689)
NOTICE:  BL31: Found U-Boot DTB at 0x209cf40, model: Pine64 PinePhone (1.2)


Tow-Boot 2021.10 (Oct 04 2021 - 15:09:26 +0000)005 [variant: mmcboot]

CPU:   Allwinner A64 (SUN50I)
Model: Pine64 PinePhone (1.2)
DRAM:  3 GiB
MMC:   mmc@1c0f000: 0, mmc@1c10000: 2, mmc@1c11000: 1
Loading Environment from nowhere... OK
In:    serial
Out:   serial
Err:   serial
Net:   No ethernet found.
Please press [ESCAPE] or [CTRL+C] to enter the boot menu.
off
off
switch to partitions #0, OK
mmc1(part 0) is current device
Scanning mmc 1:1...
Found /extlinux/extlinux.conf
Retrieving file: /extlinux/extlinux.conf
534 bytes read in 3 ms (173.8 KiB/s)
1:    Mobian GNU/Linux 5.15-sunxi64
Retrieving file: /initrd.img-5.15-sunxi64
81754277 bytes read in 1742 ms (44.8 MiB/s)
Retrieving file: /vmlinuz-5.15-sunxi64
7448010 bytes read in 161 ms (44.1 MiB/s)
append: root=/dev/mapper/calamares_crypt console=ttyS0,115200 consoleblank=0 loglevel=7 rw plymouth.ignore-serial-consoles vt.global_cursor_default=0 console=tty
Retrieving file: /dtb-5.15-sunxi64/allwinner/sun50i-a64-pinephone-1.2.dtb
52083 bytes read in 9 ms (5.5 MiB/s)
   Uncompressing Kernel Image
Moving Image from 0x40080000 to 0x40200000, end=415b0000
## Flattened Device Tree blob at 4fa00000
   Booting using the fdt blob at 0x4fa00000
   Loading Ramdisk to 45208000, end 49fff8a5 ... OK
   Loading Device Tree to 00000000451f8000, end 0000000045207b72 ... OK

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 5.15-sunxi64 (salsaci@runner-gg5xhzcb-project-17289439-concurrent-1) (gcc (Debian 11.3.0-3) 11.3.0, GNU ld (GNU Binutils for Debian) 2.38.50.20220622) #1 SMP Thu Jun 30 09:17:08 UTC 2022
[    0.000000] Machine model: Pine64 PinePhone (1.2)
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]
[    0.000000]   DMA32    empty
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000040000000-0x00000000ffffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000000ffffffff]
[    0.000000] cma: Reserved 256 MiB at 0x00000000ec800000
[    0.000000] psci: probing for conduit method from DT.
[    0.000000] psci: PSCIv1.1 detected in firmware.
[    0.000000] psci: Using standard PSCI v0.2 function IDs
[    0.000000] psci: MIGRATE_INFO_TYPE not supported.
[    0.000000] psci: SMC Calling Convention v1.2
[    0.000000] percpu: Embedded 29 pages/cpu s80472 r8192 d30120 u118784
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: kernel page table isolation disabled by kernel configuration
[    0.000000] CPU features: detected: ARM erratum 843419
[    0.000000] CPU features: detected: ARM erratum 845719
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 774144
[    0.000000] Kernel command line: root=/dev/mapper/calamares_crypt console=ttyS0,115200 consoleblank=0 loglevel=7 rw plymouth.ignore-serial-consoles vt.global_cursor_default=0 console=tty
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:on, heap free:off
[    0.000000] Memory: 63856K/3145728K available (10496K kernel code, 1824K rwdata, 3296K rodata, 3904K init, 523K bss, 162292K reserved, 262144K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 35369 entries in 139 pages
[    0.000000] ftrace: allocated 139 pages with 4 groups
[    0.000000] trace event string verifier disabled
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4.
[    0.000000]     Rude variant of Tasks RCU enabled.
[    0.000000]     Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] Root IRQ handler: gic_handle_irq
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] arch_timer: Enabling global workaround for Allwinner erratum UNKNOWN1
[    0.000000] arch_timer: CPU0: Trapping CNTVCT access
[    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[    0.000001] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
[    0.000236] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[    0.000789] Console: colour dummy device 80x25
[    0.001190] printk: console [tty0] enabled
[    0.001246] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
[    0.001274] pid_max: default: 32768 minimum: 301
[    0.001566] LSM: Security Framework initializing
[    0.001657] Yama: becoming mindful.
[    0.001834] AppArmor: AppArmor initialized
[    0.001855] TOMOYO Linux initialized
[    0.002093] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.002143] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.004973] rcu: Hierarchical SRCU implementation.
[    0.006951] smp: Bringing up secondary CPUs ...
[    0.008777] Detected VIPT I-cache on CPU1
[    0.008843] arch_timer: CPU1: Trapping CNTVCT access
[    0.008859] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[    0.010039] Detected VIPT I-cache on CPU2
[    0.010075] arch_timer: CPU2: Trapping CNTVCT access
[    0.010084] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
[    0.011145] Detected VIPT I-cache on CPU3
[    0.011177] arch_timer: CPU3: Trapping CNTVCT access
[    0.011186] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
[    0.011275] smp: Brought up 1 node, 4 CPUs
[    0.011366] SMP: Total of 4 processors activated.
[    0.011380] CPU features: detected: 32-bit EL0 Support
[    0.011391] CPU features: detected: 32-bit EL1 Support
[    0.011405] CPU features: detected: CRC32 instructions
[    0.011487] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching
[    0.011775] CPU: All CPU(s) started at EL2
[    0.011822] alternatives: patching kernel code
[    0.132061] node 0 deferred pages initialised in 116ms
[    0.134101] devtmpfs: initialized
[    0.148063] Registered cp15_barrier emulation handler
[    0.148138] KASLR disabled due to lack of seed
[    0.148431] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.148492] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.159782] pinctrl core: initialized pinctrl subsystem
[    0.161671] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.165005] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations
[    0.165389] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.166046] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.166127] audit: initializing netlink subsys (disabled)
[    0.166346] audit: type=2000 audit(0.164:1): state=initialized audit_enabled=0 res=1
[    0.167021] thermal_sys: Registered thermal governor 'fair_share'
[    0.167029] thermal_sys: Registered thermal governor 'bang_bang'
[    0.167046] thermal_sys: Registered thermal governor 'step_wise'
[    0.167060] thermal_sys: Registered thermal governor 'user_space'
[    0.167073] thermal_sys: Registered thermal governor 'power_allocator'
[    0.167648] cpuidle: using governor ladder
[    0.167691] cpuidle: using governor menu
[    0.167870] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.168007] ASID allocator initialised with 65536 entries
[    0.186495] platform 1ca0000.dsi: Fixing up cyclic dependency with 1c0c000.lcd-controller
[    0.187615] platform 1ee0000.hdmi: Fixing up cyclic dependency with 1c0d000.lcd-controller
[    0.190609] platform hdmi-connector: Fixing up cyclic dependency with 1ee0000.hdmi
[    0.201059] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.201101] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
[    0.201118] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.201133] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
[    1.039063] fbcon: Taking over console
[    1.040496] iommu: Default domain type: Translated
[    1.040526] iommu: DMA domain TLB invalidation policy: strict mode
[    1.041048] usbcore: registered new interface driver usbfs
[    1.041104] usbcore: registered new interface driver hub
[    1.041148] usbcore: registered new device driver usb
[    1.041363] power_supply_init_attrs: Property 37 skipped because it is missing from power_supply_attrs
[    1.042786] NetLabel: Initializing
[    1.042803] NetLabel:  domain hash size = 128
[    1.042815] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    1.042906] NetLabel:  unlabeled traffic allowed by default
[    1.043590] clocksource: Switched to clocksource arch_sys_counter
[    1.096673] VFS: Disk quotas dquot_6.6.0
[    1.096801] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.097718] AppArmor: AppArmor Filesystem Enabled
[    1.107216] NET: Registered PF_INET protocol family
[    1.107614] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    1.110254] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
[    1.110440] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    1.110561] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    1.111059] TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)
[    1.111739] TCP: Hash tables configured (established 32768 bind 32768)
[    1.112115] MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear)
[    1.112356] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    1.112502] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    1.112869] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    1.112910] NET: Registered PF_XDP protocol family
[    1.113349] Trying to unpack rootfs image as initramfs...
[    1.125721] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
[    1.126408] kvm [1]: IPA Size Limit: 40 bits
[    1.130030] kvm [1]: vgic interrupt IRQ9
[    1.130396] kvm [1]: Hyp mode initialized successfully
[    1.137602] Initialise system trusted keyrings
[    1.137723] Key type blacklist registered
[    1.138025] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    1.145993] zbud: loaded
[    1.147354] integrity: Platform Keyring initialized
[    1.147416] Key type asymmetric registered
[    1.147432] Asymmetric key parser 'x509' registered
[    1.147545] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[    1.147923] io scheduler mq-deadline registered
[    1.156956] sun50i-a64-r-pinctrl 1f02c00.pinctrl: initialized sunXi PIO driver
[    1.158475] sun50i-a64-r-pinctrl 1f02c00.pinctrl: supply vcc-pl not found, using dummy regulator
[    1.169714] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    1.175371] Error: Driver 'sun8i-hdmi-phy' is already registered, aborting...
[    1.180942] lima 1c40000.gpu: gp - mali400 version major 1 minor 1
[    1.181062] lima 1c40000.gpu: pp0 - mali400 version major 1 minor 1
[    1.181120] lima 1c40000.gpu: pp1 - mali400 version major 1 minor 1
[    1.181184] lima 1c40000.gpu: l2 cache 64K, 4-way, 64byte cache line, 64bit external bus
[    1.181847] lima 1c40000.gpu: bus rate = 200000000
[    1.181870] lima 1c40000.gpu: mod rate = 500000000
[    1.182407] [drm] Initialized lima 1.1.0 20191231 for 1c40000.gpu on minor 0
[    1.185423] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.185478] ehci-platform: EHCI generic platform driver
[    1.185735] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    1.185780] ohci-platform: OHCI generic platform driver
[    1.186320] mousedev: PS/2 mouse device common for all mice
[    1.187424] sun6i-rtc 1f00000.rtc: registered as rtc0
[    1.187502] sun6i-rtc 1f00000.rtc: setting system clock to 2022-08-24T21:55:20 UTC (1661378120)
[    1.187532] sun6i-rtc 1f00000.rtc: RTC enabled
[    1.189574] sunxi-wdt 1c20ca0.watchdog: Watchdog enabled (timeout=16 sec, nowayout=0)
[    1.192108] Synopsys Designware Multimedia Card Interface Driver
[    1.193390] ledtrig-cpu: registered to indicate activity on CPUs
[    1.193582] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ....
[    1.193689] hid: raw HID events driver (C) Jiri Kosina
[    1.194193] usbcore: registered new interface driver usbhid
[    1.194213] usbhid: USB HID core driver
[    1.194754] ashmem: initialized
[    1.199668] NET: Registered PF_INET6 protocol family
[    6.685068] Freeing initrd memory: 79836K
[    6.741009] Segment Routing with IPv6
[    6.741123] In-situ OAM (IOAM) with IPv6
[    6.741233] mip6: Mobile IPv6
[    6.741251] NET: Registered PF_PACKET protocol family
[    6.741436] mpls_gso: MPLS GSO support
[    6.742319] registered taskstats version 1
[    6.742347] Loading compiled-in X.509 certificates
[    6.743878] zswap: loaded using pool lz4hc/z3fold
[    6.744703] Key type ._fscrypt registered
[    6.744720] Key type .fscrypt registered
[    6.744732] Key type fscrypt-provisioning registered
[    6.745047] AppArmor: AppArmor sha1 policy hashing enabled
[    6.745089] ima: No TPM chip found, activating TPM-bypass!
[    6.745128] ima: Allocated hash algorithm: sha256
[    6.745199] ima: No architecture policies found
[    6.760466] platform 1100000.mixer: Fixing up cyclic dependency with 1c0d000.lcd-controller
[    6.760593] platform 1100000.mixer: Fixing up cyclic dependency with 1c0c000.lcd-controller
[    6.761509] platform 1200000.mixer: Fixing up cyclic dependency with 1c0d000.lcd-controller
[    6.761609] platform 1200000.mixer: Fixing up cyclic dependency with 1c0c000.lcd-controller
[    6.762559] sunxi-rsb 1f03400.rsb: RSB running at 4000000 Hz
[    6.763013] axp20x-rsb sunxi-rsb-3a3: AXP20x variant AXP803 found
[    6.765412] axp20x-gpio axp20x-gpio: DMA mask not set
[    6.766023] axp20x-gpio axp20x-gpio: AXP209 pinctrl and GPIO driver loaded
[    6.767624] vcc-3v3: supplied by regulator-dummy
[    6.768156] vdd-cpux: supplied by regulator-dummy
[    6.768960] dcdc4: supplied by regulator-dummy
[    6.769154] vcc-dram: supplied by regulator-dummy
[    6.769619] vdd-sys: supplied by regulator-dummy
[    6.770272] dc1sw: supplied by regulator-dummy
[    6.770429] dovdd-csi: Bringing 3300000uV into 1800000-1800000uV
[    6.770832] dovdd-csi: supplied by regulator-dummy
[    6.771036] vcc-pl: supplied by regulator-dummy
[    6.771526] vcc-pll-avcc: supplied by regulator-dummy
[    6.772246] vcc-dsi-sensor: supplied by regulator-dummy
[    6.772656] vcc-mipi-io: supplied by regulator-dummy
[    6.773113] avdd-csi: supplied by regulator-dummy
[    6.773535] vcc-wifi-io: supplied by regulator-dummy
[    6.773731] vcc-lpddr: supplied by regulator-dummy
[    6.774473] eldo2: supplied by regulator-dummy
[    6.774641] dvdd-1v8-csi: Bringing 700000uV into 1800000-1800000uV
[    6.775056] dvdd-1v8-csi: supplied by regulator-dummy
[    6.775515] vcc-1v2-hsic: supplied by regulator-dummy
[    6.775761] vdd-cpus: supplied by regulator-dummy
[    6.776204] vcc-rtc: supplied by regulator-dummy
[    6.776730] vcc-lcd-ctp-stk: supplied by regulator-dummy
[    6.776902] vcc-1v8-typec: Bringing 3300000uV into 1800000-1800000uV
[    6.777321] vcc-1v8-typec: supplied by regulator-dummy
[    6.777456] axp20x-rsb sunxi-rsb-3a3: AXP20X driver loaded
[    6.782451] sun50i-a64-pinctrl 1c20800.pinctrl: initialized sunXi PIO driver
[    6.784374] printk: console [ttyS0] disabled
[    6.804576] 1c28000.serial: ttyS0 at MMIO 0x1c28000 (irq = 43, base_baud = 1500000) is a U6_16550A
[    8.112354] printk: console [ttyS0] enabled
[    8.138313] 1c28400.serial: ttyS1 at MMIO 0x1c28400 (irq = 44, base_baud = 1500000) is a U6_16550A
[    8.147461] serial serial0: tty port ttyS1 registered
[    8.175735] 1c28c00.serial: ttyS2 at MMIO 0x1c28c00 (irq = 45, base_baud = 1500000) is a U6_16550A
[    8.187410] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[    8.198654] i2c 0-0028: Fixing up cyclic dependency with 1c19400.phy
[    8.219350] sunxi-mmc 1c0f000.mmc: Got CD GPIO
[    8.219479] sunxi-mmc 1c10000.mmc: allocated mmc-pwrseq
[    8.220645] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[    8.221531] phy id_det change to device
[    8.266734] sunxi-mmc 1c11000.mmc: initialized, max. request size: 2048 KB, uses new timings mode
[    8.266804] sunxi-mmc 1c0f000.mmc: initialized, max. request size: 16384 KB, uses new timings mode
[    8.267048] sunxi-mmc 1c10000.mmc: initialized, max. request size: 16384 KB, uses new timings mode
[    8.291299] mmc1: new high speed SDIO card at address 0001
[    8.342990] mmc0: new high speed SDXC card at address aaaa
[    8.350395] mmcblk0: mmc0:aaaa SL128 119 GiB
[    8.376974]  mmcblk0: p1
[    8.395461] mmc2: new DDR MMC card at address 0001
[    8.401882] mmcblk2: mmc2:0001 DA4032 29.1 GiB
[    8.413515]  mmcblk2: p1 p2
[    8.421336] mmcblk2boot0: mmc2:0001 DA4032 4.00 MiB
[    8.432471] mmcblk2boot1: mmc2:0001 DA4032 4.00 MiB
[    9.251676] phy phy-1c19400.phy.1: Changing dr_mode to 1
[    9.257064] phy id_det change to host
[    9.467698] ehci-platform 1c1a000.usb: EHCI Host Controller
[    9.473691] ehci-platform 1c1a000.usb: new USB bus registered, assigned bus number 1
[    9.481644] ehci-platform 1c1a000.usb: irq 29, io mem 0x01c1a000
[    9.503618] ehci-platform 1c1a000.usb: USB 2.0 started, EHCI 1.00
[    9.509981] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    9.518270] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    9.525513] usb usb1: Product: EHCI Host Controller
[    9.530406] usb usb1: Manufacturer: Linux 5.15-sunxi64 ehci_hcd
[    9.536341] usb usb1: SerialNumber: 1c1a000.usb
[    9.541512] hub 1-0:1.0: USB hub found
[    9.545344] hub 1-0:1.0: 1 port detected
[    9.550407] ehci-platform 1c1b000.usb: EHCI Host Controller
[    9.556236] ehci-platform 1c1b000.usb: new USB bus registered, assigned bus number 2
[    9.564157] ehci-platform 1c1b000.usb: irq 31, io mem 0x01c1b000
[    9.583615] ehci-platform 1c1b000.usb: USB 2.0 started, EHCI 1.00
[    9.589906] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    9.598194] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    9.605427] usb usb2: Product: EHCI Host Controller
[    9.610326] usb usb2: Manufacturer: Linux 5.15-sunxi64 ehci_hcd
[    9.616257] usb usb2: SerialNumber: 1c1b000.usb
[    9.624334] hub 2-0:1.0: USB hub found
[    9.628124] hub 2-0:1.0: 1 port detected
[    9.635395] phy id_det change to host
[    9.847643] ohci-platform 1c1a400.usb: Generic Platform OHCI controller
[    9.854643] ohci-platform 1c1a400.usb: new USB bus registered, assigned bus number 3
[    9.862693] ohci-platform 1c1a400.usb: irq 30, io mem 0x01c1a400
[    9.931928] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.15
[    9.940261] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    9.947516] usb usb3: Product: Generic Platform OHCI controller
[    9.953456] usb usb3: Manufacturer: Linux 5.15-sunxi64 ohci_hcd
[    9.959391] usb usb3: SerialNumber: 1c1a400.usb
[    9.964559] hub 3-0:1.0: USB hub found
[    9.968899] hub 3-0:1.0: 1 port detected
[    9.974077] ohci-platform 1c1b400.usb: Generic Platform OHCI controller
[    9.981006] ohci-platform 1c1b400.usb: new USB bus registered, assigned bus number 4
[    9.988915] ohci-platform 1c1b400.usb: irq 32, io mem 0x01c1b400
[   10.059832] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.15
[   10.068125] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   10.075365] usb usb4: Product: Generic Platform OHCI controller
[   10.081299] usb usb4: Manufacturer: Linux 5.15-sunxi64 ohci_hcd
[   10.087235] usb usb4: SerialNumber: 1c1b400.usb
[   10.092363] hub 4-0:1.0: USB hub found
[   10.096171] hub 4-0:1.0: 1 port detected
[   10.100983] usb0-vbus: supplied by vcc5v0
[   10.105692] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   10.115475] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   10.125383] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[   10.135896] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   10.152946] Freeing unused kernel memory: 3904K
[   10.175843] Run /init as init process
[   11.107441] i2c_gpio: module verification failed: signature and/or required key missing - tainting kernel
[   11.124703] i2c 3-003c: Fixing up cyclic dependency with 1cb0000.csi
[   11.135964] i2c 3-004c: Fixing up cyclic dependency with 1cb0000.csi
[   11.154525] i2c-gpio i2c-csi: using lines 141 (SDA) and 140 (SCL)
[   11.157241] scpi_protocol scpi: SCP Protocol 1.2 Firmware 0.5.10000 version
[   11.168427] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   11.178514] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   11.201709] musb-sunxi 1c19000.usb: Disabling musb host side code due to re-routed phy
[   11.218054] input: 1c21800.lradc as /devices/platform/soc/1c21800.lradc/input/input1
[   11.227798] usb_phy_generic usb_phy_generic.1.auto: supply vcc not found, using dummy regulator
[   11.228156] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   11.237390] usb_phy_generic usb_phy_generic.1.auto: dummy supplies not allowed for exclusive requests
[   11.247397] pinephone-keyboard 2-0015: error -ENXIO: Failed to read device ID
[   11.255396] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   11.271693] axp20x-usb-power-supply axp20x-usb-power-supply: DMA mask not set
[   11.273343] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   11.299133] axp20x-usb-power-supply axp20x-usb-power-supply: Increasing Vbus hold voltage to 4.5V
[   11.317732] cryptd: max_cpu_qlen set to 1000
[   11.328217] Goodix-TS 0-005d: ID 917S, version: 0200
[   11.333668] Goodix-TS 0-005d: Direct firmware load for goodix_917S_cfg.bin failed with error -2
[   11.369024] input: Goodix Capacitive TouchScreen as /devices/platform/soc/1c2ac00.i2c/i2c-0/0-005d/input/input2
[   11.369353] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   11.456027] anx7688 0-0028: Vendor id 0x1f29
[   11.484571] panel-sitronix-st7703 1ca0000.dsi.0: mipi_dsi_attach failed (-517). Is host ready?
[   11.523040] sun4i-drm display-engine: bound 1100000.mixer (ops 0xffffffc008b2ffc0)
[   11.532162] sun4i-drm display-engine: bound 1200000.mixer (ops 0xffffffc008b2ffc0)
[   11.540699] sun4i-drm display-engine: No panel or bridge found... RGB output disabled
[   11.548622] sun4i-drm display-engine: bound 1c0c000.lcd-controller (ops 0xffffffc008b2b6e0)
[   11.557442] sun4i-drm display-engine: bound 1c0d000.lcd-controller (ops 0xffffffc008b2b6e0)
[   11.565935] sun4i-drm display-engine: bound 1ca0000.dsi (ops 0xffffffc008b2ef58)
[   11.574920] sun8i-dw-hdmi 1ee0000.hdmi: Detected HDMI TX controller v1.32a with HDCP (sun8i_dw_hdmi_phy)
[   11.585108] sun8i-dw-hdmi 1ee0000.hdmi: registered DesignWare HDMI I2C bus driver
[   11.593301] sun4i-drm display-engine: bound 1ee0000.hdmi (ops 0xffffffc008b2f2f8)
[   11.603703] [drm] Initialized sun4i-drm 1.0.0 20150629 for display-engine on minor 1
[   11.611804] sun4i-drm display-engine: [drm] Cannot find any crtc or sizes
[   11.841353] Console: switching to colour frame buffer device 90x90
[   11.893523] sun4i-drm display-engine: [drm] fb0: sun4i-drmdrmfb frame buffer device
[   11.917079] sun6i-mipi-dsi 1ca0000.dsi: Attached device xbd599
[   11.923724] panel-sitronix-st7703 1ca0000.dsi.0: 720x1440@60 24bpp dsi 4dl - ready
[   11.926778]  mmcblk2: p1 p2
[   12.136768] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
[   12.153078] device-mapper: uevent: version 1.0.3
[   12.161154] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
[   12.325075] random: cryptsetup: uninitialized urandom read (4 bytes read)
[   13.581206] random: osk-sdl: uninitialized urandom read (16 bytes read)
[   14.104667] random: osk-sdl: uninitialized urandom read (16 bytes read)
[   22.895305] random: crng init done
[   41.951644] ps: disabling
[   41.954698] dc1sw: disabling
[   41.958063] avdd-csi: disabling
[   41.961716] vcc-1v2-hsic: disabling
[   41.965530] usb0-vbus: disabling


I've been able to eliminate several possible issues.

At least part of the kernel is still running. When I plug in a USB cable between the phone's USB port and my computer, it prints messages indicating it is reacting to it.

It wasn't just something peculiar with my old mobian install. I made a fresh install and the issue came right back after a long session of installing and updating software.

It isn't the hardware. Mobian installer and live SD cards boot and run without problem. And the fresh install worked for a while.

It isn't the root filesystem. Using an SD image, I ran a long fsck on the old filesystem and found no problems. On the new install, I chose a completely different filesystem (BTRFS instead of F2FS like before).

It isn't that the boot is just really slow (unless it is absurdly slow). I've left it at the hanged location in boot for an hour and it did not progress.

It isn't the boot loader. I was originally running straight u-boot. For the preparations for the re-install, I put on the latest tow-boot and I am now having the same problems.
Is anyone else having the same issue or have an that's not my neighbor idea of where to look. 
Try disabling the eMMC and booting from an SD card with a fresh OS image. Re-flashing the OS might help if the eMMC is corrupted.
  Reply


Possibly Related Threads…
Thread Author Replies Views Last Post
  How to use an NVMe disk as rootfs Smaking 3 333 11-29-2024, 06:51 AM
Last Post: walter1950
  Chatty Matrix Decryption Key jojuma 2 1,329 02-26-2024, 12:48 PM
Last Post: tophneal
  mobian installed to eMMC - how to install tow-boot grump_fiddle_reinstall 6 4,203 11-22-2023, 11:46 AM
Last Post: aLoop100o
  How to check if tow-boot is installed? jojuma 3 1,883 09-29-2023, 11:29 PM
Last Post: tllim
  Mobian boot failed with zstd message after upgrade. Mahgue 0 1,014 09-01-2023, 11:29 AM
Last Post: Mahgue
  4G data on default startup / boot dcinoz 1 1,252 04-29-2023, 11:24 PM
Last Post: dcinoz
  PinePhone won't boot after software update jb2000 2 2,012 03-17-2023, 07:26 AM
Last Post: Zebulon Walton
  Boot log analyses -> GPU no regulator (mali) found freelectro 0 1,428 03-06-2023, 12:06 PM
Last Post: freelectro
  [solved] Black screen at boot time mdk 0 1,302 01-13-2023, 03:33 AM
Last Post: mdk
  No boot after upgrade: vibration, red LED, then 2 vibrations and yellow LED 3x5co 1 1,422 01-04-2023, 06:46 PM
Last Post: 3x5co

Forum Jump:


Users browsing this thread: 2 Guest(s)