miniDLNA - Error opening <file>

[winky]

is anybody out there who can help? i'm running arch on raspberry with miniDLNA and got the following error (<file> is a placeholder, of course):

$this->bbcode_second_pass_code('', '
[root@rpi:/var/cache/minidlna]# tail -2 minidlna.log
[2013/03/27 21:39:48] upnphttp.c:1967: error: Error opening <file>.mp3
[2013/03/27 21:40:08] upnphttp.c:1967: error: Error opening <file>.mp4
')

what i did:

On March 16th I installed miniDLNA v1.0.25-5 via pacman (community repo). At this time my Rasp was running under firmware 20130309-1. After setting up minidlna.conf it was no problem to play Videos over WiFi with my Samsung-TV (UE32C6700).

6 days later I upgraded (pacman -Syu) and got some new stuff:

$this->bbcode_second_pass_code('', '
[2013-03-22 23:28] upgraded sqlite (3.7.15.2-1 -> 3.7.16-1)
[2013-03-22 23:30] upgraded raspberrypi-firmware (20130309-1 -> 20130319-1)
')

The other dependencies of miniDLNA (libexif, libjpeg, libid3tag, flac, libvorbis, ffmpeg) are still the same.

But now, if i try to play something, my TV tells me "unsupported file format". I get this error for both, Music (mp3) and Video (mp4). My TV shows all files of my Media-Folders correct, even i put some new files in it or delete something and scan the repo (minidlna -R).
The unplayable files aren't changed! The TV-Firmware isn't changed! The only error of miniDLNA is shown above (...error opening foo...). I'm pretty sure, the problem depends on the ArchUpgrade but i have no idea what i could do.

anybody?

FYI - the whole upgrade-log:

$this->bbcode_second_pass_code('', '
[root@rpi:~]# cat /tmp/pacman_upgrade.log
[2013-03-22 23:24] Running 'pacman -Syu'
[2013-03-22 23:24] synchronizing package lists
[2013-03-22 23:25] starting full system upgrade
[2013-03-22 23:28] upgraded polkit (0.109-1 -> 0.110-1)
[2013-03-22 23:28] upgraded sqlite (3.7.15.2-1 -> 3.7.16-1)
[2013-03-22 23:28] warning: /etc/colord.conf installed as /etc/colord.conf.pacnew
[2013-03-22 23:28] upgraded colord (0.1.28-1 -> 0.1.30-2)
[2013-03-22 23:28] upgraded libxi (1.6.2-1 -> 1.7-1)
[2013-03-22 23:28] upgraded mesa (9.1-3 -> 9.1.1-1)
[2013-03-22 23:28] upgraded mesa-libgl (9.1-3 -> 9.1.1-1)
[2013-03-22 23:28] installed freeglut (2.8.0-2)
[2013-03-22 23:28] installed glu (9.0.0-2)
[2013-03-22 23:28] installed jasper (1.900.1-8)
[2013-03-22 23:28] upgraded gdk-pixbuf2 (2.26.5-1 -> 2.26.5-2)
[2013-03-22 23:28] upgraded geoclue (0.12.0-5 -> 0.12.99-1)
[2013-03-22 23:28] upgraded gettext (0.18.2-1 -> 0.18.2.1-1)
[2013-03-22 23:28] upgraded gpgme (1.3.1-5 -> 1.3.2-1)
[2013-03-22 23:28] upgraded gtk-update-icon-cache (2.24.16-1 -> 2.24.17-1)
[2013-03-22 23:28] upgraded libcups (1.6.1-6 -> 1.6.2-1)
[2013-03-22 23:29] upgraded gtk2 (2.24.16-1 -> 2.24.17-1)
[2013-03-22 23:29] upgraded libassuan (2.0.3-1 -> 2.1.0-1)
[2013-03-22 23:29] upgraded libproxy (0.4.7-2 -> 0.4.11-1)
[2013-03-22 23:29] upgraded man-pages (3.48-1 -> 3.50-1)
[2013-03-22 23:29] upgraded ncurses (5.9-4 -> 5.9-5)
[2013-03-22 23:30] upgraded perl (5.16.2-4 -> 5.16.3-1)
[2013-03-22 23:30] upgraded perl-log-log4perl (1.37-1 -> 1.40-1)
[2013-03-22 23:30] warning: /etc/php/php.ini installed as /etc/php/php.ini.pacnew
[2013-03-22 23:30] upgraded php (5.4.12-1 -> 5.4.13-2)
[2013-03-22 23:30] upgraded php-cgi (5.4.12-1 -> 5.4.13-2)
[2013-03-22 23:30] upgraded php-gd (5.4.12-1 -> 5.4.13-2)
[2013-03-22 23:30] upgraded php-sqlite (5.4.12-1 -> 5.4.13-2)
[2013-03-22 23:30] installed raspberrypi-firmware-bootloader (20130319-1)
[2013-03-22 23:30] installed raspberrypi-firmware-bootloader-x (20130319-1)
[2013-03-22 23:30] installed raspberrypi-firmware-emergency-kernel (20130319-1)
[2013-03-22 23:30] installed raspberrypi-firmware-tools (20130319-1)
[2013-03-22 23:30] installed raspberrypi-firmware-examples (20130319-1)
[2013-03-22 23:30] upgraded raspberrypi-firmware (20130309-1 -> 20130319-1)
[2013-03-22 23:30] upgraded shadow (4.1.5.1-4 -> 4.1.5.1-5)
[2013-03-22 23:30] upgraded sudo (1.8.6.p7-1 -> 1.8.6.p7-2)
[2013-03-22 23:30] upgraded util-linux (2.22.2-1 -> 2.22.2-2)
[2013-03-22 23:30] upgraded xf86-input-evdev (2.7.3-2 -> 2.7.3-3)
[2013-03-22 23:30] upgraded xf86-video-fbdev (0.4.3-2 -> 0.4.3-3)
[2013-03-22 23:30] upgraded xorg-server-common (1.13.3-1 -> 1.14.0-2)
[2013-03-22 23:31] upgraded xorg-server (1.13.3-1 -> 1.14.0-2)
')

[pepedog]

It doesn't matter what platform minidlna on on, it's running fine on my dockstar an Zyxel fine.
Mesa was a big thing. If you altered any service or conf file, other than /etc/minidlna.conf, upgrade to minidlna may trash unless you do it right.
So tell us about which user, and file paths, and let's see files you configured.
And did you sync before reboot?

[winky]

nope, i didn't sync'd before reboot.

could it possible there is a new compiled package required (compare armv6h and armv6l)? i can't remember which kernel i had before upgrading the system but i think the architecture was called armv6h.

$this->bbcode_second_pass_code('', '
[root@rpi:~]# ls -l /var/cache/pacman/pkg/minidlna*
-rw-r--r-- 1 root root 111124 17. Feb 06:17 /var/cache/pacman/pkg/minidlna-1.0.25-5-armv6h.pkg.tar.xz
[root@rpi:~]# uname -a
Linux rpi 3.6.11-8-ARCH+ #1 PREEMPT Sat Mar 9 00:38:58 UTC 2013 armv6l GNU/Linux
')

i didn't changed a lot between setup a working DLNA and a broken DLNA after upgrade. but at the moment i'm reviewing all settings, paths, users and so on, to be sure i have a well formed config. after finishing that i will give an update.

[winky]

ok, i give up. after my unsuccessful review i uninstalled minidlna and set it up again. i can start the service, a scan of Media-Folder is successful and the files are shown on my TV, but i still can't play them.

now the whole config is looking like that:

$this->bbcode_second_pass_code('', '
[root@rpi:~]# pacman -Qi minidlna
Name : minidlna
Version : 1.0.25-5
URL : http://sourceforge.net/projects/minidlna/
Lizenzen : GPL
Gruppen : Nichts
Stellt bereit : Nichts
Hängt ab von : libexif libjpeg libid3tag flac libvorbis ffmpeg sqlite
Optionale Abhängigkeiten: Nichts
Benötigt von : Nichts
Konflikt mit : Nichts
Ersetzt : Nichts
Installationsgröße : 316,00 KiB
Packer : Kevin Mihelich <kevin@archlinuxarm.org>
Architektur : armv6h
Erstellt am : So 17 Feb 2013 06:17:42 CET
Installiert am : Mi 27 Mär 2013 23:36:12 CET
Installationsgrund : Ausdrücklich installiert
Installations-Skript : Ja
Beschreibung : A DLNA/UPnP-AV Media server (aka ReadyDLNA)
')

$this->bbcode_second_pass_code('', '
[root@rpi:~]# uname -a
Linux rpi 3.6.11-8-ARCH+ #1 PREEMPT Sat Mar 9 00:38:58 UTC 2013 armv6l GNU/Linux
')

$this->bbcode_second_pass_code('', '
[root@rpi:~]# grep -Ev "^#|^$" /etc/minidlna.conf
port=8200
media_dir=A,/home/winky/Media/Music
media_dir=V,/home/winky/Media/Videos
media_dir=P,/home/winky/Media/Pictures
friendly_name=RPi
db_dir=/var/cache/minidlna
log_dir=/var/log
album_art_names=Cover.jpg/cover.jpg/AlbumArtSmall.jpg/albumartsmall.jpg/AlbumArt.jpg/albumart.jpg/Album.jpg/album.jpg/Folder.jpg/folder.jpg/Thumb.jpg/thumb.jpg
inotify=no
enable_tivo=no
strict_dlna=no
presentation_url=http://rpi.fritz.box:8200/index.php
notify_interval=900
serial=12345678
model_number=1
')

$this->bbcode_second_pass_code('', '[root@rpi:~]# cat /etc/conf.d/minidlna
MINIDLNA_USER=nobody
MINIDLNA_OPTS=""')

$this->bbcode_second_pass_code('', '
[root@rpi:~]# ls -la /home/winky/Media/
insgesamt 20
drwxr-xr-x 5 winky users 4096 27. Mär 21:23 .
drwx------ 13 winky users 4096 27. Mär 21:28 ..
drwxr-xr-x 3 winky users 4096 27. Mär 21:28 Music
drwxr-xr-x 2 winky users 4096 27. Mär 21:28 Pictures
drwxr-xr-x 3 winky users 4096 17. Mär 19:29 Videos
')

$this->bbcode_second_pass_code('', '
[root@rpi:~]# ls -la /var/{cache,run,log}/minidlna*
-rw-r--r-- 1 nobody nobody 1207 28. Mär 00:12 /var/log/minidlna.log

/var/cache/minidlna:
insgesamt 96
drwxr-xr-x 3 nobody nobody 4096 27. Mär 23:51 .
drwxr-xr-x 9 root root 4096 27. Mär 23:36 ..
drwxr-xr-x 3 nobody nobody 4096 27. Mär 23:51 art_cache
-rw-r--r-- 1 nobody nobody 86016 28. Mär 00:12 files.db

/var/log/minidlna:
insgesamt 8
drwxr-xr-x 2 nobody nobody 4096 27. Mär 23:46 .
drwxr-xr-x 8 root root 4096 27. Mär 23:51 ..

/var/run/minidlna:
insgesamt 4
drwxr-xr-x 2 nobody nobody 60 28. Mär 00:12 .
drwxr-xr-x 19 root root 580 28. Mär 00:04 ..
-rw-r--r-- 1 nobody nobody 6 28. Mär 00:12 minidlna.pid
')

$this->bbcode_second_pass_code('', '
[root@rpi:~]# systemctl status minidlna
minidlna.service - minidlna server
Loaded: loaded (/usr/lib/systemd/system/minidlna.service; enabled)
Active: active (running) since Do 2013-03-28 00:12:15 CET; 2min 55s ago
Process: 14892 ExecStart=/usr/sbin/minidlna -P /var/run/minidlna/minidlna.pid (code=exited, status=0/SUCCESS)
Main PID: 14893 (minidlna)
CGroup: name=systemd:/system/minidlna.service
└─14893 /usr/sbin/minidlna -P /var/run/minidlna/minidlna.pid
[root@rpi:~]# ps -ef | grep minidln[a]
nobody 14893 1 0 00:12 ? 00:00:00 /usr/sbin/minidlna -P /var/run/minidlna/minidlna.pid
')

$this->bbcode_second_pass_code('', '
[root@rpi:~]# tail -3 /var/log/minidlna.log
[2013/03/28 00:12:15] minidlna.c:885: warn: Starting MiniDLNA version 1.0.25 [SQLite 3.7.16].
[2013/03/28 00:12:15] minidlna.c:991: warn: HTTP listening on port 8200
[2013/03/28 00:12:29] upnphttp.c:1967: error: Error opening /home/winky/Media/Videos/TestMovie.mp4
')

The Presentation-URL is running and shows the correct number of files:

$this->bbcode_second_pass_quote('', 'M')iniDLNA status
Audio files: 2
Video files: 11
Image files: 0

[pepedog]

Want to play with permissions or owner of /home/winky/Media/Videos/TestMovie.mp4

[winky]

as expected, the following command didn't help:

$this->bbcode_second_pass_code('', 'chmod -R 777 /home/winky/Media/')

After restart of miniDLNA i get the same errors. Other suggestions?

[winky]

oooh what a shame ... 3 days of debugging all the f**king stuff ... now i've got it:

$this->bbcode_second_pass_code('', '
[root@rpi:~]# ls -ld /home/winky/
drwx------ 13 winky users 4096 1. Jan 1970 /home/winky/
[root@rpi:~]# chmod 755 /home/winky
')

why the hell was my home 700?? i have no idea, but this is the reason, why minidlna worked well as root, but (of course) not as systemuser.
at the moment all is running as expected. the solution is far away of the initial error "unknown file format", but close enough...