search

jcorporation / myMPDos

A music player image for Raspberry Pi based on Alpine Linux, MPD and myMPD.
https://jcorporation.github.io/myMPDos/
GNU General Public License v3.0
29 stars 6 forks source link

Nothing runs on first boot #38

Closed stratosgear closed 7 months ago

stratosgear commented 7 months ago

Followed the instructions to the letter and although I can ssh to my pi, no mpd/mympd services are running at all (nothing available at https://my.pi.ip)

Redid the installation (burned again a new sd card) but same result.

I noticed that:

# myMPDos repository
MYMPDOS_REPOSITORY="https://raw.githubusercontent.com/jcorporation/myMPDos/master/repository"

resolves to a 404 page. https://raw.githubusercontent.com/jcorporation/myMPDos/master/repository

That doesn't seem right, does it?

stratosgear commented 7 months ago

Hmmm, I guess that url is used as a root folder, based on which some packages are supposed to be looked up and installed?

My process list immediattely after booting the first time looks like:

pi3:~# pstree
init-+-getty
     |-ntpd
     |-sshd---sshd---ash---pstree
     |-syslogd
     `-udhcpc

Am I supposed to be installing something? The docs make it seem that everything should be done for me!

dmesg looks "clean":

pi3:~# dmesg
[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 6.6.14-0-rpi (buildozer@build-3-19-aarch64) (gcc (Alpine 13.2.1_git20231014) 13.2.1 20231014, GNU ld (GNU Binutils) 2.41) #1-Alpine SMP PREEMPT Fri Jan 26 13:41:25 UTC 2024
[    0.000000] KASLR enabled
[    0.000000] random: crng init done
[    0.000000] Machine model: Raspberry Pi 3 Model B Rev 1.2
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created CMA memory pool at 0x0000000037400000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000037400000..0x000000003b3fffff (65536 KiB) map reusable linux,cma
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003b3fffff]
[    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 0x0000000000000000-0x000000003b3fffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000] On node 0, zone DMA: 19456 pages in unavailable ranges
[    0.000000] percpu: Embedded 30 pages/cpu s85224 r8192 d29464 u122880
[    0.000000] pcpu-alloc: s85224 r8192 d29464 u122880 alloc=30*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: kernel page table isolation forced ON by KASLR
[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)
[    0.000000] CPU features: detected: ARM erratum 843419
[    0.000000] CPU features: detected: ARM erratum 845719
[    0.000000] alternatives: applying boot alternatives
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_hdmi=0 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  modules=loop,squashfs,sd-mod,usb-storage quiet console=tty1
[    0.000000] Unknown kernel command line parameters "modules=loop,squashfs,sd-mod,usb-storage", will be passed to user space.
[    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 238896
[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off
[    0.000000] software IO TLB: area num 4.
[    0.000000] software IO TLB: mapped [mem 0x0000000031f80000-0x0000000035f80000] (64MB)
[    0.000000] Memory: 788980K/970752K available (12544K kernel code, 2204K rwdata, 4240K rodata, 3456K init, 1073K bss, 116236K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] trace event string verifier disabled
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu:     RCU event tracing is enabled.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
[    0.000000]  Trampoline 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: bcm2836_arm_irqchip_handle_irq
[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000001] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000401] Console: colour dummy device 80x25
[    0.000416] printk: console [tty1] enabled
[    0.000525] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=76800)
[    0.000546] pid_max: default: 32768 minimum: 301
[    0.000836] LSM: initializing lsm=capability,integrity
[    0.001103] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    0.001134] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    0.002258] cgroup: Disabling memory control group subsystem
[    0.004284] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1.
[    0.004460] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1.
[    0.004767] rcu: Hierarchical SRCU implementation.
[    0.004776] rcu:     Max phase no-delay instances is 1000.
[    0.006851] EFI services will not be available.
[    0.007233] smp: Bringing up secondary CPUs ...
[    0.008041] Detected VIPT I-cache on CPU1
[    0.008186] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[    0.009036] Detected VIPT I-cache on CPU2
[    0.009141] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
[    0.009928] Detected VIPT I-cache on CPU3
[    0.010027] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
[    0.010136] smp: Brought up 1 node, 4 CPUs
[    0.010159] SMP: Total of 4 processors activated.
[    0.010169] CPU features: detected: 32-bit EL0 Support
[    0.010175] CPU features: detected: 32-bit EL1 Support
[    0.010186] CPU features: detected: CRC32 instructions
[    0.010306] CPU: All CPU(s) started at EL2
[    0.010326] alternatives: applying system-wide alternatives
[    0.013466] devtmpfs: initialized
[    0.025936] Enabled cp15_barrier support
[    0.025971] Enabled setend support
[    0.026210] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.026237] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.029359] pinctrl core: initialized pinctrl subsystem
[    0.030114] DMI not present or invalid.
[    0.030792] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.037892] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
[    0.038251] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.038791] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.038957] audit: initializing netlink subsys (disabled)
[    0.039257] audit: type=2000 audit(0.036:1): state=initialized audit_enabled=0 res=1
[    0.040126] thermal_sys: Registered thermal governor 'step_wise'
[    0.040237] cpuidle: using governor menu
[    0.040517] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.040663] ASID allocator initialised with 32768 entries
[    0.041746] Serial: AMBA PL011 UART driver
[    0.046882] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.056264] raspberrypi-firmware soc:firmware: Attached to firmware from 2023-12-08T12:42:10, variant start
[    0.060274] raspberrypi-firmware soc:firmware: Firmware hash is e02d33b3122450accf9dea471a177d3b5623f5ad
[    0.068398] Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL
[    0.068411] Modules: 0 pages in range for non-PLT usage
[    0.068418] Modules: 518352 pages in range for PLT usage
[    0.074422] bcm2835-dma 3f007000.dma-controller: DMA legacy API manager, dmachans=0x1
[    0.075921] iommu: Default domain type: Translated
[    0.075933] iommu: DMA domain TLB invalidation policy: strict mode
[    0.077901] SCSI subsystem initialized
[    0.078192] usbcore: registered new interface driver usbfs
[    0.078247] usbcore: registered new interface driver hub
[    0.078311] usbcore: registered new device driver usb
[    0.078784] pps_core: LinuxPPS API ver. 1 registered
[    0.078793] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.078828] PTP clock support registered
[    0.080403] vgaarb: loaded
[    0.081007] clocksource: Switched to clocksource arch_sys_counter
[    1.715902] VFS: Disk quotas dquot_6.6.0
[    1.715987] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.716144] FS-Cache: Loaded
[    1.716459] CacheFiles: Loaded
[    1.727846] NET: Registered PF_INET protocol family
[    1.728113] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    1.730156] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
[    1.730199] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    1.730290] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    1.730407] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
[    1.730774] TCP: Hash tables configured (established 8192 bind 8192)
[    1.731169] MPTCP token hash table entries: 1024 (order: 2, 24576 bytes, linear)
[    1.731262] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.731315] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.731579] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    1.732257] RPC: Registered named UNIX socket transport module.
[    1.732269] RPC: Registered udp transport module.
[    1.732277] RPC: Registered tcp transport module.
[    1.732283] RPC: Registered tcp-with-tls transport module.
[    1.732290] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.732322] PCI: CLS 0 bytes, default 64
[    1.732925] kvm [1]: IPA Size Limit: 40 bits
[    1.732980] Trying to unpack rootfs image as initramfs...
[    1.734746] kvm [1]: Hyp mode initialized successfully
[    1.738870] Initialise system trusted keyrings
[    1.739412] workingset: timestamp_bits=46 max_order=18 bucket_order=0
[    1.739513] zbud: loaded
[    1.741141] NFS: Registering the id_resolver key type
[    1.741190] Key type id_resolver registered
[    1.741199] Key type id_legacy registered
[    1.741236] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.741247] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.742419] Key type asymmetric registered
[    1.742444] Asymmetric key parser 'x509' registered
[    1.742529] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    1.742780] io scheduler mq-deadline registered
[    1.742793] io scheduler kyber registered
[    1.742856] io scheduler bfq registered
[    1.758214] bcm2708_fb soc:fb: FB found 1 display(s)
[    1.768620] Console: switching to colour frame buffer device 82x26
[    1.771464] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 656x416
[    1.778083] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    1.782645] bcm2835-rng 3f104000.rng: hwrng registered
[    1.783687] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.805661] brd: module loaded
[    1.817642] loop: module loaded
[    1.819148] Loading iSCSI transport class v2.0-870.
[    1.831729] usbcore: registered new device driver r8152-cfgselector
[    1.831800] usbcore: registered new interface driver r8152
[    1.831852] usbcore: registered new interface driver lan78xx
[    1.831903] usbcore: registered new interface driver smsc95xx
[    1.832168] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    2.176339] Freeing initrd memory: 5664K
[    2.560507] Core Release: 2.80a
[    2.560535] Setting default values for core params
[    2.560560] Finished setting default values for core params
[    2.760909] Using Buffer DMA mode
[    2.760918] Periodic Transfer Interrupt Enhancement - disabled
[    2.760926] Multiprocessor Interrupt Enhancement - disabled
[    2.760933] OTG VER PARAM: 0, OTG VER FLAG: 0
[    2.760950] Dedicated Tx FIFOs mode
[    2.761645] 
[    2.761653] WARN::dwc_otg_hcd_init:1072: FIQ DMA bounce buffers: virt = ffffffc080481000 dma = 0x00000000f7810000 len=9024
[    2.761695] FIQ FSM acceleration enabled for :
[    2.761695] Non-periodic Split Transactions
[    2.761695] Periodic Split Transactions
[    2.761695] High-Speed Isochronous Endpoints
[    2.761695] Interrupt/Control Split Transaction hack enabled
[    2.761709] dwc_otg: Microframe scheduler enabled
[    2.761740] 
[    2.761745] WARN::hcd_init_fiq:496: MPHI regs_base at ffffffc08006d000
[    2.761802] dwc_otg 3f980000.usb: DWC OTG Controller
[    2.761839] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    2.761881] dwc_otg 3f980000.usb: irq 74, io mem 0x00000000
[    2.761933] Init: Port Power? op_state=1
[    2.761940] Init: Power Port (0)
[    2.762216] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06
[    2.762235] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.762249] usb usb1: Product: DWC OTG Controller
[    2.762261] usb usb1: Manufacturer: Linux 6.6.14-0-rpi dwc_otg_hcd
[    2.762272] usb usb1: SerialNumber: 3f980000.usb
[    2.763313] hub 1-0:1.0: USB hub found
[    2.763388] hub 1-0:1.0: 1 port detected
[    2.764670] dwc_otg: FIQ enabled
[    2.764689] dwc_otg: NAK holdoff enabled
[    2.764696] dwc_otg: FIQ split-transaction FSM enabled
[    2.764715] Module dwc_common_port init
[    2.765748] usbcore: registered new interface driver uas
[    2.765854] usbcore: registered new interface driver usb-storage
[    2.766822] mousedev: PS/2 mouse device common for all mice
[    2.772084] sdhci: Secure Digital Host Controller Interface driver
[    2.772109] sdhci: Copyright(c) Pierre Ossman
[    2.772607] sdhci-pltfm: SDHCI platform and OF driver helper
[    2.775316] ledtrig-cpu: registered to indicate activity on CPUs
[    2.775614] hid: raw HID events driver (C) Jiri Kosina
[    2.775700] usbcore: registered new interface driver usbhid
[    2.775706] usbhid: USB HID core driver
[    2.780723] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
[    2.781451] Key type dns_resolver registered
[    2.813608] registered taskstats version 1
[    2.813837] Loading compiled-in X.509 certificates
[    2.822934] Key type .fscrypt registered
[    2.822951] Key type fscrypt-provisioning registered
[    2.830447] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    2.830727] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    2.832351] sdhost: log_buf @ 00000000e3185224 (c405f000)
[    2.873097] Indeed it is in host mode hprt0 = 00021501
[    2.877114] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    2.877242] uart-pl011 3f201000.serial: there is not valid maps for state default
[    2.878616] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    2.879234] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 99, base_baud = 0) is a PL011 rev2
[    2.881072] of_cfs_init
[    2.881170] of_cfs_init: OK
[    2.881355] clk: Disabling unused clocks
[    2.885731] Freeing unused kernel memory: 3456K
[    2.913070] Run /init as init process
[    2.913079]   with arguments:
[    2.913082]     /init
[    2.913085]   with environment:
[    2.913088]     HOME=/
[    2.913092]     TERM=linux
[    2.913095]     modules=loop,squashfs,sd-mod,usb-storage
[    2.940560] mmc0: host does not support reading read-only switch, assuming write-enable
[    2.945690] mmc0: Host Software Queue enabled
[    2.945727] mmc0: new high speed SDHC card at address aaaa
[    2.946665] mmcblk0: mmc0:aaaa SC16G 14.8 GiB
[    2.950514]  mmcblk0: p1 p2
[    2.951085] mmcblk0: mmc0:aaaa SC16G 14.8 GiB (quirks 0x00004000)
[    2.956835] Alpine Init 3.9.1-r0
[    2.959980] Loading boot drivers...
[    2.971663] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    2.973741] Loading boot drivers: ok.
[    2.980443] Mounting boot media...
[    3.069069] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    3.069391] Indeed it is in host mode hprt0 = 00001101
[    3.285471] usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00
[    3.285505] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.289689] hub 1-1:1.0: USB hub found
[    3.289887] hub 1-1:1.0: 5 ports detected
[    3.585144] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    3.685441] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00
[    3.685472] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.688351] smsc95xx v2.0.0
[    3.784414] SMSC LAN8700 usb-001:003:01: attached PHY driver (mii_bus:phy_addr=usb-001:003:01, irq=184)
[    3.785783] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:8c:b8:9c
[    3.868479] EXT4-fs (mmcblk0p2): orphan cleanup on readonly fs
[    3.870983] EXT4-fs (mmcblk0p2): mounted filesystem 7a18cfd9-bc4e-4040-be5e-edaa18ed0467 ro with ordered data mode. Quota mode: none.
[    4.147630] Mounting boot media: ok.
[    4.155643] Loading user settings from /media/mmcblk0p2/pi3.apkovl.tar.gz...
[    4.204965] Loading user settings from /media/mmcblk0p2/pi3.apkovl.tar.gz: ok.
[    4.269097] EXT4-fs (mmcblk0p2): re-mounted 7a18cfd9-bc4e-4040-be5e-edaa18ed0467 ro. Quota mode: none.
[    4.431097] Installing packages to root filesystem...
[    4.497850] EXT4-fs (mmcblk0p2): re-mounted 7a18cfd9-bc4e-4040-be5e-edaa18ed0467 r/w. Quota mode: none.
[    5.503240] EXT4-fs (mmcblk0p2): re-mounted 7a18cfd9-bc4e-4040-be5e-edaa18ed0467 ro. Quota mode: none.
[    5.513498] Installing packages to root filesystem: ok.
[    8.000954] loop0: detected capacity change from 0 to 59672
[    8.009182] F2FS-fs (loop0): Magic Mismatch, valid(0xf2f52010) - read(0xa38532fd)
[    8.009212] F2FS-fs (loop0): Can't find valid F2FS filesystem in 1th superblock
[    8.009296] F2FS-fs (loop0): Magic Mismatch, valid(0xf2f52010) - read(0x9c34dbac)
[    8.009308] F2FS-fs (loop0): Can't find valid F2FS filesystem in 2th superblock
[    9.767768] rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000
[    9.770845] rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem
[    9.916395] mc: Linux media interface: v0.10
[    9.972614] videodev: Linux video capture interface: v2.00
[    9.992815] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    9.993753] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    9.993773] [vc_sm_connected_init]: start
[    9.997579] [vc_sm_connected_init]: installed successfully
[    9.998789] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[   10.006459] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[   10.047590] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[   10.051273] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[   10.051317] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[   10.053441] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[   10.053477] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[   10.057723] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[   10.057758] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[   10.062467] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[   10.062504] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[   10.064810] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[   10.064852] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[   10.066919] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[   10.074136] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[   10.074580] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[   10.074945] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[   10.075319] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[   10.075347] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[   10.075360] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[   10.075367] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[   10.075375] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[   10.088533] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[   10.093112] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[   10.093651] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[   10.094147] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[   10.094181] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[   10.094196] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[   10.094205] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[   10.094213] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[   10.094667] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[   10.108492] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[   11.200049] NET: Registered PF_PACKET protocol family
[   11.310324] NET: Registered PF_INET6 protocol family
[   11.311408] Segment Routing with IPv6
[   11.311439] In-situ OAM (IOAM) with IPv6
[   11.513099] EXT4-fs (mmcblk0p2): re-mounted 7a18cfd9-bc4e-4040-be5e-edaa18ed0467 ro. Quota mode: none.
[   12.272349] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
[   12.276194] smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off
pi3:~#

How else can I debug this?

Thanks, btw!!!

jcorporation commented 7 months ago

The 404 for the repository url is correct, there is no index page and directory listing is disabled.

On first boot the bootstrap script should run and configure myMPDos accordingly to your created bootstrap.txt file. It removes itself and reboots afterwards. Are you seeing any errors in this stage?

stratosgear commented 7 months ago

Hmm, the pi3 is headless. I'll have to scramble to get a display to it to see what happens... :(

I found the mympdos/overlay/etc/local.d/mympdos-bootstrap.start file that is doing the bootstrapping, and tried to do some redirects to a log file, but I see that the bootstrap is doing some heavy duty sd card repartitioning so I have nowhere to output (second :( )

I guess I really have to scramble for an external monitor... :sniff:

jcorporation commented 7 months ago

Logging the output of the bootstrap script is a good idea. I will look into this for the next release.

stratosgear commented 7 months ago

Ok. Still painful! Even with an attached monitor the output scrolls too fast to read it all. Had to boot a couple of times (and every time burning again the SD card from scratch and editing bootstrap.txt) and STILL not able to catch the error. :(

Had to try again and take a video of the boot process.

The mistake was on a custom package s3fs that in alpine it seems it is named s3fs-fuse. The bootstrapper fails with a:

ERROR: unable to select packages:
  s3fs (no such package)
    required by: world(s3fs)

User error, indeed, but the end effect was REALLY hard and inconvenient to debug.

I think a permanent log would be a GREAT addition!

Sorry to sound so negative, I am not. This just took MUCH longer than I was expecting, and I was not prepared for that.

jcorporation commented 7 months ago

Ok, you have added an invalid package name to the EXTRA_SOFTWARE variable?

jcorporation commented 7 months ago

https://github.com/jcorporation/myMPDos/commit/90f01f31b0ba063cfbef30efe77f4d0bacec97b7

stratosgear commented 7 months ago

Hey thanks!

I cloned the repo, run the build.sh script from the devel branch and burned the ./images/myMPDos-aarch64-1.4.2-20240412.img to my sd card with: sudo dd bs=4M if=./images/myMPDos-aarch64-1.4.2-20240412.img of=/dev/sde status=progress oflag=sync

I mounted the sdcard with sudo mount /dev/sde1 /mnt/sdcard and I copied a simplyfied bootstrap of:

# Simple bootstrap configuration file for myMPDos
# For all available options refer to bootstrap-advanced.txt

ROOT_PASSWORD="someLongPass"
TIMEZONE="Europe/Madrid"

# If WLAN_ENABLED is not set to true eth0 is configured
WLAN_ENABLE="false"
#WLAN_SSID="ssid of your wlan"
#WLAN_PSK="psk of the ssid"

AUDIOHAT="iqaudio-dacplus"

Booting with this sd card, I get to connect to the pi butpstree still gives:

myMPDos:~# pstree
init-+-getty
     |-ntpd
     |-sshd---sshd---ash---pstree
     |-syslogd
     `-udhcpc

and ofcourse nothing is available on https:// (neither on http)

I poweroff the pi and mount the sd card on my desktop and I find the new bootrstap.log that looks "ok":

✗  cat bootstrap.log

myMPDos 1.4.2 bootstrap script

 *   WARNING: you are stopping a sysinit service
 *   Executing: /lib/rc/sh/openrc-run.sh /lib/rc/sh/openrc-run.sh /etc/init.d/modloop stop
 *   Unmounting /.modloop ... [ ok ]
Creating data partition on sd card

The number of cylinders for this disk is set to 111129.
There is nothing wrong with that, but this is larger than 1024,
and could in certain setups cause problems with:
1) software that runs at boot time (e.g., old versions of LILO)
2) booting and partitioning software from other OSs
   (e.g., DOS FDISK, OS/2 FDISK)

Command (m for help): Partition type
   p   primary partition (1-4)
   e   extended
Partition number (1-4): First sector (1048576-31116287, default 1048576): Using default value 1048576
Last sector or +size{,K,M,G,T} (1048576-31116287, default 31116287): Using default value 31116287

Command (m for help): The partition table has been altered.
Calling ioctl() to re-read partition table
Waiting for /dev/mmcblk0p1 found with FS
 *   Executing: /lib/rc/sh/openrc-run.sh /lib/rc/sh/openrc-run.sh /etc/init.d/modloop start
 *   Mounting modloop  ...
 *   Verifying modloop
 [ ok ]
Waiting for /dev/mmcblk0p2 found with NOFS
Formating data partition on sd card
(1/5) Installing libblkid (2.39.3-r0)
(2/5) Installing libcom_err (1.47.0-r5)
(3/5) Installing e2fsprogs-libs (1.47.0-r5)
(4/5) Installing libuuid (2.39.3-r0)
(5/5) Installing e2fsprogs (1.47.0-r5)
Executing busybox-1.36.1-r15.trigger
OK: 16 MiB in 31 packages
mke2fs 1.47.0 (5-Feb-2023)
Discarding device blocks: done                            
Creating filesystem with 3758464 4k blocks and 940240 inodes
Filesystem UUID: 34b7b943-98c5-4dc6-b77c-04f699465326
Superblock backups stored on blocks: 
    32768, 98304, 163840, 229376, 294912, 819200, 884736, 1605632, 2654208

Allocating group tables: done                            
Writing inode tables: done                            
Creating journal (16384 blocks): done
Writing superblocks and filesystem accounting information: done   

Mounting data partition on sd card

Filesystem usage
Filesystem                Size      Used Available Use% Mounted on
devtmpfs                 10.0M         0     10.0M   0% /dev
shm                     421.7M         0    421.7M   0% /dev/shm
tmpfs                   421.7M     15.3M    406.4M   4% /
tmpfs                   168.7M     48.0K    168.6M   0% /run
/dev/mmcblk0p1          511.7M     84.2M    427.5M  16% /media/mmcblk0p1
/dev/loop0               29.3M     29.3M         0 100% /.modloop
/dev/mmcblk0p2           14.0G      2.0M     13.3G   0% /media/mmcblk0p2

Mounts
sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime)
devtmpfs on /dev type devtmpfs (rw,nosuid,noexec,relatime,size=10240k,nr_inodes=98622,mode=755)
proc on /proc type proc (rw,nosuid,nodev,noexec,relatime)
devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000)
shm on /dev/shm type tmpfs (rw,nosuid,nodev,noexec,relatime)
tmpfs on / type tmpfs (rw,relatime,mode=755)
tmpfs on /run type tmpfs (rw,nosuid,nodev,size=172728k,nr_inodes=819200,mode=755)
mqueue on /dev/mqueue type mqueue (rw,nosuid,nodev,noexec,relatime)
securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime)
debugfs on /sys/kernel/debug type debugfs (rw,nosuid,nodev,noexec,relatime)
configfs on /sys/kernel/config type configfs (rw,nosuid,nodev,noexec,relatime)
pstore on /sys/fs/pstore type pstore (rw,nosuid,nodev,noexec,relatime)
tracefs on /sys/kernel/debug/tracing type tracefs (rw,nosuid,nodev,noexec,relatime)
/dev/mmcblk0p1 on /media/mmcblk0p1 type vfat (rw,relatime,fmask=0022,dmask=0022,codepage=437,iocharset=ascii,shortname=mixed,errors=remount-ro)
/dev/loop0 on /.modloop type squashfs (ro,relatime,errors=continue)
/dev/mmcblk0p2 on /media/mmcblk0p2 type ext4 (ro,noatime)

Configuring eth0
 *   Executing: /lib/rc/sh/openrc-run.sh /lib/rc/sh/openrc-run.sh /etc/init.d/networking start
 *   Starting networking ...
 *     lo ... [ ok ]
 *     eth0 ...udhcpc: started, v1.36.1
udhcpc: broadcasting discover
udhcpc: broadcasting select for 192.168.3.90, server 192.168.3.1
udhcpc: lease of 192.168.3.90 obtained from 192.168.3.1, lease time 4294967295
 [ ok ]
Checking network connectivityOK
Setup NTP
 *   service ntpd added to runlevel default
 *   Executing: /lib/rc/sh/openrc-run.sh /lib/rc/sh/openrc-run.sh /etc/init.d/ntpd start
 *   Starting busybox ntpd ...
 *   start-stop-daemon: fopen `/run/ntpd.pid': No such file or directory
 *   Detaching to start `/usr/sbin/ntpd' ... [ ok ]
Setup OpenSSH
 *   service sshd added to runlevel default
 *   Caching service dependencies ... [ ok ]
ssh-keygen: generating new host keys: RSA ECDSA ED25519 
 *   Starting sshd ...
 *   start-stop-daemon: fopen `/run/sshd.pid': No such file or directory
 *   Detaching to start `/usr/sbin/sshd' ... [ ok ]
Enabling ssh root login
 *   Executing: /lib/rc/sh/openrc-run.sh /lib/rc/sh/openrc-run.sh /etc/init.d/sshd stop
 *   Stopping sshd ...
 *   Will stop /usr/sbin/sshd
 *   Will stop PID 2877
 *   Will stop processes of `/usr/sbin/sshd'

 *   Sending signal 15 to PID 2877 ... [ ok ]
 [ ok ]
 [ ok ]
 *   Executing: /lib/rc/sh/openrc-run.sh /lib/rc/sh/openrc-run.sh /etc/init.d/sshd start
 *   Starting sshd ...
 *   start-stop-daemon: fopen `/run/sshd.pid': No such file or directory
 *   Detaching to start `/usr/sbin/sshd' ... [ ok ]
Setting keymap to us us
(1/1) Installing kbd-bkeymaps (2.6.3-r0)
OK: 26 MiB in 43 packages
 *   Caching service dependencies ... [ ok ]
 *   Setting keymap ... [ ok ]
(1/1) Purging kbd-bkeymaps (2.6.3-r0)
OK: 23 MiB in 42 packages
Setting up apk cache
Adding repositories and upgrade
Added mirror dl-cdn.alpinelinux.org
Updating repository indexes... done.
Adding myMPDos archiv signing public key
Upgrading all packages
fetch http://dl-cdn.alpinelinux.org/alpine/v3.19/main/aarch64/APKINDEX.tar.gz
fetch http://dl-cdn.alpinelinux.org/alpine/v3.19/community/aarch64/APKINDEX.tar.gz
fetch https://raw.githubusercontent.com/jcorporation/myMPDos/master/repository/aarch64/APKINDEX.tar.gz
3.19.1 [/media/mmcblk0p1/apks]
v3.19.1-384-gb99f0a6854c [http://dl-cdn.alpinelinux.org/alpine/v3.19/main]
v3.19.1-391-g04ed6b769a6 [http://dl-cdn.alpinelinux.org/alpine/v3.19/community]
myMPDos [https://raw.githubusercontent.com/jcorporation/myMPDos/master/repository]
OK: 22911 distinct packages available
Upgrading critical system libraries and apk-tools:
(1/1) Upgrading apk-tools (2.14.0-r5 -> 2.14.3-r1)
Executing busybox-1.36.1-r15.trigger
Continuing the upgrade transaction with new apk-tools:
fetch http://dl-cdn.alpinelinux.org/alpine/v3.19/main/aarch64/APKINDEX.tar.gz
fetch http://dl-cdn.alpinelinux.org/alpine/v3.19/community/aarch64/APKINDEX.tar.gz
fetch https://raw.githubusercontent.com/jcorporation/myMPDos/master/repository/aarch64/APKINDEX.tar.gz
(1/4) Upgrading ca-certificates-bundle (20230506-r0 -> 20240226-r0)
(2/4) Upgrading libcrypto3 (3.1.4-r5 -> 3.1.4-r6)
(3/4) Upgrading libssl3 (3.1.4-r5 -> 3.1.4-r6)
(4/4) Upgrading openssl (3.1.4-r5 -> 3.1.4-r6)
Executing busybox-1.36.1-r15.trigger
OK: 23 MiB in 42 packages
Installing packages: mympdos-base mympd mympdos-libmpdclient mympdos-mpc doas alsa-utils rng-tools usbutils raspberrypi busybox-extras net-tools mpg123 mympdos-libgpiod2 mygpiod mympdos-mpd-stable
(1/153) Installing dialog (1.3.20231002-r0)
(2/153) Installing alsa-ucm-conf (1.2.10-r0)
(3/153) Installing alsa-lib (1.2.10-r0)
(4/153) Installing fftw-single-libs (3.3.10-r5)
(5/153) Installing libformw (6.4_p20231125-r0)
(6/153) Installing libmenuw (6.4_p20231125-r0)
(7/153) Installing libpanelw (6.4_p20231125-r0)
(8/153) Installing alsa-utils (1.2.10-r0)
(9/153) Installing alsa-utils-openrc (1.2.10-r0)
(10/153) Installing busybox-extras (1.36.1-r15)
Executing busybox-extras-1.36.1-r15.post-install
(11/153) Installing doas (6.8.2-r6)
(12/153) Installing mpg123-libs (1.32.3-r0)
(13/153) Installing mpg123 (1.32.3-r0)
(14/153) Installing mympdos-libmpdclient (2.23-r1)
(15/153) Installing ca-certificates (20240226-r0)
(16/153) Installing brotli-libs (1.1.0-r1)
(17/153) Installing c-ares (1.27.0-r0)
(18/153) Installing libunistring (1.1-r2)
(19/153) Installing libidn2 (2.3.4-r4)
(20/153) Installing nghttp2-libs (1.58.0-r0)
(21/153) Installing libcurl (8.5.0-r0)
(22/153) Installing curl (8.5.0-r0)
(23/153) Installing lua5.4-libs (5.4.6-r1)
(24/153) Installing readline (8.2.1-r2)
(25/153) Installing lua5.4 (5.4.6-r1)
(26/153) Installing mympdos-libgpiod2 (2.1-r0)
(27/153) Installing mygpiod (0.7.0-r0)
Executing mygpiod-0.7.0-r0.pre-install
Executing mygpiod-0.7.0-r0.post-install
myGPIOd installed
Modify /etc/mygpiod.conf to suit your needs.
(28/153) Installing libid3tag (0.16.2-r2)
(29/153) Installing libogg (1.3.5-r5)
(30/153) Installing libflac (1.4.3-r1)
(31/153) Installing flac (1.4.3-r1)
(32/153) Installing pcre2 (10.42-r2)
(33/153) Installing popt (1.19-r3)
(34/153) Installing slang (2.3.3-r3)
(35/153) Installing newt (0.52.24-r0)
(36/153) Installing mympd (14.1.1-r0)
Executing mympd-14.1.1-r0.pre-install
(37/153) Installing raspberrypi-utils-vcgencmd (0.20240203-r0)
(38/153) Installing mympdos-base (0.9.2-r0)
(39/153) Installing mympdos-mpc (0.36-r0)
(40/153) Installing libSvtAv1Enc (1.7.0-r0)
(41/153) Installing aom-libs (3.7.1-r0)
(42/153) Installing libxau (1.0.11-r3)
(43/153) Installing libmd (1.1.0-r0)
(44/153) Installing libbsd (0.11.7-r3)
(45/153) Installing libxdmcp (1.1.4-r3)
(46/153) Installing libxcb (1.16-r0)
(47/153) Installing libx11 (1.8.7-r0)
(48/153) Installing hwdata-pci (0.377.2-r0)
(49/153) Installing libpciaccess (0.17-r2)
(50/153) Installing libdrm (2.4.118-r0)
(51/153) Installing libxext (1.3.5-r3)
(52/153) Installing libxfixes (6.0.1-r3)
(53/153) Installing libffi (3.4.4-r3)
(54/153) Installing wayland-libs-client (1.22.0-r4)
(55/153) Installing libva (2.20.0-r0)
(56/153) Installing libvdpau (1.5-r2)
(57/153) Installing ffmpeg-libavutil (6.1.1-r0)
(58/153) Installing libdav1d (1.3.0-r1)
(59/153) Installing libgcc (13.2.1_git20231014-r0)
(60/153) Installing libstdc++ (13.2.1_git20231014-r0)
(61/153) Installing libhwy (1.0.7-r0)
(62/153) Installing lcms2 (2.15-r4)
(63/153) Installing libjxl (0.8.2-r0)
(64/153) Installing lame-libs (3.100-r5)
(65/153) Installing opus (1.4-r0)
(66/153) Installing rav1e-libs (0.6.6-r2)
(67/153) Installing libgomp (13.2.1_git20231014-r0)
(68/153) Installing soxr (0.1.3-r7)
(69/153) Installing ffmpeg-libswresample (6.1.1-r0)
(70/153) Installing libtheora (1.1.1-r18)
(71/153) Installing libvorbis (1.3.7-r2)
(72/153) Installing libvpx (1.13.1-r0)
(73/153) Installing libsharpyuv (1.3.2-r0)
(74/153) Installing libwebp (1.3.2-r0)
(75/153) Installing libwebpmux (1.3.2-r0)
(76/153) Installing x264-libs (0.164_git20231001-r0)
(77/153) Installing numactl (2.0.16-r4)
(78/153) Installing x265-libs (3.5-r4)
(79/153) Installing xvidcore (1.3.7-r2)
(80/153) Installing ffmpeg-libavcodec (6.1.1-r0)
(81/153) Installing libexpat (2.6.2-r0)
(82/153) Installing libbz2 (1.0.8-r6)
(83/153) Installing libpng (1.6.40-r0)
(84/153) Installing freetype (2.13.2-r0)
(85/153) Installing fontconfig (2.14.2-r4)
(86/153) Installing fribidi (1.0.13-r0)
(87/153) Installing libintl (0.22.3-r0)
(88/153) Installing libmount (2.39.3-r0)
(89/153) Installing glib (2.78.4-r0)
(90/153) Installing graphite2 (1.3.14-r6)
(91/153) Installing harfbuzz (8.3.0-r0)
(92/153) Installing libunibreak (5.1-r3)
(93/153) Installing libass (0.17.1-r1)
(94/153) Installing libbluray (1.3.4-r1)
(95/153) Installing libopenmpt (0.7.3-r1)
(96/153) Installing cjson (1.7.17-r0)
(97/153) Installing mbedtls (2.28.8-r0)
(98/153) Installing librist (0.2.10-r0)
(99/153) Installing libsrt (1.5.3-r0)
(100/153) Installing libssh (0.10.6-r0)
(101/153) Installing xz-libs (5.4.5-r0)
(102/153) Installing libxml2 (2.11.7-r0)
(103/153) Installing libsodium (1.0.19-r0)
(104/153) Installing libzmq (4.3.5-r2)
(105/153) Installing ffmpeg-libavformat (6.1.1-r0)
(106/153) Installing serd-libs (0.32.0-r0)
(107/153) Installing zix-libs (0.4.2-r0)
(108/153) Installing sord-libs (0.16.16-r0)
(109/153) Installing sratom (0.6.16-r0)
(110/153) Installing lilv-libs (0.24.22-r0)
(111/153) Installing glslang-libs (1.3.261.1-r0)
(112/153) Installing libdovi (3.2.0-r1)
(113/153) Installing spirv-tools (1.3.261.1-r0)
(114/153) Installing shaderc (2023.7-r0)
(115/153) Installing vulkan-loader (1.3.261.1-r0)
(116/153) Installing libplacebo (6.338.1-r0)
(117/153) Installing ffmpeg-libpostproc (6.1.1-r0)
(118/153) Installing ffmpeg-libswscale (6.1.1-r0)
(119/153) Installing vidstab (1.1.1-r0)
(120/153) Installing zimg (3.0.5-r2)
(121/153) Installing ffmpeg-libavfilter (6.1.1-r0)
(122/153) Installing faad2-libs (2.11.1-r0)
(123/153) Installing fmt (10.1.1-r0)
(124/153) Installing icu-data-en (74.1-r0)
Executing icu-data-en-74.1-r0.post-install
*
* If you need ICU with non-English locales and legacy charset support, install
* package icu-data-full.
*
(125/153) Installing icu-libs (74.1-r0)
(126/153) Installing libmad (0.15.1b-r9)
(127/153) Installing libnfs (5.0.2-r0)
(128/153) Installing libsamplerate (0.2.2-r3)
(129/153) Installing sqlite-libs (3.44.2-r0)
(130/153) Installing liburing (2.5-r1)
(131/153) Installing mympdos-mpd-stable (0.23.15-r0)
Executing mympdos-mpd-stable-0.23.15-r0.pre-install
(132/153) Installing mympdos-mpd-stable-openrc (0.23.15-r0)
(133/153) Installing mii-tool (2.10-r3)
(134/153) Installing net-tools (2.10-r3)
(135/153) Installing raspberrypi-userland-libs (0.20231020-r0)
(136/153) Installing raspberrypi-userland (0.20231020-r0)
(137/153) Installing jitterentropy-library (3.4.1-r0)
(138/153) Installing rng-tools (6.16-r3)
Executing rng-tools-6.16-r3.pre-install
(139/153) Installing rng-tools-openrc (6.16-r3)
(140/153) Installing hwdata-usb (0.377.2-r0)
(141/153) Installing udev-init-scripts (35-r1)
(142/153) Installing udev-init-scripts-openrc (35-r1)
(143/153) Installing eudev-libs (3.2.14-r0)
(144/153) Installing zstd-libs (1.5.5-r8)
(145/153) Installing kmod-libs (31-r2)
(146/153) Installing eudev (3.2.14-r0)
(147/153) Installing raspberrypi-userland-udev (0.20231020-r0)
Executing raspberrypi-userland-udev-0.20231020-r0.post-install
(148/153) Installing eudev-openrc (3.2.14-r0)
(149/153) Installing pkgconf (2.1.0-r0)
(150/153) Installing eudev-dev (3.2.14-r0)
(151/153) Installing libusb (1.0.26-r3)
(152/153) Installing libusb-dev (1.0.26-r3)
(153/153) Installing usbutils (017-r0)
Executing busybox-1.36.1-r15.trigger
Executing ca-certificates-20240226-r0.trigger
Executing glib-2.78.4-r0.trigger
Executing eudev-3.2.14-r0.trigger
OK: 153 MiB in 195 packages
Creating user and groups
Creating files in data partition
Installing myMPD scripts
'/usr/local/defaults/mympd-scripts/RW-Library.lua' -> '/var/lib/mympd/scripts/RW-Library.lua'
'/usr/local/defaults/mympd-scripts/Reboot.lua' -> '/var/lib/mympd/scripts/Reboot.lua'
'/usr/local/defaults/mympd-scripts/Shutdown.lua' -> '/var/lib/mympd/scripts/Shutdown.lua'
Setting defaults
Enable automatic reconfiguration of MPD
Enable automount
Configuring MPD
Configuring sound devices
  Soundcard IQaudIODAC - IQaudIO DAC HiFi pcm512x-hifi-0
    Mixer: none
Updating MPD configuration
Unmuting soundcards
0:Analogue
0:Digital
Enabling boot services
 *   service rngd added to runlevel boot
 *   service networking added to runlevel boot
 *   rc-update: service `urandom' does not exist
 *   service alsa added to runlevel boot
Enabling default services
 *   service mpd added to runlevel default
 *   service mympd added to runlevel default
Setting root password
Changing password for root
New password: 
Retype password: 
passwd: password for root changed by root
Setting usercfg.txt
Removing installation files
Removing wlan packages
OK: 153 MiB in 195 packages
Removing obsolet users and groups
Syncing apk cache
deleting APKINDEX.b2c94760.tar.gz
deleting APKINDEX.be78cdff.tar.gz
deleting APKINDEX.8cde5983.tar.gz
Cleaning up
Create myMPD configuration
NOTICE   mympd     Cache dir: "/var/cache/mympd" created
NOTICE   mympd     Work dir: "/var/lib/mympd"
NOTICE   mympd     Config dir: "/var/lib/mympd/config" created
NOTICE   mympd     Dropping privileges to user "mympd"
NOTICE   mympd     SSL cert dir: "/var/lib/mympd/ssl" created
NOTICE   mympd     Creating self signed ca certificate
NOTICE   mympd     Creating server certificate
NOTICE   mympd     Set server certificate san to: DNS:localhost,DNS:ip6-localhost,DNS:ip6-loopback,DNS:myMPDos,IP:127.0.0.1,IP:192.168.3.90,IP:::1,IP:fe80::ba27:ebff:fe8c:b89c
Created myMPD config
Exiting gracefully, thank you for using myMPD
Trusting myMPD CA
Saving configuration
Archiving the following files:
etc/
etc/apk/arch
etc/apk/keys/alpine-devel@lists.alpinelinux.org-524d27bb.rsa.pub
etc/apk/keys/alpine-devel@lists.alpinelinux.org-58199dcc.rsa.pub
etc/apk/keys/alpine-devel@lists.alpinelinux.org-616a9724.rsa.pub
etc/apk/keys/alpine-devel@lists.alpinelinux.org-616adfeb.rsa.pub
etc/apk/keys/alpine-devel@lists.alpinelinux.org-616ae350.rsa.pub
etc/apk/keys/build-6618eef4.rsa.pub
etc/apk/keys/mail@jcgames.de.rsa.pub
etc/apk/protected_paths.d/ca-certificates.list
etc/apk/protected_paths.d/lbu.list
etc/apk/cache
etc/apk/repositories
etc/apk/world
etc/conf.d/
etc/conf.d/local
etc/conf.d/loadkmap
etc/local.d/
etc/runlevels/
etc/runlevels/default/
etc/runlevels/default/local
etc/runlevels/default/ntpd
etc/runlevels/default/sshd
etc/runlevels/default/mpd
etc/runlevels/default/mympd
etc/runlevels/sysinit/devfs
etc/runlevels/sysinit/dmesg
etc/runlevels/sysinit/mdev
etc/runlevels/sysinit/hwdrivers
etc/runlevels/sysinit/modloop
etc/runlevels/boot/modules
etc/runlevels/boot/sysctl
etc/runlevels/boot/hostname
etc/runlevels/boot/bootmisc
etc/runlevels/boot/syslog
etc/runlevels/boot/swclock
etc/runlevels/boot/loadkmap
etc/runlevels/boot/rngd
etc/runlevels/boot/networking
etc/runlevels/boot/alsa
etc/runlevels/shutdown/mount-ro
etc/runlevels/shutdown/killprocs
etc/runlevels/shutdown/savecache
etc/fstab
etc/hostname
etc/network/interfaces
etc/lbu/lbu.conf
etc/ssl/certs/mympd.pem
etc/resolv.conf
etc/zoneinfo/
etc/zoneinfo/Europe/
etc/zoneinfo/Europe/Madrid
etc/localtime
etc/ssh/sshd_config
etc/ssh/ssh_host_rsa_key.pub
etc/ssh/ssh_host_rsa_key
etc/ssh/ssh_host_ecdsa_key.pub
etc/ssh/ssh_host_ecdsa_key
etc/ssh/ssh_host_ed25519_key.pub
etc/ssh/ssh_host_ed25519_key
etc/keymap/
etc/keymap/us.bmap.gz
etc/doas.d/mympd.conf
etc/mympdos/mympdos.conf
etc/mympdos/custom/
etc/mympdos/custom/mpd.replace
etc/mdev.conf
etc/mpd.conf
etc/passwd-
etc/passwd
etc/shadow-
etc/shadow
etc/group-
etc/group
etc/inittab
var/lib/alsa/asound.state
var/lib/mpd/
var/lib/mpd/cache/
var/lib/mympd/
var/lib/mympd/scripts/
var/lib/mympd/scripts/RW-Library.lua
var/lib/mympd/scripts/Reboot.lua
var/lib/mympd/scripts/Shutdown.lua
var/lib/mympd/config/
var/lib/mympd/config/http
var/lib/mympd/config/http_host
var/lib/mympd/config/http_port
var/lib/mympd/config/ssl
var/lib/mympd/config/ssl_port
var/lib/mympd/config/ssl_san
var/lib/mympd/config/custom_cert
var/lib/mympd/config/pin_hash
var/lib/mympd/config/acl
var/lib/mympd/config/scriptacl
var/lib/mympd/config/lualibs
var/lib/mympd/config/covercache_keep_days
var/lib/mympd/config/loglevel
var/lib/mympd/config/save_caches
var/lib/mympd/config/mympd_uri
var/lib/mympd/config/stickers
var/lib/mympd/config/stickers_pad_int
var/lib/mympd/config/album_mode
var/lib/mympd/config/album_group_tag
var/lib/mympd/ssl/
var/lib/mympd/ssl/ca.key
var/lib/mympd/ssl/ca.pem
var/lib/mympd/ssl/server.key
var/lib/mympd/ssl/server.pem
Created /media/mmcblk0p2/myMPDos.apkovl.tar.gz
Successfully saved apk overlay files

I had the same experience with the published v1.4.1 image, but I get no bootstrap.log from there.

Am I still missing something here? Why are the services not up? Is there something else to try/debug?

stratosgear commented 7 months ago

Hmmm, I booted the qemu image, with a ./image start. No bootstrap.txt with that, but I assume a default one would be used?

I still get a:

localhost:~# pstree
init-+-6*[getty]
     |-login---ash---pstree
     `-syslogd

with no mpd services running.

Not sure what I'm doing wrong, or if my assumptions on how this ought to work is wrong too.

Last check to perform WITHOUT the use of any audio DAC (commenting the AUDIOHAT ilne) , resulted in a pi3 not booting. Not sure if that was a fluke, or bad luck. Retrying with another sd card now...

jcorporation commented 7 months ago

If you start the image in qemu it does not load the overlay created by the bootstrap process. This is expected.

Your bootstrap log looks good. The startup log after the first reboot is interesting:

stratosgear commented 7 months ago

Ooops, I'll need some help here. How would I know if the overlay is found and loaded?

(BTW, it is not an issue with a weird DAC, becuase booting without any AUDIOHAT options, still does not start any mpd services)

stratosgear commented 7 months ago

Justin case it might be usefull. Here is a dmesg from the latest boot (actually first boot of the newly burned sd card):

myMPDos:~# dmesg
[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 6.6.14-0-rpi (buildozer@build-3-19-aarch64) (gcc (Alpine 13.2.1_git20231014) 13.2.1 20231014, GNU ld (GNU Binutils) 2.41) #1-Alpine SMP PREEMPT Fri Jan 26 13:41:25 UTC 2024
[    0.000000] KASLR enabled
[    0.000000] random: crng init done
[    0.000000] Machine model: Raspberry Pi 3 Model B Rev 1.2
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created CMA memory pool at 0x0000000037400000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000037400000..0x000000003b3fffff (65536 KiB) map reusable linux,cma
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003b3fffff]
[    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 0x0000000000000000-0x000000003b3fffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000] On node 0, zone DMA: 19456 pages in unavailable ranges
[    0.000000] percpu: Embedded 30 pages/cpu s85224 r8192 d29464 u122880
[    0.000000] pcpu-alloc: s85224 r8192 d29464 u122880 alloc=30*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: kernel page table isolation forced ON by KASLR
[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)
[    0.000000] CPU features: detected: ARM erratum 843419
[    0.000000] CPU features: detected: ARM erratum 845719
[    0.000000] alternatives: applying boot alternatives
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_hdmi=0 bcm2708_fb.fbwidth=1920 bcm2708_fb.fbheight=1200 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  modules=loop,squashfs,sd-mod,usb-storage quiet console=tty1
[    0.000000] Unknown kernel command line parameters "modules=loop,squashfs,sd-mod,usb-storage", will be passed to user space.
[    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 238896
[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off
[    0.000000] software IO TLB: area num 4.
[    0.000000] software IO TLB: mapped [mem 0x0000000031f80000-0x0000000035f80000] (64MB)
[    0.000000] Memory: 788980K/970752K available (12544K kernel code, 2204K rwdata, 4240K rodata, 3456K init, 1073K bss, 116236K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] trace event string verifier disabled
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu:     RCU event tracing is enabled.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
[    0.000000]  Trampoline 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: bcm2836_arm_irqchip_handle_irq
[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000001] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000404] Console: colour dummy device 80x25
[    0.000419] printk: console [tty1] enabled
[    0.000528] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=76800)
[    0.000549] pid_max: default: 32768 minimum: 301
[    0.000839] LSM: initializing lsm=capability,integrity
[    0.001105] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    0.001137] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    0.002258] cgroup: Disabling memory control group subsystem
[    0.004284] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1.
[    0.004460] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1.
[    0.004765] rcu: Hierarchical SRCU implementation.
[    0.004774] rcu:     Max phase no-delay instances is 1000.
[    0.006849] EFI services will not be available.
[    0.007242] smp: Bringing up secondary CPUs ...
[    0.008048] Detected VIPT I-cache on CPU1
[    0.008195] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[    0.009055] Detected VIPT I-cache on CPU2
[    0.009163] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
[    0.009947] Detected VIPT I-cache on CPU3
[    0.010045] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
[    0.010152] smp: Brought up 1 node, 4 CPUs
[    0.010175] SMP: Total of 4 processors activated.
[    0.010185] CPU features: detected: 32-bit EL0 Support
[    0.010192] CPU features: detected: 32-bit EL1 Support
[    0.010202] CPU features: detected: CRC32 instructions
[    0.010321] CPU: All CPU(s) started at EL2
[    0.010341] alternatives: applying system-wide alternatives
[    0.013467] devtmpfs: initialized
[    0.025935] Enabled cp15_barrier support
[    0.025970] Enabled setend support
[    0.026210] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.026237] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.029388] pinctrl core: initialized pinctrl subsystem
[    0.030152] DMI not present or invalid.
[    0.030830] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.037942] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
[    0.038298] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.038840] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.039006] audit: initializing netlink subsys (disabled)
[    0.039299] audit: type=2000 audit(0.036:1): state=initialized audit_enabled=0 res=1
[    0.040210] thermal_sys: Registered thermal governor 'step_wise'
[    0.040289] cpuidle: using governor menu
[    0.040568] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.040711] ASID allocator initialised with 32768 entries
[    0.041793] Serial: AMBA PL011 UART driver
[    0.046933] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.056258] raspberrypi-firmware soc:firmware: Attached to firmware from 2023-12-08T12:42:10, variant start
[    0.060269] raspberrypi-firmware soc:firmware: Firmware hash is e02d33b3122450accf9dea471a177d3b5623f5ad
[    0.068398] Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL
[    0.068411] Modules: 0 pages in range for non-PLT usage
[    0.068418] Modules: 518352 pages in range for PLT usage
[    0.074437] bcm2835-dma 3f007000.dma-controller: DMA legacy API manager, dmachans=0x1
[    0.075942] iommu: Default domain type: Translated
[    0.075954] iommu: DMA domain TLB invalidation policy: strict mode
[    0.077920] SCSI subsystem initialized
[    0.078215] usbcore: registered new interface driver usbfs
[    0.078270] usbcore: registered new interface driver hub
[    0.078333] usbcore: registered new device driver usb
[    0.078807] pps_core: LinuxPPS API ver. 1 registered
[    0.078817] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.078852] PTP clock support registered
[    0.080421] vgaarb: loaded
[    0.081018] clocksource: Switched to clocksource arch_sys_counter
[    1.715499] VFS: Disk quotas dquot_6.6.0
[    1.715583] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.715741] FS-Cache: Loaded
[    1.716061] CacheFiles: Loaded
[    1.727388] NET: Registered PF_INET protocol family
[    1.727656] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    1.729694] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
[    1.729739] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    1.729828] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    1.729946] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
[    1.730307] TCP: Hash tables configured (established 8192 bind 8192)
[    1.730696] MPTCP token hash table entries: 1024 (order: 2, 24576 bytes, linear)
[    1.730791] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.730843] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.731105] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    1.731783] RPC: Registered named UNIX socket transport module.
[    1.731795] RPC: Registered udp transport module.
[    1.731803] RPC: Registered tcp transport module.
[    1.731809] RPC: Registered tcp-with-tls transport module.
[    1.731816] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.731848] PCI: CLS 0 bytes, default 64
[    1.732452] kvm [1]: IPA Size Limit: 40 bits
[    1.732507] Trying to unpack rootfs image as initramfs...
[    1.734241] kvm [1]: Hyp mode initialized successfully
[    1.739841] Initialise system trusted keyrings
[    1.740111] workingset: timestamp_bits=46 max_order=18 bucket_order=0
[    1.740201] zbud: loaded
[    1.741825] NFS: Registering the id_resolver key type
[    1.741866] Key type id_resolver registered
[    1.741875] Key type id_legacy registered
[    1.741907] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.741918] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.743114] Key type asymmetric registered
[    1.743136] Asymmetric key parser 'x509' registered
[    1.743339] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    1.743541] io scheduler mq-deadline registered
[    1.743553] io scheduler kyber registered
[    1.743616] io scheduler bfq registered
[    1.757952] bcm2708_fb soc:fb: FB found 1 display(s)
[    1.802059] Console: switching to colour frame buffer device 240x75
[    1.825271] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1920x1200
[    1.831371] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    1.836580] bcm2835-rng 3f104000.rng: hwrng registered
[    1.837447] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.862854] brd: module loaded
[    1.875892] loop: module loaded
[    1.876979] Loading iSCSI transport class v2.0-870.
[    1.884883] usbcore: registered new device driver r8152-cfgselector
[    1.884963] usbcore: registered new interface driver r8152
[    1.885088] usbcore: registered new interface driver lan78xx
[    1.885186] usbcore: registered new interface driver smsc95xx
[    1.885529] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    2.188096] Freeing initrd memory: 5664K
[    2.613987] Core Release: 2.80a
[    2.614018] Setting default values for core params
[    2.614046] Finished setting default values for core params
[    2.814451] Using Buffer DMA mode
[    2.814460] Periodic Transfer Interrupt Enhancement - disabled
[    2.814468] Multiprocessor Interrupt Enhancement - disabled
[    2.814476] OTG VER PARAM: 0, OTG VER FLAG: 0
[    2.814492] Dedicated Tx FIFOs mode
[    2.815262] 
[    2.815270] WARN::dwc_otg_hcd_init:1072: FIQ DMA bounce buffers: virt = ffffffc080481000 dma = 0x00000000f7810000 len=9024
[    2.815314] FIQ FSM acceleration enabled for :
[    2.815314] Non-periodic Split Transactions
[    2.815314] Periodic Split Transactions
[    2.815314] High-Speed Isochronous Endpoints
[    2.815314] Interrupt/Control Split Transaction hack enabled
[    2.815329] dwc_otg: Microframe scheduler enabled
[    2.815359] 
[    2.815364] WARN::hcd_init_fiq:496: MPHI regs_base at ffffffc08006d000
[    2.815431] dwc_otg 3f980000.usb: DWC OTG Controller
[    2.815477] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    2.815525] dwc_otg 3f980000.usb: irq 74, io mem 0x00000000
[    2.815579] Init: Port Power? op_state=1
[    2.815586] Init: Power Port (0)
[    2.815882] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06
[    2.815903] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.815918] usb usb1: Product: DWC OTG Controller
[    2.815930] usb usb1: Manufacturer: Linux 6.6.14-0-rpi dwc_otg_hcd
[    2.815941] usb usb1: SerialNumber: 3f980000.usb
[    2.817074] hub 1-0:1.0: USB hub found
[    2.817317] hub 1-0:1.0: 1 port detected
[    2.818134] dwc_otg: FIQ enabled
[    2.818147] dwc_otg: NAK holdoff enabled
[    2.818154] dwc_otg: FIQ split-transaction FSM enabled
[    2.818170] Module dwc_common_port init
[    2.819668] usbcore: registered new interface driver uas
[    2.819779] usbcore: registered new interface driver usb-storage
[    2.820727] mousedev: PS/2 mouse device common for all mice
[    2.826970] sdhci: Secure Digital Host Controller Interface driver
[    2.826998] sdhci: Copyright(c) Pierre Ossman
[    2.827542] sdhci-pltfm: SDHCI platform and OF driver helper
[    2.830074] ledtrig-cpu: registered to indicate activity on CPUs
[    2.830397] hid: raw HID events driver (C) Jiri Kosina
[    2.830496] usbcore: registered new interface driver usbhid
[    2.830502] usbhid: USB HID core driver
[    2.836045] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
[    2.836611] Key type dns_resolver registered
[    2.872919] registered taskstats version 1
[    2.873328] Loading compiled-in X.509 certificates
[    2.883443] Key type .fscrypt registered
[    2.883464] Key type fscrypt-provisioning registered
[    2.890674] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    2.890908] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    2.892528] sdhost: log_buf @ 00000000d41ffa27 (c24e9000)
[    2.929117] Indeed it is in host mode hprt0 = 00021501
[    2.941173] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    2.941642] uart-pl011 3f201000.serial: there is not valid maps for state default
[    2.942050] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    2.942370] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 99, base_baud = 0) is a PL011 rev2
[    2.944644] of_cfs_init
[    2.944752] of_cfs_init: OK
[    2.944954] clk: Disabling unused clocks
[    2.948718] Freeing unused kernel memory: 3456K
[    2.961233] Run /init as init process
[    2.961243]   with arguments:
[    2.961247]     /init
[    2.961250]   with environment:
[    2.961253]     HOME=/
[    2.961256]     TERM=linux
[    2.961260]     modules=loop,squashfs,sd-mod,usb-storage
[    2.976077] mmc0: host does not support reading read-only switch, assuming write-enable
[    2.979126] mmc0: Host Software Queue enabled
[    2.979165] mmc0: new high speed SDHC card at address 0001
[    2.980323] mmcblk0: mmc0:0001 SD 14.6 GiB
[    2.983432]  mmcblk0: p1 p2
[    2.984207] mmcblk0: mmc0:0001 SD 14.6 GiB
[    3.005235] Alpine Init 3.9.1-r0
[    3.008480] Loading boot drivers...
[    3.020963] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    3.022681] Loading boot drivers: ok.
[    3.028527] Mounting boot media...
[    3.117449] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    3.117681] Indeed it is in host mode hprt0 = 00001101
[    3.333593] usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00
[    3.333623] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.336371] hub 1-1:1.0: USB hub found
[    3.336593] hub 1-1:1.0: 5 ports detected
[    3.625654] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    3.725765] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00
[    3.725806] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.729363] smsc95xx v2.0.0
[    3.827345] SMSC LAN8700 usb-001:003:01: attached PHY driver (mii_bus:phy_addr=usb-001:003:01, irq=184)
[    3.828306] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:8c:b8:9c
[    3.905044] usb 1-1.4: new low-speed USB device number 4 using dwc_otg
[    3.969921] EXT4-fs (mmcblk0p2): orphan cleanup on readonly fs
[    3.972793] EXT4-fs (mmcblk0p2): mounted filesystem 264d0b14-f834-47a6-8006-3596c185d265 ro with ordered data mode. Quota mode: none.
[    4.034644] usb 1-1.4: New USB device found, idVendor=413c, idProduct=2107, bcdDevice= 1.15
[    4.034687] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    4.034702] usb 1-1.4: Product: Dell USB Entry Keyboard
[    4.034714] usb 1-1.4: Manufacturer: Dell
[    4.049454] input: Dell Dell USB Entry Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/0003:413C:2107.0001/input/input0
[    4.110169] hid-generic 0003:413C:2107.0001: input,hidraw0: USB HID v1.10 Keyboard [Dell Dell USB Entry Keyboard] on usb-3f980000.usb-1.4/input0
[    4.386427] Mounting boot media: ok.
[    4.395265] Loading user settings from /media/mmcblk0p2/myMPDos.apkovl.tar.gz...
[    4.460589] Loading user settings from /media/mmcblk0p2/myMPDos.apkovl.tar.gz: ok.
[    4.517077] EXT4-fs (mmcblk0p2): re-mounted 264d0b14-f834-47a6-8006-3596c185d265 ro. Quota mode: none.
[    4.684562] Installing packages to root filesystem...
[    4.747483] EXT4-fs (mmcblk0p2): re-mounted 264d0b14-f834-47a6-8006-3596c185d265 r/w. Quota mode: none.
[    5.716088] EXT4-fs (mmcblk0p2): re-mounted 264d0b14-f834-47a6-8006-3596c185d265 ro. Quota mode: none.
[    5.725469] Installing packages to root filesystem: ok.
[    8.301293] loop0: detected capacity change from 0 to 59672
[    8.310048] F2FS-fs (loop0): Magic Mismatch, valid(0xf2f52010) - read(0xa38532fd)
[    8.310079] F2FS-fs (loop0): Can't find valid F2FS filesystem in 1th superblock
[    8.310165] F2FS-fs (loop0): Magic Mismatch, valid(0xf2f52010) - read(0x9c34dbac)
[    8.310176] F2FS-fs (loop0): Can't find valid F2FS filesystem in 2th superblock
[   10.184367] rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000
[   10.190113] rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem
[   10.355952] mc: Linux media interface: v0.10
[   10.410097] videodev: Linux video capture interface: v2.00
[   10.430962] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[   10.431944] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[   10.431965] [vc_sm_connected_init]: start
[   10.432897] [vc_sm_connected_init]: installed successfully
[   10.435609] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[   10.443894] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[   10.484259] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[   10.488595] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[   10.488655] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[   10.491194] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[   10.491244] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[   10.494351] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[   10.494403] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[   10.502806] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[   10.502855] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[   10.505409] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[   10.505456] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[   10.507811] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[   10.519247] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[   10.519704] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[   10.520102] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[   10.520470] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[   10.520500] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[   10.520512] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[   10.520522] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[   10.520531] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[   10.538075] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[   10.538613] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[   10.540453] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[   10.544623] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[   10.544661] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[   10.544674] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[   10.544684] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[   10.544699] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[   10.544849] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[   10.563650] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[   11.686840] NET: Registered PF_PACKET protocol family
[   11.797407] NET: Registered PF_INET6 protocol family
[   11.798470] Segment Routing with IPv6
[   11.798499] In-situ OAM (IOAM) with IPv6
[   12.017108] EXT4-fs (mmcblk0p2): re-mounted 264d0b14-f834-47a6-8006-3596c185d265 ro. Quota mode: none.
[   12.848686] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
[   12.853478] smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off
jcorporation commented 7 months ago

dmesg output is not useful. What says apk info? List it myMPD and MPD packages?

Is any useful message in /var/log/messages?

Edit: It seems the initial messages are not logged, you need a monitor or a serial console.

stratosgear commented 7 months ago

/var/log/messages contain boring info:

Jan 11 14:43:03 myMPDos syslog.info syslogd started: BusyBox v1.36.1
Jan 11 14:43:03 myMPDos daemon.info init: starting pid 2463, tty '': '/sbin/openrc default'
Jan 11 14:43:03 myMPDos daemon.warn openrc[2463]: WARNING: clock skew detected!
Jan 11 14:43:03 myMPDos auth.info sshd[2519]: Server listening on 0.0.0.0 port 22.
Jan 11 14:43:03 myMPDos auth.info sshd[2519]: Server listening on :: port 22.
Jan 11 14:43:04 myMPDos daemon.info init: starting pid 2550, tty '/dev/tty1': '/sbin/getty 38400 tty1'
Apr 12 20:17:03 myMPDos auth.info sshd[2598]: Accepted password for root from 192.168.3.94 port 47078 ssh2

But apk info does NOT list the required packages:

myMPDos:~# apk info
WARNING: opening from cache http://dl-cdn.alpinelinux.org/alpine/v3.19/main: No such file or directory
WARNING: opening from cache http://dl-cdn.alpinelinux.org/alpine/v3.19/community: No such file or directory
WARNING: opening from cache https://raw.githubusercontent.com/jcorporation/myMPDos/master/repository: No such file or directory
alpine-base
alpine-baselayout
alpine-baselayout-data
alpine-conf
alpine-keys
alpine-release
apk-tools
busybox
busybox-binsh
busybox-mdev-openrc
busybox-openrc
busybox-suid
ca-certificates-bundle
doas
e2fsprogs
e2fsprogs-libs
ifupdown-ng
libblkid
libc-utils
libcap2
libcom_err
libcrypto3
libedit
libncursesw
libssl3
libuuid
mdev-conf
musl
musl-utils
ncurses-terminfo-base
openrc
openssh
openssh-client-common
openssh-client-default
openssh-keygen
openssh-server
openssh-server-common
openssh-server-common-openrc
openssh-sftp-server
openssl
scanelf
ssl_client
zlib
myMPDos:~#

I do not have a serial cable, but I could grab another video of the boot process. Last time I did it I didn't notice anything weird, but I could have missed it!

stratosgear commented 7 months ago

Well the video looks pretty clean.

https://drive.google.com/file/d/1d0FrXiKljYpj6HMtWRWCmJhMmrI1Rp5K/view?usp=sharing (Github allows only up to 10MB uploads, This is about 20MB)

Apologies! The video is awful, low quality, and the webcam was keep trying to focus. I can make it out though. Plus, it is only a 1m 50secs long torture only!

I really apologize for the length of this thread but if YOU cannot shed some light I do not know who can help at this point! I appreciate your patience and help on this matter!!!

jcorporation commented 7 months ago

It seems the overlay is not loaded. Strange, this is not specific to myMPD but Alpine Linux standard.

I will try to reproduce this.

jcorporation commented 7 months ago

I can reproduce your issue. The apk cache is not setup correctly. Root cause will be fixed in next release.

There is a workaround: type apk update && apk cache -v sync and reboot.

Edit: It seems apk cache -v sync deletes sometimes the false APKINDEX files, but I have not figured out why...

stratosgear commented 7 months ago

Great! I verify that the workaround works.

Is this a "permanent" workaround, or I have to keep redoing it before each reboot?

Of course, I would be upgrading to the latest version when the new release is out, but just so I know for the intermediate tests I will be doing. Thanks!

jcorporation commented 7 months ago

Is this a "permanent" workaround, or I have to keep redoing it before each reboot?

No, run this once should fix the issue.

stratosgear commented 7 months ago

I also have issues editing /etc/mpd.conf. I make edits so I setup mpd in a satellite mode, but I cannot persist them. After my edits (verified that I was able to change the file after doing a cat on the file) and executing a savereboot.sh, it seems that my changes are lost on the next reboot.

Is this related with this current sync issue...?

jcorporation commented 7 months ago

/etc/mpd.conf is managed by the script /usr/bin/configmpd.sh as described in https://jcorporation.github.io/myMPDos/advanced-topics/customize-mpd.

stratosgear commented 7 months ago

First of all sorry for bombarding you with all these issues, and I really appreciate for taking the time to be so patient with me (although, I think I have indeed found some real bugs, and not everything is irrelevant chatter :) )

I am trying to collect information about the intended usage of myMPDOS that seems to be spread in multiple places. Maybe I am missing a clear context here, that I have not seen explicitly explain in the docs.

Is the intended and proposed usage to preconfigure everything at sdcard creation only?

Or I am, somehow, allowed to continuously editing the configuration files at runtime and persist them as I go?

My current issue here is that I am not sure what the EXACT configuration of mpd and mympd should be in a satellite configuration (I know about the satellite page in the docs, and I am using it as a guide), so I am doing multiple edits until I get everything working. Obviously, making random edits and re-burning an sdcard each time I want to make a change in a file, is TOO time consuming. So, basically I want to keep doing changes in a live system, maybe do some reboots, and once I get all the parameters right, then maybe do one final burn with all the right settings (and safekeep all the changes in case I want to reburn a new sdcard in the future)

So, under this context, it is not clear how I can use the info under the Customize Mpd section on the myMPDOS docs page.

Thanks!

jcorporation commented 7 months ago

To find a valid mpd config you can edit the /etc/mpd.conf file until it works. Before you reboot/shutdown copy it to /etc/mympdos/custom/mpd.conf and save the changes with lbu_commit. Hope this helps.