Thank you for your donation!


Cloudsmith graciously provides open-source package management and distribution for our project.


Solved: Filenames with quotation marks cause MPD to fail
#1
Hello,
my moode is showing a somewhat weird issue. When in "tag viewer" > selecting a genre > clicking the "moode image" > clear/play, for one tag (one genre) moode is only selecting 42 tracks into the playlist and is not starting playback automatically. For another genre it is selecting all tracks and shuffling, for the other genres it is selecting all songs and starting playback? (I like to listen to genres most of the time and like to just have it shuffle through my library, as I am mostly in the mood for a type of music rather than a specific band or cd)

To sum up:
Genre > Swing (or Blues) > Clear/play: All tracks added and no automatic playback
Genre > Neo-Folk > only 42 Songs, the same songs every time, no playback
Genre > Gothic > All songs added, playback starts automatically

It doesn't matter whether I am using Brave, Chrome (macOS) or safari (iOS) (iOS is showing album and track count on genre level though, which chrome isn't).

I am absolutely at a loss as to why this might happen and am currently "recreating" the Library (again) and hope there might be some further steps. I could try.

Thanks in advance,
DS

- edit-
As it turns out, mpd can't quite handle filenames that contain ". mpd will still run, but cause an "exception", subsequently neither adding following tracks to the playlist nor starting to play. ssh'ing into moode and running the following command will show all files containing such quotation marks on all attached devices: 
Code:
find /media/ -name "*\"*"

Editing the filenames (removing ") fixed the issue for all genres that had been affected in my case. Lots of thanks to Kent for helping me figure this.


Attached Files Thumbnail(s)
       
Reply
#2
Addendum: Just checked the version of moode and installed the latest update, same issue. Cleared playback cache, systemlog and increased debug level. I probably should also mention that this is running moode and pihole via nginx as per this instruction


Latest output of moodeutil is this:
Code:
20211015 150732 worker: Job regen_library done
20211015 154144 worker: Job clearplayhistory
20211015 154150 worker: Job clearsyslogs
20211015 154156 worker: Job compactdb
20211015 154204 waitWorker(): Start (sys-config, w_active=0)
20211015 154204 waitWorker(): End   (sys-config, w_active=0)
20211015 154212 readMpdResponse(): error: response $str[0]=(ACK [5@3915] {} Space expected after closing '"')
20211015 154212 enhanceMetadata(): error: currentsong file is NULL
20211015 154218 readMpdResponse(): error: response $str[0]=(ACK [5@43] {} Space expected after closing '"')
20211015 154218 enhanceMetadata(): error: currentsong file is NULL

mpd log:
Code:
➜  ~ tail -25 /var/log/mpd/log
Oct 15 15:42 : player: played "USB/scaldic_art/Jännerwein/Abendläuten/04 - The Grass is Always Browner.mp3"
Oct 15 15:42 : player: played "USB/scaldic_art/Karnnos/A Burial In Flames/05 - Riddle Of Knots.mp3"
Oct 15 15:42 : exception: Space expected after closing '"'
Oct 15 15:42 : exception: Space expected after closing '"'

syslog is empty. Since I rebooted anyways after the upgrade, I'll verbatim post dmesg, just in case someone might find something useful here...

Code:
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.10.60-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1449 SMP Wed Aug 25 15:00:44 BST 2021
[    0.000000] CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.2
[    0.000000] random: fast init done
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x000000002ac00000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000002fffffff]
[    0.000000]   Normal   empty
[    0.000000]   HighMem  [mem 0x0000000030000000-0x000000007fffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000]   node   0: [mem 0x0000000040000000-0x000000007fffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000007fffffff]
[    0.000000] On node 0 totalpages: 504832
[    0.000000]   DMA zone: 1728 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 196608 pages, LIFO batch:63
[    0.000000]   HighMem zone: 308224 pages, LIFO batch:63
[    0.000000] percpu: Embedded 20 pages/cpu s50764 r8192 d22964 u81920
[    0.000000] pcpu-alloc: s50764 r8192 d22964 u81920 alloc=20*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 503104
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:CA:49:72 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  net.ifnames=0 console=ttyAMA0,115200 console=tty1 root=PARTUUID=067e19d7-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[    0.000000] Kernel parameter elevator= does not have any effect anymore.
              Please use sysfs to set IO scheduler for individual devices.
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x0000000025a00000-0x0000000029a00000] (64MB)
[    0.000000] Memory: 1850028K/2019328K available (10240K kernel code, 1364K rwdata, 3188K rodata, 2048K init, 891K bss, 103764K reserved, 65536K cma-reserved, 1232896K highmem)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 34309 entries in 68 pages
[    0.000000] ftrace: allocated 68 pages with 2 groups
[    0.000000] rcu: Hierarchical RCU implementation.
[    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 10 jiffies.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] random: get_random_bytes called from start_kernel+0x3c8/0x59c with crng_init=1
[    0.000008] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
[    0.000035] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
[    0.000100] bcm2835: system timer (irq = 25)
[    0.000750] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[    0.000772] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[    0.000795] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[    0.000813] Switching to timer-based delay loop, resolution 18ns
[    0.001070] Console: colour dummy device 80x30
[    0.001841] printk: console [tty1] enabled
[    0.001911] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000)
[    0.001966] pid_max: default: 32768 minimum: 301
[    0.002147] LSM: Security Framework initializing
[    0.002347] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.002393] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.003873] cgroup: Disabling memory control group subsystem
[    0.004122] CPU: Testing write buffer coherency: ok
[    0.004597] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.005840] Setting up static identity map for 0x200000 - 0x20003c
[    0.006058] rcu: Hierarchical SRCU implementation.
[    0.007008] smp: Bringing up secondary CPUs ...
[    0.008233] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.009609] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.011011] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.011174] smp: Brought up 1 node, 4 CPUs
[    0.011221] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[    0.011251] CPU: All CPU(s) started in HYP mode.
[    0.011279] CPU: Virtualization extensions available.
[    0.012156] devtmpfs: initialized
[    0.026400] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[    0.026658] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.026712] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.028598] pinctrl core: initialized pinctrl subsystem
[    0.029726] NET: Registered protocol family 16
[    0.033732] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.034517] audit: initializing netlink subsys (disabled)
[    0.034796] audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1
[    0.035374] thermal_sys: Registered thermal governor 'step_wise'
[    0.036137] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.036192] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.036606] Serial: AMBA PL011 UART driver
[    0.067796] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.080876] raspberrypi-firmware soc:firmware: Attached to firmware from 2021-08-31T14:52:47, variant start
[    0.090889] raspberrypi-firmware soc:firmware: Firmware hash is 67615e950e1e28b92dfae6303cf7a8b879a8908f
[    0.131885] Kprobes globally optimized
[    0.136969] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.141131] vgaarb: loaded
[    0.141598] SCSI subsystem initialized
[    0.141826] usbcore: registered new interface driver usbfs
[    0.141915] usbcore: registered new interface driver hub
[    0.142005] usbcore: registered new device driver usb
[    0.142361] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.144335] clocksource: Switched to clocksource arch_sys_counter
[    1.103769] VFS: Disk quotas dquot_6.6.0
[    1.103887] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.104065] FS-Cache: Loaded
[    1.104281] CacheFiles: Loaded
[    1.114608] NET: Registered protocol family 2
[    1.114884] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    1.116360] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    1.116427] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    1.116512] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    1.116597] TCP: Hash tables configured (established 8192 bind 8192)
[    1.116772] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.116829] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.117118] NET: Registered protocol family 1
[    1.117923] RPC: Registered named UNIX socket transport module.
[    1.117957] RPC: Registered udp transport module.
[    1.117986] RPC: Registered tcp transport module.
[    1.118014] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.118052] PCI: CLS 0 bytes, default 64
[    1.120997] Initialise system trusted keyrings
[    1.121256] workingset: timestamp_bits=14 max_order=19 bucket_order=5
[    1.129563] zbud: loaded
[    1.131782] FS-Cache: Netfs 'nfs' registered for caching
[    1.132551] NFS: Registering the id_resolver key type
[    1.132605] Key type id_resolver registered
[    1.132634] Key type id_legacy registered
[    1.132786] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.132820] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.133866] Key type asymmetric registered
[    1.133898] Asymmetric key parser 'x509' registered
[    1.134103] bounce: pool size: 64 pages
[    1.134161] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    1.134407] io scheduler mq-deadline registered
[    1.134439] io scheduler kyber registered
[    1.138801] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.138852] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.138954] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.139074] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x007fffffff -> 0x0400000000
[    1.196443] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    1.196826] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.196863] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.196899] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    1.197012] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.197270] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.200764] PCI: bus0: Fast back to back transfers disabled
[    1.201149] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    1.201293] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    1.201744] pci 0000:01:00.0: PME# supported from D0 D3cold
[    1.205233] PCI: bus1: Fast back to back transfers disabled
[    1.205348] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.205389] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    1.205493] pci 0000:00:00.0: PCI bridge to [bus 01]
[    1.205533] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.205977] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[    1.206220] pcieport 0000:00:00.0: PME: Signaling with IRQ 61
[    1.207165] bcm2708_fb soc:fb: FB found 1 display(s)
[    1.218614] Console: switching to colour frame buffer device 80x30
[    1.221645] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480
[    1.229033] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    1.232985] iproc-rng200 fe104000.rng: hwrng registered
[    1.234236] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.237069] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    1.251423] brd: module loaded
[    1.264818] loop: module loaded
[    1.267532] Loading iSCSI transport class v2.0-870.
[    1.271747] libphy: Fixed MDIO Bus: probed
[    1.274463] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.294420] libphy: bcmgenet MII bus: probed
[    1.374522] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.376714] usbcore: registered new interface driver r8152
[    1.377777] usbcore: registered new interface driver lan78xx
[    1.378827] usbcore: registered new interface driver smsc95xx
[    1.381415] xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142)
[    1.382535] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.383509] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    1.388154] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000060000000890
[    1.391436] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
[    1.393407] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.395420] usb usb1: Product: xHCI Host Controller
[    1.396427] usb usb1: Manufacturer: Linux 5.10.60-v7l+ xhci-hcd
[    1.397420] usb usb1: SerialNumber: 0000:01:00.0
[    1.399130] hub 1-0:1.0: USB hub found
[    1.400152] hub 1-0:1.0: 1 port detected
[    1.401752] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.402716] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    1.404624] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    1.406131] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10
[    1.408077] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.410089] usb usb2: Product: xHCI Host Controller
[    1.411158] usb usb2: Manufacturer: Linux 5.10.60-v7l+ xhci-hcd
[    1.412228] usb usb2: SerialNumber: 0000:01:00.0
[    1.413996] hub 2-0:1.0: USB hub found
[    1.415161] hub 2-0:1.0: 4 ports detected
[    1.417834] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.419147] dwc_otg: FIQ enabled
[    1.419162] dwc_otg: NAK holdoff enabled
[    1.419177] dwc_otg: FIQ split-transaction FSM enabled
[    1.419195] Module dwc_common_port init
[    1.419660] usbcore: registered new interface driver uas
[    1.420775] usbcore: registered new interface driver usb-storage
[    1.421945] mousedev: PS/2 mouse device common for all mice
[    1.424739] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.429063] sdhci: Secure Digital Host Controller Interface driver
[    1.430007] sdhci: Copyright(c) Pierre Ossman
[    1.431516] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.435868] ledtrig-cpu: registered to indicate activity on CPUs
[    1.437150] hid: raw HID events driver (C) Jiri Kosina
[    1.438281] usbcore: registered new interface driver usbhid
[    1.439256] usbhid: USB HID core driver
[    1.447568] Initializing XFRM netlink socket
[    1.448584] NET: Registered protocol family 17
[    1.449658] Key type dns_resolver registered
[    1.451084] Registering SWP/SWPB emulation handler
[    1.452163] registered taskstats version 1
[    1.453063] Loading compiled-in X.509 certificates
[    1.454779] Key type ._fscrypt registered
[    1.455614] Key type .fscrypt registered
[    1.456422] Key type fscrypt-provisioning registered
[    1.468655] uart-pl011 fe201000.serial: there is not valid maps for state default
[    1.470552] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.471521] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2
[    1.604919] usb 2-2: new SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[    1.727364] usb 2-2: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
[    1.727380] usb 2-2: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    1.727394] usb 2-2: Product: USB 3.0 Destop HD EP0 Product string
[    1.727408] usb 2-2: Manufacturer: ASMT
[    1.727421] usb 2-2: SerialNumber: 00000000009C
[    2.782229] printk: console [ttyAMA0] enabled
[    2.798812] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    2.812537] of_cfs_init
[    2.816104] of_cfs_init: OK
[    2.835355] scsi host0: uas
[    2.840602] scsi 0:0:0:0: Direct-Access     ASMT     2235             0    PQ: 0 ANSI: 6
[    2.853904] sd 0:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB)
[    2.863526] sd 0:0:0:0: [sda] 4096-byte physical blocks
[    2.864390] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
[    2.869968] sd 0:0:0:0: [sda] Write Protect is off
[    2.883699] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
[    2.884375] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    2.896258] sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of physical block size (4096 bytes)
[    2.934387] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    2.989286] mmc0: new ultra high speed DDR50 SDHC card at address aaaa
[    2.997866] mmcblk0: mmc0:aaaa SC16G 14.8 GiB
[    3.008435]  mmcblk0: p1 p2
[    3.116952] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    3.127144] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    3.135357] usb 1-1: Product: USB2.0 Hub
[    3.142043] hub 1-1:1.0: USB hub found
[    3.147032] hub 1-1:1.0: 4 ports detected
[    3.226722]  sda: sda1 sda2
[    3.233682] sd 0:0:0:0: [sda] Attached SCSI disk
[    3.251305] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    3.261699] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    3.277660] devtmpfs: mounted
[    3.291634] Freeing unused kernel memory: 2048K
[    3.324677] Run /sbin/init as init process
[    3.329813]   with arguments:
[    3.329828]     /sbin/init
[    3.329842]   with environment:
[    3.329857]     HOME=/
[    3.329871]     TERM=linux
[    3.751537] systemd[1]: System time before build time, advancing clock.
[    3.877269] NET: Registered protocol family 10
[    3.884212] Segment Routing with IPv6
[    3.957836] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[    3.984973] systemd[1]: Detected architecture arm.
[    4.051158] systemd[1]: Set hostname to <rpi>.
[    4.213581] uart-pl011 fe201000.serial: no DMA platform data
[    4.625170] systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly.
[    4.712132] systemd[1]: /lib/systemd/system/smbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/smbd.pid → /run/samba/smbd.pid; please update the unit file accordingly.
[    4.792717] systemd[1]: /lib/systemd/system/nmbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/nmbd.pid → /run/samba/nmbd.pid; please update the unit file accordingly.
[    4.925402] random: systemd: uninitialized urandom read (16 bytes read)
[    4.960175] random: systemd: uninitialized urandom read (16 bytes read)
[    4.968645] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    4.980678] random: systemd: uninitialized urandom read (16 bytes read)
[    5.027222] systemd[1]: Listening on RPCbind Server Activation Socket.
[    5.038202] systemd[1]: Created slice system-getty.slice.
[    5.047847] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    5.270698] i2c /dev entries driver
[    5.948045] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    6.674724] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000
[    6.675260] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000
[    6.675792] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000
[    6.676344] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000
[    6.730597] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    6.732999] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    6.733024] [vc_sm_connected_init]: start
[    6.734732] [vc_sm_connected_init]: installed successfully
[    6.744230] mc: Linux media interface: v0.10
[    6.803423] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    6.813800] bcm2835_audio bcm2835_audio: card created with 4 channels
[    6.819521] bcm2835_audio bcm2835_audio: card created with 4 channels
[    6.872029] videodev: Linux video capture interface: v2.00
[    6.899412] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    6.900140] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    6.901944] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    6.906841] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    6.908875] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    6.910244] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    6.939639] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    6.940382] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    6.940421] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    6.940452] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    6.941096] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    6.952326] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    6.952403] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    6.952428] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    6.952452] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    6.952514] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    6.952929] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    6.953691] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    6.953735] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    6.967404] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    6.967469] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    7.000533] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    9.064903] systemd[1]: Started File System Check on /dev/disk/by-partuuid/067e19d7-01.
[    9.079753] systemd[1]: Mounting /boot...
[    9.178236] systemd[1]: Mounted /boot.
[    9.181041] systemd[1]: Reached target Local File Systems.
[    9.188710] systemd[1]: Starting Set console font and keymap...
[    9.190644] systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped.
[    9.197794] systemd[1]: Starting Log2Ram...
[    9.206104] systemd[1]: Starting Preprocess NFS configuration...
[    9.215156] systemd[1]: Starting Raise network interfaces...
[    9.229047] systemd[1]: Started Set console font and keymap.
[    9.830407] systemd-journald[321]: Received request to flush runtime journal from PID 1
[   10.545581] random: crng init done
[   10.545598] random: 7 urandom warning(s) missed due to ratelimiting
[   10.619292] 8021q: 802.1Q VLAN Support v1.8
[   10.817091] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[   10.857743] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[   10.907950] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[   10.908231] bcmgenet fd580000.ethernet eth0: Link is Down
[   13.994662] bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[   13.994718] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   15.359493] ICMPv6: process `dhcpcd' is using deprecated sysctl (syscall) net.ipv6.neigh.eth0.retrans_time - use net.ipv6.neigh.eth0.retrans_time_ms instead
[   20.291263] FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.

Please let me know if I could / should provide any more details. Thank you for listening ツ
Reply
#3
Quick test with Auto-shuffle OFF, Random play ON and Clear/Play some genres and no issues. In each case play starts at a randomly selected track in the Queue.

Maybe try a fresh, unmodified image.
Enjoy the Music!
moodeaudio.org | Mastodon Feed | GitHub
Reply
#4
All this hinges on well-formed metadata.

Genre is tricky if multiple values are assigned to tracks. That’s handled differently in MP3/Id3v2 versus, say, FLAC/Vorbis comment. The ReadMpdResponse error messages make my spidery sense tingle but I can’t explore until later today.

Regards,
Kent
Reply
#5
Thanks for your responses.

I only have one Genre set for the tags, and only three distinct tags are affected (blues, swing, neo-folk), others work fine.

I have just started with a fresh image - it is slightly improved - clear/play now queues 109 songs for Neo-Folk instead of 42. but it is still always the same songs ? . I also ran Disk Utility repair (on a current macOS) on the external drive which hosts the files, to no avail.

any further ideas what I coudl try?
Reply
#6
@demian.smith

It's certainly suggestive that a fresh image changed your statistics but I would still like to eliminate the possibility of malformed metadata.

Rather than play 20 questions, I suggest you zip up a file containing 6 tracks, place the zip file some place I can download it from (Google Drive, DropBox, etc), and post its URL to me via private message on this forum.

The 6 tracks would be: one track which queued and one track which did not queue for each of the 3 genres which are misbehaving.

I can quickly examine them for possible malformed metadata and I'm used to the processes involved.

Regards,
Kent
Reply
#7
Hi Kent,
thanks a million for your offer! 


I had a quick look at the "statistics" for the genres where autoplay is failing / adding to playlist isn't working as expected:
Blues: 3914 of 5453 added
Neo-Folk: 42 of 1076 added
Swing: 8058 of 9195 added

I honestly have trouble "finding" tracks that aren't added for both blues and Swing (just due to the sheer amount), but have created a zip file with examples for Neo-Folk not added as well as one blues track included (alongside working Neo-Folk) (link removed)

I have also increased all the buffer sizes for MPD to their max, just in case (no luck there).

Thanks again for having a look!
Reply
#8
(10-17-2021, 10:17 AM)demian.smith Wrote: Hi Kent,
thanks a million for your offer! 


I had a quick look at the "statistics" for the genres where autoplay is failing / adding to playlist isn't working as expected:
Blues: 3914 of 5453 added
Neo-Folk: 42 of 1076 added
Swing: 8058 of 9195 added

I honestly have trouble "finding" tracks that aren't added for both blues and Swing (just due to the sheer amount), but have created a zip file with examples for Neo-Folk not added as well as one blues track included (alongside working Neo-Folk): https://we.tl/t-mAFi4v7LBV

I have also increased all the buffer sizes for MPD to their max, just in case (no luck there).

Thanks again for having a look!

I'm mystified.

The tracks you supplied (be sure to take them down now) are all tagged according to ID3 version 2.4.0. Some contain a lot of metadata some only a little, but it all looks clean in the tools I have.

More importantly, when I add these 6 tracks to my test player, MPD indexes them all and my repetition of your test example succeeds:
  • in tag view, select Neo-Folk from the Genres column, and then select clear/play
  • all 5 Neo-Folk tracks are added to the queue and the first track begins to play.

I'm not sure what to examine next. Looking beyond the specific tag values in the files you supplied I haven't yet found any obvious differences which might have caused a track to be in the 'included' or 'missing' category. The statistics you posted suggest there should be, especially for the Neo-Folk tracks (only 42 added out of 1076).

You could try enabling MPD's verbose logging level but I'm not sure what to expect and you may find the log file (/var/log/mpd/log) overwhelming with so many tracks involved!

Here's a snippet from said log when selecting the Neo-Folk genre and hitting clear/play

Code:
...
Oct 17 11:42 : client: process command "add "USB/KAR02/moode/included/02 I Crave For You.mp3""
Oct 17 11:42 : client: command returned 0
Oct 17 11:42 : client: process command "add "USB/KAR02/moode/included/05 - Riddle Of Knots.mp3""
Oct 17 11:42 : client: command returned 0
Oct 17 11:42 : client: process command "add "USB/KAR02/moode/missing/05 Hollows of Devotion.mp3""
Oct 17 11:42 : client: command returned 0
Oct 17 11:42 : client: process command "add "USB/KAR02/moode/missing/03 Midwinternight.mp3""
Oct 17 11:42 : client: command returned 0
Oct 17 11:42 : client: process command "add "USB/KAR02/moode/missing/03 Sturmgeweiht.mp3""
Oct 17 11:42 : client: command returned 0
Oct 17 11:42 : client: process command "play"
Oct 17 11:42 : playlist: play 0:"USB/KAR02/moode/included/02 I Crave For You.mp3"
Oct 17 11:42 : client: command returned 0
...

In your case, you should see 42 such "add" commands.

Regards,
Kent

PS - not relevant to this discussion, but Sturmgeweiht is a challenge to someone who supposedly knew German long ago in a galaxy far, far away!
Reply
#9
Hi Kent,

I am equally mystified. In fact, I moved all of my audio to another drive over the weekend (thinking the old HDD might be the culprit) and in the process of doing so also cleared out the metadata for all songs (which is ... tedious ... ). I then regenerated the library, from two new USB Drives (one an old SSD in a enclosement, the other a regular USB drive). I also changed the structure (just in case all bands in root would be an issue for MPD) so there's Folder A; B; C; and so on. 

To my surprise - I have exactly the same happening, with exactly the same genres affected and Neo-Folk (the easiest example) still only playing the same 42 Songs, no matter whether I select clear / add, clear / play or even "remove items" and then just "add". 

I was thinking a specific track might be causing the issue? But. that should show in mpd log ? and also, some tracks from OTWATM are added, but not all. Any idea, "how" the track are added by mpd? I.e., how are the folders "walked through"? I'll attach my mpd log as well as /var/log/mpd /State below, maybe someone can see something that I don't see Big Grin

PS - not relevant to this discussion, but Sturmgeweiht is a challenge to someone who supposedly knew German long ago in a galaxy far, far away!
It is indeed a very specific and very poetic way of speaking German, which would be a "challenge" for some native speakers too Big Grin


Code:
Oct 19 10:17 : state_file: Loading state file /var/lib/mpd/state
Oct 19 10:17 : client: [0] opened from 127.0.0.1:34000
Oct 19 10:17 : client: [0] process command "status"
Oct 19 10:17 : client: [0] command returned 0
Oct 19 10:17 : client: [0] process command "currentsong"
Oct 19 10:17 : client: [0] command returned 0
Oct 19 10:17 : client: [1] opened from 127.0.0.1:34004
Oct 19 10:17 : client: [1] process command "status"
Oct 19 10:17 : client: [1] command returned 0
Oct 19 10:17 : client: [1] process command "currentsong"
Oct 19 10:17 : client: [1] command returned 0
Oct 19 10:17 : client: [2] opened from 127.0.0.1:34008
Oct 19 10:17 : client: [2] process command "lsinfo "USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/01 Gal Anda.mp3""
Oct 19 10:17 : client: [2] command returned 0
Oct 19 10:17 : client: [2] closed
Oct 19 10:17 : client: [0] process command "lsinfo "USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/01 Gal Anda.mp3""
Oct 19 10:17 : client: [0] command returned 0
Oct 19 10:17 : client: [0] process command "close"
Oct 19 10:17 : client: [0] command returned 4
Oct 19 10:17 : client: [0] closed
Oct 19 10:17 : client: [3] opened from 127.0.0.1:34012
Oct 19 10:17 : client: [3] process command "status"
Oct 19 10:17 : client: [3] command returned 0
Oct 19 10:17 : client: [3] process command "idle"
Oct 19 10:17 : client: [3] command returned 1
Oct 19 10:17 : client: [4] opened from 127.0.0.1:34016
Oct 19 10:17 : client: [4] process command "lsinfo "USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/01 Gal Anda.mp3""
Oct 19 10:17 : client: [4] command returned 0
Oct 19 10:17 : client: [4] closed
Oct 19 10:17 : client: [1] process command "lsinfo "USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/01 Gal Anda.mp3""
Oct 19 10:17 : client: [1] command returned 0
Oct 19 10:17 : client: [1] process command "close"
Oct 19 10:17 : client: [1] command returned 4
Oct 19 10:17 : client: [1] closed
Oct 19 10:17 : client: [5] opened from 127.0.0.1:34020
Oct 19 10:17 : client: [6] opened from 127.0.0.1:34024
Oct 19 10:17 : client: [6] process command "lsinfo"
Oct 19 10:17 : client: [6] command returned 0
Oct 19 10:17 : client: [6] process command "close"
Oct 19 10:17 : client: [6] command returned 4
Oct 19 10:17 : client: [6] closed
Oct 19 10:17 : client: [7] opened from 127.0.0.1:34028
Oct 19 10:17 : client: [7] process command "status"
Oct 19 10:17 : client: [7] command returned 0
Oct 19 10:17 : client: [7] process command "currentsong"
Oct 19 10:17 : client: [7] command returned 0
Oct 19 10:17 : client: [7] process command "lsinfo "USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/01 Gal Anda.mp3""
Oct 19 10:17 : client: [7] command returned 0
Oct 19 10:17 : client: [7] process command "close"
Oct 19 10:17 : client: [7] command returned 4
Oct 19 10:17 : client: [7] closed
Oct 19 10:17 : client: [8] opened from 127.0.0.1:34032
Oct 19 10:17 : client: [8] process command "status"
Oct 19 10:17 : client: [8] command returned 0
Oct 19 10:17 : client: [8] process command "idle"
Oct 19 10:17 : client: [8] command returned 1
Oct 19 10:17 : client: [5] process command "close"
Oct 19 10:17 : client: [5] command returned 4
Oct 19 10:17 : client: [5] closed
Oct 19 10:17 : client: [9] opened from 127.0.0.1:34036
Oct 19 10:17 : client: [9] process command "playlistinfo"
Oct 19 10:17 : client: [9] command returned 0
Oct 19 10:17 : client: [9] process command "close"
Oct 19 10:17 : client: [9] command returned 4
Oct 19 10:17 : client: [9] closed
Oct 19 10:17 : client: [10] opened from 127.0.0.1:34040
Oct 19 10:17 : client: [10] process command list
Oct 19 10:17 : client: process command "clear"
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/01 Gal Anda.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/02 I Crave For You.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/03 In A Robe Of Fire.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/04 Sol Ek Sa.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/05 My Devotion Will Never Fade.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/06 Lost In Emptiness.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/07 She With Whom Compar'd The Alpes Are Vallies.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/K/Karnnos/A Burial In Flames/01 - Behind The Shadows.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/K/Karnnos/A Burial In Flames/02 - Flowing Void.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/K/Karnnos/A Burial In Flames/03 - Ruins Of Beauty And Love.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/K/Karnnos/A Burial In Flames/04 - Golden Kettle.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/K/Karnnos/A Burial In Flames/05 - Riddle Of Knots.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/K/Karnnos/A Burial In Flames/06 - Winter Serpent.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/K/Karnnos/A Burial In Flames/07 - Torches.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/K/Karnnos/A Burial In Flames/08 - Seethe.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/K/Karnnos/A Burial In Flames/09 - Rod Of Chase.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/K/Karnnos/A Burial In Flames/10 - Land Of Stags.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/K/Karnnos/A Burial In Flames/11 - Cernos Blaze.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/K/Karnnos/A Burial In Flames/12 - A Burial In Flames.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/J/Jännerwein/Abendläuten/01 - Abendläuten.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/J/Jännerwein/Abendläuten/02 - Klage.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/J/Jännerwein/Abendläuten/03 - Instrumental I.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/J/Jännerwein/Abendläuten/04 - The Grass is Always Browner.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/J/Jännerwein/Abendläuten/05 - Sturm.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/J/Jännerwein/Abendläuten/06 - Vereinsamt.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/J/Jännerwein/Abendläuten/07 - Den Berg Empor.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/J/Jännerwein/Abendläuten/08 - Wehmut.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/J/Jännerwein/Abendläuten/09 - Burian.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/J/Jännerwein/Abendläuten/10 - Instrumental II.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/J/Jännerwein/Abendläuten/11 - Bei Gedämpftem Trommelklang.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/J/Jännerwein/Abendläuten/12 - Abendgebet.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/J/Jännerwein/Abendläuten/13 - Ausklang.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/Opium Dream Estate/AlphaOmega/01 - Carpe Mortem.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/Opium Dream Estate/AlphaOmega/02 - The Lady Of My Dreams.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/Opium Dream Estate/AlphaOmega/03 - A Cold Morning.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/Opium Dream Estate/AlphaOmega/04 - Comes From Heaven.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/Opium Dream Estate/AlphaOmega/05 - The Day My Wish Went Away.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/Opium Dream Estate/AlphaOmega/06 - Katmanduh.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/Opium Dream Estate/AlphaOmega/07 - Twilight.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/Opium Dream Estate/AlphaOmega/08 - The Forest Burns.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/Opium Dream Estate/AlphaOmega/09 - The Fog.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/O/Opium Dream Estate/AlphaOmega/10 - My Ethereal Muse.mp3""
Oct 19 10:17 : client: command returned 0
Oct 19 10:17 : client: process command "add "USB/Intenso/K/Kiss the Anus of a Black Cat/An Interlude To The Outermost/01 - Prelude ("The World Is In Fear Again And It Has All Been Manufactured").mp3""
Oct 19 10:17 : exception: Space expected after closing '"'
Oct 19 10:17 : client: command returned 3
Oct 19 10:17 : client: [10] process command list returned 3
Oct 19 10:17 : client: [3] process command "status"
Oct 19 10:17 : client: [3] command returned 0
Oct 19 10:17 : client: [8] process command "status"
Oct 19 10:17 : client: [8] command returned 0
Oct 19 10:17 : client: [3] process command "currentsong"
Oct 19 10:17 : client: [3] command returned 0
Oct 19 10:17 : client: [10] process command "close"
Oct 19 10:17 : client: [10] command returned 4
Oct 19 10:17 : client: [10] closed
Oct 19 10:17 : client: [8] process command "currentsong"
Oct 19 10:17 : client: [8] command returned 0
Oct 19 10:17 : client: [3] process command "close"
Oct 19 10:17 : client: [3] command returned 4
Oct 19 10:17 : client: [3] closed
Oct 19 10:17 : client: [8] process command "close"
Oct 19 10:17 : client: [8] command returned 4
Oct 19 10:17 : client: [8] closed
Oct 19 10:17 : client: [11] opened from 127.0.0.1:34044
Oct 19 10:17 : client: [11] process command "status"
Oct 19 10:17 : client: [11] command returned 0
Oct 19 10:17 : client: [11] process command "idle"
Oct 19 10:17 : client: [11] command returned 1
Oct 19 10:17 : client: [12] opened from 127.0.0.1:34048
Oct 19 10:17 : client: [12] process command "playlistinfo"
Oct 19 10:17 : client: [12] command returned 0
Oct 19 10:17 : client: [12] process command "close"
Oct 19 10:17 : client: [12] command returned 4
Oct 19 10:17 : client: [12] closed
Code:
                                                                                                                              sw_volume: 50
audio_device_state:1:ALSA Default
audio_device_state:0:ALSA Bluetooth
audio_device_state:0:HTTP Server
state: stop
random: 0
repeat: 0
single: 0
consume: 0
crossfade: 0
mixrampdb: 0.000000
mixrampdelay: -1.000000
playlist_begin
0:USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/01 Gal Anda.mp3
1:USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/02 I Crave For You.mp3
2:USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/03 In A Robe Of Fire.mp3
3:USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/04 Sol Ek Sa.mp3
4:USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/05 My Devotion Will Never Fade.mp3
5:USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/06 Lost In Emptiness.mp3
6:USB/Intenso/O/OTWATM/1998 - 2003/CD1 - Algiz/07 She With Whom Compar'd The Alpes Are Vallies.mp3
7:USB/Intenso/K/Karnnos/A Burial In Flames/01 - Behind The Shadows.mp3
8:USB/Intenso/K/Karnnos/A Burial In Flames/02 - Flowing Void.mp3
9:USB/Intenso/K/Karnnos/A Burial In Flames/03 - Ruins Of Beauty And Love.mp3
10:USB/Intenso/K/Karnnos/A Burial In Flames/04 - Golden Kettle.mp3
11:USB/Intenso/K/Karnnos/A Burial In Flames/05 - Riddle Of Knots.mp3
12:USB/Intenso/K/Karnnos/A Burial In Flames/06 - Winter Serpent.mp3
13:USB/Intenso/K/Karnnos/A Burial In Flames/07 - Torches.mp3
14:USB/Intenso/K/Karnnos/A Burial In Flames/08 - Seethe.mp3
15:USB/Intenso/K/Karnnos/A Burial In Flames/09 - Rod Of Chase.mp3
16:USB/Intenso/K/Karnnos/A Burial In Flames/10 - Land Of Stags.mp3
17:USB/Intenso/K/Karnnos/A Burial In Flames/11 - Cernos Blaze.mp3
18:USB/Intenso/K/Karnnos/A Burial In Flames/12 - A Burial In Flames.mp3
19:USB/Intenso/J/Jännerwein/Abendläuten/01 - Abendläuten.mp3
20:USB/Intenso/J/Jännerwein/Abendläuten/02 - Klage.mp3
21:USB/Intenso/J/Jännerwein/Abendläuten/03 - Instrumental I.mp3
22:USB/Intenso/J/Jännerwein/Abendläuten/04 - The Grass is Always Browner.mp3
23:USB/Intenso/J/Jännerwein/Abendläuten/05 - Sturm.mp3
24:USB/Intenso/J/Jännerwein/Abendläuten/06 - Vereinsamt.mp3
25:USB/Intenso/J/Jännerwein/Abendläuten/07 - Den Berg Empor.mp3
26:USB/Intenso/J/Jännerwein/Abendläuten/08 - Wehmut.mp3
27:USB/Intenso/J/Jännerwein/Abendläuten/09 - Burian.mp3
28:USB/Intenso/J/Jännerwein/Abendläuten/10 - Instrumental II.mp3
29:USB/Intenso/J/Jännerwein/Abendläuten/11 - Bei Gedämpftem Trommelklang.mp3
30:USB/Intenso/J/Jännerwein/Abendläuten/12 - Abendgebet.mp3
31:USB/Intenso/J/Jännerwein/Abendläuten/13 - Ausklang.mp3
32:USB/Intenso/O/Opium Dream Estate/AlphaOmega/01 - Carpe Mortem.mp3
33:USB/Intenso/O/Opium Dream Estate/AlphaOmega/02 - The Lady Of My Dreams.mp3
34:USB/Intenso/O/Opium Dream Estate/AlphaOmega/03 - A Cold Morning.mp3
35:USB/Intenso/O/Opium Dream Estate/AlphaOmega/04 - Comes From Heaven.mp3
36:USB/Intenso/O/Opium Dream Estate/AlphaOmega/05 - The Day My Wish Went Away.mp3
37:USB/Intenso/O/Opium Dream Estate/AlphaOmega/06 - Katmanduh.mp3
38:USB/Intenso/O/Opium Dream Estate/AlphaOmega/07 - Twilight.mp3
39:USB/Intenso/O/Opium Dream Estate/AlphaOmega/08 - The Forest Burns.mp3
40:USB/Intenso/O/Opium Dream Estate/AlphaOmega/09 - The Fog.mp3
41:USB/Intenso/O/Opium Dream Estate/AlphaOmega/10 - My Ethereal Muse.mp3
playlist_end
Reply
#10
Code:
Oct 19 10:17 : client: process command "add "USB/Intenso/K/Kiss the Anus of a Black Cat/An Interlude To The Outermost/01 - Prelude ("The World Is In Fear Again And It Has All Been Manufactured").mp3""
Oct 19 10:17 : exception: Space expected after closing '"'
Oct 19 10:17 : client: command returned 3

There's that exception code again. 

The evidence suggests that when the process to select all the tracks of a specific genre hits this exception, it simply stops. The resulting playlist contains however many tracks it has already accumulated. 

That would explain why the odd statistics, why you see the same behavior after all the changes you made, and also why I didn't see anything wrong with the files I looked at---they didn't happen to include one of the "exceptional" files.

How about you zip up the "Kiss..." track (I had to look up this band!) and send me the URL as before.

Regards,
Kent
Reply


Forum Jump: