SMB error: One or more items failed to play.
dejhost
Newbie
Posts: 5
#1
2015-03-24, 23:23
Hi guys,

I am running Kodi 14.2 on a raspberry pi 1B. For the last two weeks or so, Kodi refuses to play some of my movies. The movie files are located on another computer, running freenas. Protocoll used: SMB.
When starting the movie, most of the time nothing happens. Sometimes, an error message is displayed:
Quote:One or more items failed to play. Check the log file for details.

Here what the log file says:
Code:
21:49:56 T:3037933568  NOTICE: DVDPlayer: Opening: smb://JONAS/Media/Video - temp/The Book of Eli.mp4
21:49:56 T:3037933568 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
21:49:56 T:2706371648  NOTICE: Thread DVDPlayer start, auto delete: false
21:49:56 T:2706371648  NOTICE: Creating InputStream
21:49:56 T:2706371648   ERROR: CDVDPlayer::OpenInputStream - error opening [smb://JONAS/Media/Video - temp/The Book of Eli.mp4]
21:49:56 T:2706371648  NOTICE: CDVDPlayer::OnExit()
21:49:56 T:3037933568   ERROR: Playlist Player: skipping unplayable item: 0, path [smb://JONAS/Media/Video - temp/The Book of Eli.mp4]
21:49:56 T:3037933568  NOTICE: CDVDPlayer::CloseFile()
21:49:56 T:3037933568  NOTICE: DVDPlayer: waiting for threads to exit
21:49:56 T:3037933568  NOTICE: DVDPlayer: finished waiting
21:49:56 T:3037933568  NOTICE: CDVDPlayer::CloseFile()
21:49:56 T:3037933568  NOTICE: DVDPlayer: waiting for threads to exit
21:49:56 T:3037933568  NOTICE: DVDPlayer: finished waiting
21:49:56 T:3037926464  NOTICE: Found 1 Lists of Devices
21:49:56 T:3037926464  NOTICE: Enumerated PI devices:
21:49:56 T:3037926464  NOTICE:     Device 1
21:49:56 T:3037926464  NOTICE:         m_deviceName      : HDMI
21:49:56 T:3037926464  NOTICE:         m_displayName     : HDMI
21:49:56 T:3037926464  NOTICE:         m_displayNameExtra:
21:49:56 T:3037926464  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
21:49:56 T:3037926464  NOTICE:         m_channels        : FL,FR
21:49:56 T:3037926464  NOTICE:         m_sampleRates     : 8000,11025,16000,22050,24000,32000,44100,48000,88200,96000,176400,192000
21:49:56 T:3037926464  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP,AE_FMT_AC3,AE_FMT_DTS,AE_FMT_EAC3
21:49:56 T:3037926464  NOTICE:     Device 2
21:49:56 T:3037926464  NOTICE:         m_deviceName      : Analogue
21:49:56 T:3037926464  NOTICE:         m_displayName     : Analogue
21:49:56 T:3037926464  NOTICE:         m_displayNameExtra:
21:49:56 T:3037926464  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
21:49:56 T:3037926464  NOTICE:         m_channels        : FL,FR
21:49:56 T:3037926464  NOTICE:         m_sampleRates     : 48000
21:49:56 T:3037926464  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
21:49:56 T:3037926464  NOTICE:     Device 3
21:49:56 T:3037926464  NOTICE:         m_deviceName      : Both
21:49:56 T:3037926464  NOTICE:         m_displayName     : HDMI and Analogue
21:49:56 T:3037926464  NOTICE:         m_displayNameExtra:
21:49:56 T:3037926464  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
21:49:56 T:3037926464  NOTICE:         m_channels        : FL,FR
21:49:56 T:3037926464  NOTICE:         m_sampleRates     : 48000
21:49:56 T:3037926464  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
21:50:00 T:2706371648  NOTICE: Thread BackgroundLoader start, auto delete: false

The server log displays sometimes:
Code:
Mar 24 21:16:58 Jonas smbd[31402]:   STATUS=daemon 'smbd' finished starting up and ready to serve connectionsmatchname: host name/name mismatch: 192.168.1.16 != (NULL)
Mar 24 21:16:58 Jonas smbd[31402]: [2015/03/24 21:16:58.397422,  0] ../source3/lib/util_sock.c:1199(get_remote_hostname)
Mar 24 21:16:58 Jonas smbd[31402]:   matchname failed on 192.168.1.16
Mar 24 21:16:58 Jonas kernel: <118>Mar 24 21:16:58 Jonas smbd[31402]:   STATUS=daemon 'smbd' finished starting up and ready to serve connectionsmatchname: host name/name mismatch: 192.168.1.16 != (NULL)
Mar 24 21:16:58 Jonas kernel: <118>Mar 24 21:16:58 Jonas smbd[31402]: [2015/03/24 21:16:58.397422,  0] ../source3/lib/util_sock.c:1199(get_remote_hostname)
Mar 24 21:16:58 Jonas kernel: <118>Mar 24 21:16:58 Jonas smbd[31402]:   matchname failed on 192.168.1.16

Could somebody please tell me how to fix this? Thank you very much!
Find
Reply
Milhouse
Retired Team-Kodi Member
Posts: 17,855
#2
2015-03-24, 23:32
Use IP address instead of hostname.

Use NFS instead of SMB.

I run a FreeNAS 8.3x server and have found the SMB authentication to be rather "fragile" - reboot the FreeNAS box and you need to reset the password on the FreeNAS user account in order to successfully authenticate again from a Windows 7 PC (resetting the password on the FreeNAS user account updates the associated smb password database). Fortunately I don't reboot the FreeNAS box often enough for it to become tiresome.
Texture Cache Maintenance Utility: Preload your texture cache for optimal UI performance. Remotely manage media libraries. Purge unused artwork to free up space. Find missing media. Configurable QA check to highlight metadata issues. Aid in diagnosis of library and cache related problems.
Find
Reply
dejhost
Newbie
Posts: 5
#3
2015-03-24, 23:53
Thank you, Milhouse.
Using NFS and IP address instead of the hostname, I cannot play any movies in the folder. Same symptoms. Using smb, only some movies used to fail.

Here, the latest logfile from Kodi.
Code:
22:40:35 T:2903499840   DEBUG: webserver: request received for /jsonrpc
22:40:39 T:3037933568   DEBUG: Previous line repeats 26 times.
22:40:39 T:3037933568   DEBUG: OnPlayMedia nfs://192.168.1.10/mnt/Media/Video - temp/The Book of Eli.mp4
22:40:39 T:3037933568   DEBUG: GetMovieId (nfs://192.168.1.10/mnt/Media/Video - temp/The Book of Eli.mp4), query = select idMovie from movie where idFile=490
22:40:39 T:3037933568   DEBUG: GetEpisodeId (nfs://192.168.1.10/mnt/Media/Video - temp/The Book of Eli.mp4), query = select idEpisode from episode where idFile=490
22:40:39 T:3037933568   DEBUG: GetMusicVideoId (nfs://192.168.1.10/mnt/Media/Video - temp/The Book of Eli.mp4), query = select idMVideo from musicvideo where idFile=490
22:40:39 T:3037933568   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
22:40:39 T:3037933568   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
22:40:39 T:2706371648   DEBUG: Thread BackgroundLoader 2706371648 terminating
22:40:39 T:3037933568   DEBUG: Loading settings for nfs://192.168.1.10/mnt/Media/Video - temp/The Book of Eli.mp4
22:40:39 T:3037933568   DEBUG: CPlayerCoreFactory::GetPlayers(nfs://192.168.1.10/mnt/Media/Video - temp/The Book of Eli.mp4)
22:40:39 T:3037933568   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
22:40:39 T:3037933568   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
22:40:39 T:3037933568   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
22:40:39 T:3037933568   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
22:40:39 T:3037933568   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
22:40:39 T:3037933568   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
22:40:39 T:3037933568   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
22:40:39 T:3037933568   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
22:40:39 T:3037933568   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
22:40:39 T:3037933568   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
22:40:39 T:3037933568   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
22:40:39 T:3037933568   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
22:40:39 T:3037933568   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
22:40:39 T:3037933568   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
22:40:39 T:3037933568   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
22:40:39 T:3037933568   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
22:40:39 T:3037933568   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
22:40:39 T:3037933568   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
22:40:39 T:3037933568   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: Yamaha (4)
22:40:39 T:3037933568   DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
22:40:39 T:3029537856   DEBUG: CAESinkPi:Drain delay:99ms now:0ms
22:40:39 T:3029537856   DEBUG: CAESinkPi:Deinitialize
22:40:39 T:3029537856   DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000
22:40:39 T:3029537856   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0xac79c278
22:40:40 T:3037933568  NOTICE: DVDPlayer: Opening: nfs://192.168.1.10/mnt/Media/Video - temp/The Book of Eli.mp4
22:40:40 T:3037933568 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
22:40:40 T:3037933568   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
22:40:40 T:3037933568   DEBUG: CMMALRenderer::UnInit
22:40:40 T:3037933568   DEBUG: CMMALRenderer::PreInit
22:40:40 T:2706371648  NOTICE: Thread DVDPlayer start, auto delete: false
22:40:40 T:2706371648  NOTICE: Creating InputStream
22:40:40 T:2706371648    INFO: CNFSFile::Open: Unable to open file : 'mnt/Media/Video - temp/The Book of Eli.mp4'  error : 'open call failed with "NFS: ACCESS denied. Required access r--. Allowed access ---"'
22:40:40 T:2706371648   ERROR: CDVDPlayer::OpenInputStream - error opening [nfs://192.168.1.10/mnt/Media/Video - temp/The Book of Eli.mp4]
22:40:40 T:2706371648  NOTICE: CDVDPlayer::OnExit()
22:40:40 T:2706371648   DEBUG: OnPlayBackStopped: play state was 1, starting 1
22:40:40 T:3037933568   DEBUG: OnPlayBackStopped: play state was 3, starting 0
22:40:40 T:3037933568   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
22:40:40 T:3037933568   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
22:40:40 T:3037933568   ERROR: Playlist Player: skipping unplayable item: 0, path [nfs://192.168.1.10/mnt/Media/Video - temp/The Book of Eli.mp4]
22:40:40 T:3037933568   DEBUG: Playlist Player: no more playable items... aborting playback
22:40:40 T:2706371648   DEBUG: Thread DVDPlayer 2706371648 terminating
22:40:40 T:2680370240  NOTICE: Thread BackgroundLoader start, auto delete: false
22:40:40 T:3037933568  NOTICE: CDVDPlayer::CloseFile()
22:40:40 T:3037933568  NOTICE: DVDPlayer: waiting for threads to exit
22:40:40 T:3037933568  NOTICE: DVDPlayer: finished waiting
22:40:40 T:3037933568   DEBUG: CMMALRenderer::UnInit
22:40:40 T:3037933568  NOTICE: CDVDPlayer::CloseFile()
22:40:40 T:3037933568  NOTICE: DVDPlayer: waiting for threads to exit
22:40:40 T:3037933568  NOTICE: DVDPlayer: finished waiting
22:40:40 T:3037933568   DEBUG: CMMALRenderer::UnInit
22:40:40 T:3037926464    INFO: CAESinkALSA - Unable to open device "default" for playback
22:40:40 T:3037926464  NOTICE: Found 1 Lists of Devices
22:40:40 T:3037926464  NOTICE: Enumerated PI devices:
22:40:40 T:3037926464  NOTICE:     Device 1
22:40:40 T:3037926464  NOTICE:         m_deviceName      : HDMI
22:40:40 T:3037926464  NOTICE:         m_displayName     : HDMI
22:40:40 T:3037926464  NOTICE:         m_displayNameExtra:
22:40:40 T:3037926464  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
22:40:40 T:3037926464  NOTICE:         m_channels        : FL,FR
22:40:40 T:3037926464  NOTICE:         m_sampleRates     : 8000,11025,16000,22050,24000,32000,44100,48000,88200,96000,176400,192000
22:40:40 T:3037926464  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP,AE_FMT_AC3,AE_FMT_DTS,AE_FMT_EAC3
22:40:40 T:3037926464  NOTICE:     Device 2
22:40:40 T:3037926464  NOTICE:         m_deviceName      : Analogue
22:40:40 T:3037926464  NOTICE:         m_displayName     : Analogue
22:40:40 T:3037926464  NOTICE:         m_displayNameExtra:
22:40:40 T:3037926464  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
22:40:40 T:3037926464  NOTICE:         m_channels        : FL,FR
22:40:40 T:3037926464  NOTICE:         m_sampleRates     : 48000
22:40:40 T:3037926464  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
22:40:40 T:3037926464  NOTICE:     Device 3
22:40:40 T:3037926464  NOTICE:         m_deviceName      : Both
22:40:40 T:3037926464  NOTICE:         m_displayName     : HDMI and Analogue
22:40:40 T:3037926464  NOTICE:         m_displayNameExtra:
22:40:40 T:3037926464  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
22:40:40 T:3037926464  NOTICE:         m_channels        : FL,FR
22:40:40 T:3037926464  NOTICE:         m_sampleRates     : 48000
22:40:40 T:3037926464  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
22:40:40 T:3029537856    INFO: CActiveAESink::OpenSink - initialize sink
22:40:40 T:3029537856   DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI
22:40:40 T:3029537856   DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008
22:40:40 T:3029537856   DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI
22:40:40 T:3029537856   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0xac7ca4e8
22:40:40 T:3029537856   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignmen(16)
22:40:40 T:3029537856   DEBUG: CActiveAESink::OpenSink - SinkPi Initialized:
22:40:40 T:3029537856   DEBUG:   Output Device : HDMI
22:40:40 T:3029537856   DEBUG:   Sample Rate   : 44100
22:40:40 T:3029537856   DEBUG:   Sample Format : AE_FMT_FLOAT
22:40:40 T:3029537856   DEBUG:   Channel Count : 2
22:40:40 T:3029537856   DEBUG:   Channel Layout: FL,FR
22:40:40 T:3029537856   DEBUG:   Frames        : 2205
22:40:40 T:3029537856   DEBUG:   Frame Samples : 4410
22:40:40 T:3029537856   DEBUG:   Frame Size    : 8
22:40:40 T:3037926464   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted

dejhost
Find
Reply
Milhouse
Retired Team-Kodi Member
Posts: 17,855
#4
2015-03-25, 00:57
Code:
22:40:40 T:2706371648    INFO: CNFSFile::Open: Unable to open file : 'mnt/Media/Video - temp/The Book of Eli.mp4'  error : 'open call failed with "NFS: ACCESS denied. Required access r--. Allowed access ---"'
You haven't configured the NFS server export correctly.

If you're using OpenELEC, on the FreeNAS server for the NFS share, map root to a valid FreeNAS user with read access to the media files. If it's another distribution, make sure the user running Kodi has permission to access (read) the files on the FreeNAS server.
Texture Cache Maintenance Utility: Preload your texture cache for optimal UI performance. Remotely manage media libraries. Purge unused artwork to free up space. Find missing media. Configurable QA check to highlight metadata issues. Aid in diagnosis of library and cache related problems.
Find
Reply
dejhost
Newbie
Posts: 5
#5
2015-03-25, 20:44
Hi again,

still having troubles with this one...

I am using Raspbmc. Not 100% sure where I define the user within Kodi. Is this the "Profiles" in the system settings? I created a profile named "media" and I boot into this one. I deleted all other (CIFS) shares in Kodi for now.

On the freenas, version 9.3: The same user ("media") exists here, having access to the files.
NFS share configuration:
Mapping all directories: yes
Map root user: media

NFS service:
Allow non root mount: yes
Enable NFSv4: yes


Opening a movie on Kodi gives me these logs:
Kodi log:
Code:
19:33:53 T:3038015488  NOTICE: DVDPlayer: Opening: nfs://192.168.1.10/mnt/Media/Video - temp/2046.avi
19:33:53 T:3038015488 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:33:53 T:2820895808  NOTICE: Thread DVDPlayer start, auto delete: false
19:33:53 T:2820895808  NOTICE: Creating InputStream
19:33:53 T:2820895808   ERROR: CDVDPlayer::OpenInputStream - error opening [nfs://192.168.1.10/mnt/Media/Video - temp/2046.avi]
19:33:53 T:2820895808  NOTICE: CDVDPlayer::OnExit()
19:33:53 T:3038015488   ERROR: Playlist Player: skipping unplayable item: 0, path [nfs://192.168.1.10/mnt/Media/Video - temp/2046.avi]
19:33:54 T:3038015488  NOTICE: CDVDPlayer::CloseFile()
19:33:54 T:3038015488  NOTICE: DVDPlayer: waiting for threads to exit
19:33:54 T:3038015488  NOTICE: DVDPlayer: finished waiting
19:33:54 T:3038015488  NOTICE: CDVDPlayer::CloseFile()
19:33:54 T:3038015488  NOTICE: DVDPlayer: waiting for threads to exit
19:33:54 T:3038015488  NOTICE: DVDPlayer: finished waiting
19:33:54 T:3038008384  NOTICE: Found 1 Lists of Devices
19:33:54 T:3038008384  NOTICE: Enumerated PI devices:
19:33:54 T:3038008384  NOTICE:     Device 1
19:33:54 T:3038008384  NOTICE:         m_deviceName      : HDMI
19:33:54 T:3038008384  NOTICE:         m_displayName     : HDMI
19:33:54 T:3038008384  NOTICE:         m_displayNameExtra:
19:33:54 T:3038008384  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
19:33:54 T:3038008384  NOTICE:         m_channels        : FL,FR
19:33:54 T:3038008384  NOTICE:         m_sampleRates     : 8000,11025,16000,22050,24000,32000,44100,48000,88200,96000,176400,192000
19:33:54 T:3038008384  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP,AE_FMT_AC3,AE_FMT_DTS,AE_FMT_EAC3
19:33:54 T:3038008384  NOTICE:     Device 2
19:33:54 T:3038008384  NOTICE:         m_deviceName      : Analogue
19:33:54 T:3038008384  NOTICE:         m_displayName     : Analogue
19:33:54 T:3038008384  NOTICE:         m_displayNameExtra:
19:33:54 T:3038008384  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
19:33:54 T:3038008384  NOTICE:         m_channels        : FL,FR
19:33:54 T:3038008384  NOTICE:         m_sampleRates     : 48000
19:33:54 T:3038008384  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
19:33:54 T:3038008384  NOTICE:     Device 3
19:33:54 T:3038008384  NOTICE:         m_deviceName      : Both
19:33:54 T:3038008384  NOTICE:         m_displayName     : HDMI and Analogue
19:33:54 T:3038008384  NOTICE:         m_displayNameExtra:
19:33:54 T:3038008384  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
19:33:54 T:3038008384  NOTICE:         m_channels        : FL,FR
19:33:54 T:3038008384  NOTICE:         m_sampleRates     : 48000
19:33:54 T:3038008384  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
19:33:56 T:2820895808  NOTICE: Thread BackgroundLoader start, auto delete: false
19:35:27 T:3038015488  NOTICE: NFS is idle. Closing the remaining connections.

Freenas log:
Code:
Mar 25 19:29:46 Jonas mountd[45823]: export request succeeded from 192.168.1.16
Mar 25 19:29:46 Jonas mountd[45823]: mount request succeeded from 192.168.1.16 for /mnt/Media
Mar 25 19:39:52 Jonas notifier: Stopping lockd.
Mar 25 19:39:52 Jonas notifier: Waiting for PIDS: 45923.
Mar 25 19:39:52 Jonas notifier: Stopping statd.
Mar 25 19:39:52 Jonas notifier: Stopping nfsd.
Mar 25 19:39:52 Jonas notifier: Waiting for PIDS: 45866 45867.
Mar 25 19:39:52 Jonas notifier: Stopping mountd.
Mar 25 19:39:52 Jonas notifier: Waiting for PIDS: 45823.
Mar 25 19:39:52 Jonas notifier: Stopping nfsuserd.
Mar 25 19:39:52 Jonas notifier: kill: 45786: No such process
Mar 25 19:39:52 Jonas notifier: kill: 45787: No such process
Mar 25 19:39:52 Jonas notifier: kill: 45788: No such process
Mar 25 19:39:52 Jonas notifier: Waiting for PIDS: 45785.
Mar 25 19:39:52 Jonas notifier: Stopping gssd.
Mar 25 19:39:52 Jonas notifier: Waiting for PIDS: 45769.
Mar 25 19:39:52 Jonas notifier: Stopping rpcbind.
Mar 25 19:39:52 Jonas notifier: Waiting for PIDS: 45819.
Mar 25 19:39:52 Jonas notifier: mountd not running? (check /var/run/mountd.pid).
Mar 25 19:39:52 Jonas notifier: Starting gssd.
Mar 25 19:39:52 Jonas notifier: Starting nfsuserd.
Mar 25 19:39:53 Jonas notifier: Starting rpcbind.
Mar 25 19:39:53 Jonas notifier: Starting mountd.
Mar 25 19:39:53 Jonas notifier: Starting nfsd.
Mar 25 19:39:53 Jonas notifier: Starting statd.
Mar 25 19:39:53 Jonas kernel: nfsd: can't register svc name
Mar 25 19:39:53 Jonas notifier: Starting lockd.
Mar 25 19:41:12 Jonas notifier: Stopping lockd.
Mar 25 19:41:12 Jonas notifier: Waiting for PIDS: 48015.
Mar 25 19:41:12 Jonas notifier: Stopping statd.
Mar 25 19:41:12 Jonas notifier: Stopping nfsd.
Mar 25 19:41:12 Jonas notifier: Waiting for PIDS: 47958.
Mar 25 19:41:13 Jonas notifier: Stopping mountd.
Mar 25 19:41:13 Jonas notifier: Waiting for PIDS: 47915.
Mar 25 19:41:13 Jonas notifier: Stopping nfsuserd.
Mar 25 19:41:13 Jonas notifier: kill: 47878: No such process
Mar 25 19:41:13 Jonas notifier: kill: 47879: No such process
Mar 25 19:41:13 Jonas notifier: kill: 47880: No such process
Mar 25 19:41:13 Jonas notifier: kill: 47881: No such process
Mar 25 19:41:13 Jonas notifier: Stopping gssd.
Mar 25 19:41:13 Jonas notifier: Waiting for PIDS: 47861.
Mar 25 19:41:13 Jonas notifier: Stopping rpcbind.
Mar 25 19:41:13 Jonas notifier: Waiting for PIDS: 47911.
Mar 25 19:41:13 Jonas notifier: mountd not running? (check /var/run/mountd.pid).
Mar 25 19:41:13 Jonas notifier: Starting gssd.
Mar 25 19:41:13 Jonas notifier: Starting nfsuserd.
Mar 25 19:41:13 Jonas notifier: Starting rpcbind.
Mar 25 19:41:13 Jonas notifier: Starting mountd.
Mar 25 19:41:13 Jonas notifier: Starting nfsd.
Mar 25 19:41:14 Jonas notifier: Starting statd.
Mar 25 19:41:14 Jonas kernel: nfsd: can't register svc name
Mar 25 19:41:14 Jonas notifier: Starting lockd.
Mar 25 19:41:22 Jonas mountd[48663]: export request succeeded from 192.168.1.16
Mar 25 19:41:22 Jonas mountd[48663]: mount request succeeded from 192.168.1.16 for /mnt/Media

Thanks so much for helping!
Find
Reply
Milhouse
Retired Team-Kodi Member
Posts: 17,855
#6
2015-03-25, 20:59
In Raspbmc, the Kodi application is run by the "pi" user, which has a specific uid/gid (user id/group id) and it's this uid/gid that will be accessing your files over NFS.

You have the option to "map" all NFS users to a single user on the FreeNAS server - this basically maps any user accessing your NFS shares to the uid/gid of a FreeNAS account. Or you can create an account on the FreeNAS server, and set the uid/gid of this user to be the same as the "pi" user on the RPi so that the accounts "map" automatically - you can view the uid/gid of the "pi" user by running "id pi" on the RPi.

What you've done is map the "root" NFS user to your FreeNAS "media" account, this is known as "root squashing", and is appropriate for OpenELEC (where Kodi runs under root) but not Raspbmc, as the root account isn't being used. What should work is mapping all users to your "media" account, then any NFS connection from user "pi" will be handled with the permissions of FreeNAS user "media". Obviously this means _every_ NFS user will be mapped to "media", but for a home environment this may not be a problem.

For SMB/CIFS, the user accessing the FreeNAS content is defined by the username/password properties of the connection, which you add in either your sources.xml or set in password.xml.
Texture Cache Maintenance Utility: Preload your texture cache for optimal UI performance. Remotely manage media libraries. Purge unused artwork to free up space. Find missing media. Configurable QA check to highlight metadata issues. Aid in diagnosis of library and cache related problems.
Find
Reply
dejhost
Newbie
Posts: 5
#7
2015-03-25, 22:42
Thanks for being so patient with me, Millhouse.

The first of your suggestions works:

Quote:What should work is mapping all users to your "media" account, then any NFS connection from user "pi" will be handled with the permissions of FreeNAS user "media".

So I map all incomming NFS users to the freenas user "media", and finally, I can access the files. What a relief Blush

But I would rather go for your second suggestion:
Quote:Or you can create an account on the FreeNAS server, and set the uid/gid of this user to be the same as the "pi" user on the RPi so that the accounts "map" automatically

UID and GID equals "1000" on the Raspberry. So on freenas, I created
  • an user "pi" with UID "1000" and
  • a group "media" with GID 1000.

Both users
  • the old user "media" (UID "1003") and
  • the new user "pi" (UID "1000")
join this new group "media" (GID "1000").

From the setup, I cannot not tell any difference between the two users. Except for the UID and the name, of course.

Still: The only constellation where Kodi can access the files is the one described in the beginning (map all to user media). None of the two users succeed when "map root" (or no mapping at all) is selected, independant if I activate additionally any group mapping. Any idea why that might be?

Kodi logfile:
Code:
21:19:39 T:3038072832 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
21:19:39 T:2877289536  NOTICE: Thread DVDPlayer start, auto delete: false
21:19:39 T:2877289536  NOTICE: Creating InputStream
21:19:39 T:2877289536   ERROR: CDVDPlayer::OpenInputStream - error opening [nfs://192.168.1.10/mnt/Media/Video - Movies/The Artist.avi]
21:19:39 T:2877289536  NOTICE: CDVDPlayer::OnExit()
21:19:39 T:3038072832   ERROR: Playlist Player: skipping unplayable item: 0, path [nfs://192.168.1.10/mnt/Media/Video - Movies/The Artist.avi]
21:19:39 T:2894066752  NOTICE: Thread BackgroundLoader start, auto delete: false
21:19:39 T:3038072832  NOTICE: CDVDPlayer::CloseFile()
21:19:39 T:3038072832  NOTICE: DVDPlayer: waiting for threads to exit
21:19:39 T:3038072832  NOTICE: DVDPlayer: finished waiting
21:19:39 T:3038072832  NOTICE: CDVDPlayer::CloseFile()
21:19:39 T:3038072832  NOTICE: DVDPlayer: waiting for threads to exit
21:19:39 T:3038072832  NOTICE: DVDPlayer: finished waiting
21:19:39 T:3038065728  NOTICE: Found 1 Lists of Devices
21:19:39 T:3038065728  NOTICE: Enumerated PI devices:
21:19:39 T:3038065728  NOTICE:     Device 1
21:19:39 T:3038065728  NOTICE:         m_deviceName      : HDMI
21:19:39 T:3038065728  NOTICE:         m_displayName     : HDMI
21:19:39 T:3038065728  NOTICE:         m_displayNameExtra:
21:19:39 T:3038065728  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
21:19:39 T:3038065728  NOTICE:         m_channels        : FL,FR
21:19:39 T:3038065728  NOTICE:         m_sampleRates     : 8000,11025,16000,22050,24000,32000,44100,48000,88200,96000,176400,192000
21:19:39 T:3038065728  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP,AE_FMT_AC3,AE_FMT_DTS,AE_FMT_EAC3
21:19:39 T:3038065728  NOTICE:     Device 2
21:19:39 T:3038065728  NOTICE:         m_deviceName      : Analogue
21:19:39 T:3038065728  NOTICE:         m_displayName     : Analogue
21:19:39 T:3038065728  NOTICE:         m_displayNameExtra:
21:19:39 T:3038065728  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
21:19:39 T:3038065728  NOTICE:         m_channels        : FL,FR
21:19:39 T:3038065728  NOTICE:         m_sampleRates     : 48000
21:19:39 T:3038065728  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
21:19:39 T:3038065728  NOTICE:     Device 3
21:19:39 T:3038065728  NOTICE:         m_deviceName      : Both
21:19:39 T:3038065728  NOTICE:         m_displayName     : HDMI and Analogue
21:19:39 T:3038065728  NOTICE:         m_displayNameExtra:
21:19:39 T:3038065728  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
21:19:39 T:3038065728  NOTICE:         m_channels        : FL,FR
21:19:39 T:3038065728  NOTICE:         m_sampleRates     : 48000
21:19:39 T:3038065728  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
Find
Reply
Milhouse
Retired Team-Kodi Member
Posts: 17,855
#8
2015-03-25, 23:28
(2015-03-25, 22:42)dejhost Wrote: Still: The only constellation where Kodi can access the files is the one described in the beginning (map all to user media). None of the two users succeed when "map root" (or no mapping at all) is selected, independant if I activate additionally any group mapping. Any idea why that might be?

Did you change the ownership of the files on the FreeNAS server from media (uid:1003, it will be using some default group, not sure what it will be) to pi:media (uid:1000, gid:1000)?

You can do this on the FreeNAS server with "chown -R pi:media *", though you might need to perform this as "root".
Texture Cache Maintenance Utility: Preload your texture cache for optimal UI performance. Remotely manage media libraries. Purge unused artwork to free up space. Find missing media. Configurable QA check to highlight metadata issues. Aid in diagnosis of library and cache related problems.
Find
Reply
dejhost
Newbie
Posts: 5
#9
2015-03-26, 13:25
Of course - that's it. Thanks you very much!!!

dejhost
Find
Reply

Home
Search
Help
Logout Mark Read Team Forum Stats Members Help
SMB error: One or more items failed to play.0