Jump to content

NanoPc-T4 crashes when running emby server


Recommended Posts

Posted (edited)

Hey guys,

my Nanopc-T4 armhf device with modern specs crashes completely when using emby. I have not yet determined the cause of the crashes and I'm looking for help. Basically I start up the server, it runs for about 10-15 mins and everything works as expected and then the whole system dies with no way to ssh into it anymore.

Things I have tried:

 

- let system idle for a couple days -> works

- update emby to latest release (3.4.1.0 -> 3.5.30)

- check top during emby startup, uses around 25% processing power

 

One thing I have yet to try is a stress test to see if it's not some faulty Lubuntu installation casuing this.

 

The media library is located on a NAS, all drives are mounted through NFS to the nanopc. I think before I added any media to emby, the system perfomed normally  without any crashes while emby was running, so my guess is it must have something to do with either using nfs or corrupted/faulty media. It's just surprising that the whole box dies and not just emby itself.

 

How could I further examine the cause for this?

Edited by Mr_christer
Mr_christer
Posted (edited)

Hey luke,
I finally got around to do some more testing.

Stress test - system was stable under load for the tested time.

LOGS - I wrote a quick bash script that would continously copy all emby logs from /var/lib/emby/logs to a remote server so this log has all the data until 1sec before it become unresponsive.

 

2018-10-01 12:34:58.472 Info Main: Application path: /opt/emby-server/system/EmbyServer.dll
2018-10-01 12:34:58.558 Info Main: Emby
Command line: /opt/emby-server/system/EmbyServer.dll -programdata /var/lib/emby -ffmpeg /opt/emby-server/bin/ffmpeg -ffprobe /opt/emby-server/bin/ffprobe -restartexitcode 3 -updatepackage emby-server-deb_{version}_armhf.deb
Operating system: Unix 4.4.126.0
64-Bit OS: False
64-Bit Process: False
User Interactive: True
Processor count: 6
Program data path: /var/lib/emby
Application directory: /opt/emby-server/system
2018-10-01 12:34:59.694 Info App: Application version: 3.5.3.0
2018-10-01 12:34:59.703 Info App: Loading assemblies
2018-10-01 12:34:59.829 Info App: File /var/lib/emby/plugins/IMVDb.dll has version 1.0.1.0
2018-10-01 12:34:59.832 Info App: File /opt/emby-server/system/plugins/IMVDb.dll has version 1.0.1.0
2018-10-01 12:34:59.847 Info App: File /var/lib/emby/plugins/OpenSubtitles.dll has version 1.0.2.0
2018-10-01 12:34:59.849 Info App: File /opt/emby-server/system/plugins/OpenSubtitles.dll has version 1.0.2.0
2018-10-01 12:34:59.852 Info App: File /var/lib/emby/plugins/Emby.Server.CinemaMode.dll has version 1.0.2.0
2018-10-01 12:34:59.854 Info App: File /opt/emby-server/system/plugins/Emby.Server.CinemaMode.dll has version 1.0.2.0
2018-10-01 12:34:59.886 Info App: Loading IMVDb, Version=1.0.1.0, Culture=neutral, PublicKeyToken=null from /var/lib/emby/plugins/IMVDb.dll
2018-10-01 12:34:59.887 Info App: Loading OpenSubtitles, Version=1.0.2.0, Culture=neutral, PublicKeyToken=null from /var/lib/emby/plugins/OpenSubtitles.dll
2018-10-01 12:34:59.887 Info App: Loading Emby.Server.CinemaMode, Version=1.0.2.0, Culture=neutral, PublicKeyToken=null from /var/lib/emby/plugins/Emby.Server.CinemaMode.dll
2018-10-01 12:34:59.887 Info App: Loading MediaBrowser.Api, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:34:59.887 Info App: Loading MediaBrowser.WebDashboard, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:34:59.887 Info App: Loading MediaBrowser.Model, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:34:59.887 Info App: Loading MediaBrowser.Common, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:34:59.887 Info App: Loading MediaBrowser.Controller, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:34:59.887 Info App: Loading MediaBrowser.Providers, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:34:59.887 Info App: Loading Emby.Photos, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:34:59.887 Info App: Loading Emby.Server.Implementations, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:34:59.887 Info App: Loading Emby.Server.MediaEncoding, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:34:59.887 Info App: Loading Emby.Dlna, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:34:59.887 Info App: Loading MediaBrowser.LocalMetadata, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:34:59.887 Info App: Loading Emby.Notifications, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:34:59.887 Info App: Loading MediaBrowser.XbmcMetadata, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:34:59.887 Info App: Loading Emby.Server.Connect, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:34:59.887 Info App: Loading Emby.Server.Sync, Version=3.5.3.0, Culture=neutral, PublicKeyToken=null
2018-10-01 12:35:00.549 Info SqliteUserRepository: Sqlite version: 3.24.0
2018-10-01 12:35:00.554 Info SqliteUserRepository: Sqlite compiler options: COMPILER=gcc-6.4.1 20170707,ENABLE_COLUMN_METADATA,ENABLE_DBSTAT_VTAB,ENABLE_FTS3,ENABLE_FTS3_PARENTHESIS,ENABLE_FTS3_TOKENIZER,ENABLE_FTS5,ENABLE_JSON1,ENABLE_PREUPDATE_HOOK,ENABLE_RTREE,ENABLE_SESSION,ENABLE_UNLOCK_NOTIFY,ENABLE_UPDATE_DELETE_LIMIT,LIKE_DOESNT_MATCH_BLOBS,MAX_SCHEMA_RETRY=25,MAX_VARIABLE_NUMBER=250000,OMIT_LOOKASIDE,SECURE_DELETE,THREADSAFE=1
2018-10-01 12:35:00.764 Info SqliteUserRepository: Default journal_mode for /var/lib/emby/data/users.db is wal
2018-10-01 12:35:00.784 Info SqliteUserRepository: PRAGMA synchronous=1
2018-10-01 12:35:00.866 Info AuthenticationRepository: Default journal_mode for /var/lib/emby/data/authentication.db is wal
2018-10-01 12:35:00.876 Info AuthenticationRepository: PRAGMA synchronous=1
2018-10-01 12:35:01.277 Info ActivityRepository: Default journal_mode for /var/lib/emby/data/activitylog.db is wal
2018-10-01 12:35:01.277 Info ActivityRepository: PRAGMA synchronous=1
2018-10-01 12:35:01.295 Info SqliteDisplayPreferencesRepository: Default journal_mode for /var/lib/emby/data/displaypreferences.db is wal
2018-10-01 12:35:01.295 Info SqliteDisplayPreferencesRepository: PRAGMA synchronous=1
2018-10-01 12:35:01.958 Info SqliteItemRepository: Default journal_mode for /var/lib/emby/data/library.db is wal
2018-10-01 12:35:01.960 Info SqliteItemRepository: PRAGMA synchronous=1
2018-10-01 12:35:02.849 Info HttpServer: Calling ServiceStack AppHost.Init
2018-10-01 12:35:05.305 Info HttpServer: Adding HttpListener prefix http://+:8096/
2018-10-01 12:35:05.308 Info HttpServer: Adding HttpListener prefix https://+:8920/
2018-10-01 12:35:06.831 Info Skia: SkiaSharp version: 1.60.0.0
2018-10-01 12:35:07.098 Info TaskManager: Daily trigger for Chapter image extraction set to fire at 2018-10-02 2:00:00 a.m., which is 804.88170032 minutes from now.
2018-10-01 12:35:07.179 Info TaskManager: Daily trigger for Rotate log file set to fire at 2018-10-02 12:00:00 a.m., which is 684.880352555 minutes from now.
2018-10-01 12:35:07.235 Info MediaEncoder: FFMpeg: /opt/emby-server/bin/ffmpeg
2018-10-01 12:35:07.235 Info MediaEncoder: FFProbe: /opt/emby-server/bin/ffprobe
2018-10-01 12:35:07.237 Info MediaEncoder: Validating media encoder at /opt/emby-server/bin/ffmpeg
2018-10-01 12:35:07.251 Info MediaEncoder: Running /opt/emby-server/bin/ffmpeg -decoders
2018-10-01 12:35:07.969 Info MediaEncoder: Decoder available: mpeg2video
2018-10-01 12:35:07.973 Info MediaEncoder: Decoder available: ac3
2018-10-01 12:35:07.974 Info MediaEncoder: Decoder available: aac
2018-10-01 12:35:07.975 Info MediaEncoder: Decoder available: mp3
2018-10-01 12:35:07.975 Info MediaEncoder: Decoder available: h264
2018-10-01 12:35:07.975 Info MediaEncoder: Decoder available: hevc
2018-10-01 12:35:07.983 Info MediaEncoder: Running /opt/emby-server/bin/ffmpeg -encoders
2018-10-01 12:35:08.469 Info MediaEncoder: Encoder available: libx264
2018-10-01 12:35:08.469 Info MediaEncoder: Encoder available: aac
2018-10-01 12:35:08.469 Info MediaEncoder: Encoder available: libmp3lame
2018-10-01 12:35:08.469 Info MediaEncoder: Encoder available: libopus
2018-10-01 12:35:08.469 Info MediaEncoder: Encoder available: libvorbis
2018-10-01 12:35:08.469 Info MediaEncoder: Encoder available: srt
2018-10-01 12:35:08.470 Info MediaEncoder: Encoder available: libwebp
2018-10-01 12:35:08.470 Info MediaEncoder: Encoder available: ac3
2018-10-01 12:35:08.476 Info MediaEncoder: Running /opt/emby-server/bin/ffmpeg -protocols
2018-10-01 12:35:08.992 Info MediaEncoder: Encoder validation complete
2018-10-01 12:35:08.993 Info MediaEncoder: ffmpeg supported protocols: async,cache,concat,crypto,data,ffrtmphttp,file,ftp,gopher,hls,http,httpproxy,https,mmsh,mmst,pipe,rtmp,rtmps,rtmpt,rtmpts,rtp,srtp,subfile,tcp,tls,udp,udplite,unix,smb
2018-10-01 12:35:09.004 Info App: ServerId: 33186f2c5171485c9ac013c63bffff72
2018-10-01 12:35:09.347 Info App: Starting entry point MediaBrowser.WebDashboard.ServerEntryPoint
2018-10-01 12:35:09.354 Info App: Entry point completed: MediaBrowser.WebDashboard.ServerEntryPoint. Duration: 0.0064014 seconds
2018-10-01 12:35:09.354 Info App: Starting entry point Emby.Dlna.Main.DlnaEntryPoint
2018-10-01 12:35:10.173 Info TaskManager: StartupTrigger fired for task: Check for application updates
2018-10-01 12:35:10.174 Info TaskManager: StartupTrigger fired for task: Check for plugin updates
2018-10-01 12:35:10.195 Info TaskManager: Queueing task PluginUpdateTask
2018-10-01 12:35:10.195 Info TaskManager: Queueing task SystemUpdateTask
2018-10-01 12:35:10.260 Info TaskManager: Executing Check for plugin updates
2018-10-01 12:35:10.262 Info TaskManager: Executing Check for application updates
2018-10-01 12:35:10.403 Info HttpClient: GET https://api.github.com/repos/MediaBrowser/Emby.Releases/releases
2018-10-01 12:35:10.407 Info HttpClient: GET https://www.mb3admin.com/admin/service/EmbyPackages.json
2018-10-01 12:35:10.737 Info App: Entry point completed: Emby.Dlna.Main.DlnaEntryPoint. Duration: 1.3830003 seconds
2018-10-01 12:35:10.738 Info App: Starting entry point Emby.Server.Connect.ConnectEntryPoint
2018-10-01 12:35:10.751 Info App: Loading data from /var/lib/emby/data/connect.txt
2018-10-01 12:35:10.836 Info App: Loading data from /var/lib/emby/data/wan.dat
2018-10-01 12:35:10.904 Info App: Entry point completed: Emby.Server.Connect.ConnectEntryPoint. Duration: 0.1657879 seconds
2018-10-01 12:35:10.904 Info App: Core startup complete
2018-10-01 12:35:10.904 Info App: Post-init migrations complete
2018-10-01 12:35:10.904 Info App: Starting entry point Emby.Security.PluginSecurityManager
2018-10-01 12:35:10.905 Info App: Entry point completed: Emby.Security.PluginSecurityManager. Duration: 0.0006828 seconds
2018-10-01 12:35:10.905 Info App: Starting entry point Emby.Server.CinemaMode.IntrosEntryPoint
2018-10-01 12:35:10.907 Info App: Entry point completed: Emby.Server.CinemaMode.IntrosEntryPoint. Duration: 0.0015166 seconds
2018-10-01 12:35:10.907 Info App: Starting entry point MediaBrowser.Api.ApiEntryPoint
2018-10-01 12:35:10.908 Info App: Entry point completed: MediaBrowser.Api.ApiEntryPoint. Duration: 0.000609 seconds
2018-10-01 12:35:10.908 Info App: Starting entry point Emby.Server.Implementations.News.NewsEntryPoint
2018-10-01 12:35:10.910 Info App: Entry point completed: Emby.Server.Implementations.News.NewsEntryPoint. Duration: 0.0019503 seconds
2018-10-01 12:35:10.910 Info App: Starting entry point Emby.Server.Implementations.LiveTv.EmbyTV.EntryPoint
2018-10-01 12:35:10.925 Info App: Loading live tv data from /var/lib/emby/data/livetv/timers
2018-10-01 12:35:11.478 Info HttpClient: GET https://emby.media/community/index.php?/blog/rss/1-media-browser-developers-blog
2018-10-01 12:35:11.633 Info Dlna: Registering publisher for urn:schemas-upnp-org:device:MediaServer:1 on 192.168.10.248
2018-10-01 12:35:11.863 Info Dlna: Registering publisher for urn:schemas-upnp-org:device:MediaServer:1 on fda5:bb8d:a850:0:cc64:210:dea6:61ee
2018-10-01 12:35:11.868 Info Dlna: Registering publisher for urn:schemas-upnp-org:device:MediaServer:1 on fda5:bb8d:a850::1ca
2018-10-01 12:35:11.870 Info Dlna: Registering publisher for urn:schemas-upnp-org:device:MediaServer:1 on fda5:bb8d:a850:0:182a:696e:8a1c:1e34
2018-10-01 12:35:11.873 Info Dlna: Registering publisher for urn:schemas-upnp-org:device:MediaServer:1 on fda5:bb8d:a850:0:a079:c95d:95c2:b42d
2018-10-01 12:35:11.876 Info Dlna: Registering publisher for urn:schemas-upnp-org:device:MediaServer:1 on fe80::a812:e98e:66b3:d870%2
2018-10-01 12:35:11.888 Info HttpServer: HTTP GET http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/description.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:11.944 Info HttpServer: HTTP GET http://[fda5:bb8d:a850::1ca]:8096/dlna/33186f2c5171485c9ac013c63bffff72/description.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:11.961 Info HttpServer: HTTP GET http://[fda5:bb8d:a850:0:cc64:210:dea6:61ee]:8096/dlna/33186f2c5171485c9ac013c63bffff72/description.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:11.966 Info HttpServer: HTTP GET http://[fda5:bb8d:a850:0:cc64:210:dea6:61ee]:8096/dlna/33186f2c5171485c9ac013c63bffff72/description.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:11.969 Info HttpServer: HTTP GET http://[fda5:bb8d:a850:0:182a:696e:8a1c:1e34]:8096/dlna/33186f2c5171485c9ac013c63bffff72/description.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:11.984 Info HttpServer: HTTP GET http://[fda5:bb8d:a850:0:a079:c95d:95c2:b42d]:8096/dlna/33186f2c5171485c9ac013c63bffff72/description.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:12.188 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 224ms. http://[fda5:bb8d:a850:0:cc64:210:dea6:61ee]:8096/dlna/33186f2c5171485c9ac013c63bffff72/description.xml
2018-10-01 12:35:12.188 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 216ms. http://[fda5:bb8d:a850:0:182a:696e:8a1c:1e34]:8096/dlna/33186f2c5171485c9ac013c63bffff72/description.xml
2018-10-01 12:35:12.188 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 219ms. http://[fda5:bb8d:a850:0:cc64:210:dea6:61ee]:8096/dlna/33186f2c5171485c9ac013c63bffff72/description.xml
2018-10-01 12:35:12.189 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 241ms. http://[fda5:bb8d:a850::1ca]:8096/dlna/33186f2c5171485c9ac013c63bffff72/description.xml
2018-10-01 12:35:12.189 Info HttpServer: HTTP Response 200 to 192.168.10.140. Time: 310ms. http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/description.xml
2018-10-01 12:35:12.193 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 202ms. http://[fda5:bb8d:a850:0:a079:c95d:95c2:b42d]:8096/dlna/33186f2c5171485c9ac013c63bffff72/description.xml
2018-10-01 12:35:12.305 Info HttpServer: HTTP GET http://[fda5:bb8d:a850:0:a079:c95d:95c2:b42d]:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/contentdirectory.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:12.315 Info HttpServer: HTTP GET http://[fda5:bb8d:a850::1ca]:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/contentdirectory.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:12.334 Info HttpServer: HTTP GET http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/contentdirectory.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:12.338 Info HttpServer: HTTP GET http://[fda5:bb8d:a850:0:182a:696e:8a1c:1e34]:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/contentdirectory.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:12.412 Info App: Entry point completed: Emby.Server.Implementations.LiveTv.EmbyTV.EntryPoint. Duration: 1.5021064 seconds
2018-10-01 12:35:12.412 Info App: Starting entry point Emby.Server.Implementations.Library.DeviceAccessEntryPoint
2018-10-01 12:35:12.417 Info App: Entry point completed: Emby.Server.Implementations.Library.DeviceAccessEntryPoint. Duration: 0.0040894 seconds
2018-10-01 12:35:12.417 Info App: Starting entry point Emby.Server.Implementations.IO.LibraryMonitorStartup
2018-10-01 12:35:12.488 Info HttpServer: HTTP Response 200 to 192.168.10.140. Time: 153ms. http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/contentdirectory.xml
2018-10-01 12:35:12.488 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 150ms. http://[fda5:bb8d:a850:0:182a:696e:8a1c:1e34]:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/contentdirectory.xml
2018-10-01 12:35:12.490 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 173ms. http://[fda5:bb8d:a850::1ca]:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/contentdirectory.xml
2018-10-01 12:35:12.492 Info HttpServer: HTTP GET http://[fda5:bb8d:a850:0:cc64:210:dea6:61ee]:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/contentdirectory.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:12.493 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 191ms. http://[fda5:bb8d:a850:0:a079:c95d:95c2:b42d]:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/contentdirectory.xml
2018-10-01 12:35:12.496 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 4ms. http://[fda5:bb8d:a850:0:cc64:210:dea6:61ee]:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/contentdirectory.xml
2018-10-01 12:35:12.498 Info HttpServer: HTTP GET http://[fda5:bb8d:a850:0:cc64:210:dea6:61ee]:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/contentdirectory.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:12.500 Info HttpServer: HTTP GET http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/description.xml. UserAgent: UPnP/1.0 DLNADOC/1.50 MrMC
2018-10-01 12:35:12.513 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 15ms. http://[fda5:bb8d:a850:0:cc64:210:dea6:61ee]:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/contentdirectory.xml
2018-10-01 12:35:12.513 Info HttpServer: HTTP Response 200 to 192.168.10.140. Time: 13ms. http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/description.xml
2018-10-01 12:35:12.638 Info HttpServer: HTTP GET http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/contentdirectory.xml. UserAgent: UPnP/1.0 DLNADOC/1.50 MrMC
2018-10-01 12:35:12.641 Info HttpServer: HTTP Response 200 to 192.168.10.140. Time: 3ms. http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/contentdirectory.xml
2018-10-01 12:35:12.656 Info HttpServer: HTTP GET http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/connectionmanager.xml. UserAgent: UPnP/1.0 DLNADOC/1.50 MrMC
2018-10-01 12:35:12.849 Info HttpServer: HTTP Response 200 to 192.168.10.140. Time: 193ms. http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/connectionmanager.xml
2018-10-01 12:35:12.868 Info HttpServer: HTTP SUBSCRIBE http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/events. UserAgent: UPnP/1.0 DLNADOC/1.50 MrMC
2018-10-01 12:35:12.875 Info HttpServer: HTTP SUBSCRIBE http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/events. UserAgent: UPnP/1.0 DLNADOC/1.50 MrMC
2018-10-01 12:35:12.902 Info HttpServer: HTTP Response 200 to 192.168.10.140. Time: 35ms. http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/contentdirectory/events
2018-10-01 12:35:12.904 Info HttpServer: HTTP Response 200 to 192.168.10.140. Time: 29ms. http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/events
2018-10-01 12:35:12.939 Info HttpServer: HTTP GET http://[fda5:bb8d:a850:0:cc64:210:dea6:61ee]:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/connectionmanager.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:12.939 Info HttpServer: HTTP GET http://[fda5:bb8d:a850::1ca]:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/connectionmanager.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:12.939 Info HttpServer: HTTP GET http://[fda5:bb8d:a850:0:cc64:210:dea6:61ee]:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/connectionmanager.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:12.942 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 3ms. http://[fda5:bb8d:a850::1ca]:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/connectionmanager.xml
2018-10-01 12:35:12.942 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 3ms. http://[fda5:bb8d:a850:0:cc64:210:dea6:61ee]:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/connectionmanager.xml
2018-10-01 12:35:12.942 Info HttpServer: HTTP GET http://[fda5:bb8d:a850:0:a079:c95d:95c2:b42d]:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/connectionmanager.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:12.946 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 5ms. http://[fda5:bb8d:a850:0:cc64:210:dea6:61ee]:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/connectionmanager.xml
2018-10-01 12:35:12.947 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 5ms. http://[fda5:bb8d:a850:0:a079:c95d:95c2:b42d]:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/connectionmanager.xml
2018-10-01 12:35:12.947 Info HttpServer: HTTP GET http://[fda5:bb8d:a850:0:182a:696e:8a1c:1e34]:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/connectionmanager.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:12.950 Info HttpServer: HTTP Response 200 to fda5:bb8d:a850:0:915a:5c14:3e81:2bf5. Time: 3ms. http://[fda5:bb8d:a850:0:182a:696e:8a1c:1e34]:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/connectionmanager.xml
2018-10-01 12:35:12.953 Info HttpServer: HTTP GET http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/connectionmanager.xml. UserAgent: Android/5.1.1 UPnP/1.0 Cling/2.0
2018-10-01 12:35:12.956 Info HttpServer: HTTP Response 200 to 192.168.10.140. Time: 3ms. http://192.168.10.248:8096/dlna/33186f2c5171485c9ac013c63bffff72/connectionmanager/connectionmanager.xml
2018-10-01 12:35:13.058 Info TaskManager: Check for application updates Completed after 0 minute(s) and 2 seconds
2018-10-01 12:35:13.097 Info TaskManager: ExecuteQueuedTasks
2018-10-01 12:35:13.411 Info TaskManager: Check for plugin updates Completed after 0 minute(s) and 3 seconds
2018-10-01 12:35:13.413 Info TaskManager: ExecuteQueuedTasks
2018-10-01 12:35:18.720 Info Dlna: No matching device profile found. The default will need to be used.
DeviceDescription:
FriendlyName:Living Room
Manufacturer:SoftMedia Inc.
ManufacturerUrl:http://www.remotetogo.com
ModelDescription:AirReceiver - Media Renderer
ModelName:AirReceiver
ModelNumber:01
ModelUrl:http://www.remotetogo.com
SerialNumber:
2018-10-01 12:35:18.828 Info Dlna: DLNA Session created for Living Room - AirReceiver
2018-10-01 12:35:22.818 Info App: Entry point completed: Emby.Server.Implementations.IO.LibraryMonitorStartup. Duration: 10.4011137 seconds
2018-10-01 12:35:22.819 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.AutomaticRestartEntryPoint
2018-10-01 12:35:22.864 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.AutomaticRestartEntryPoint. Duration: 0.0382286 seconds
2018-10-01 12:35:22.869 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.ExternalPortForwarding
2018-10-01 12:35:22.977 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.ExternalPortForwarding. Duration: 0.1062224 seconds
2018-10-01 12:35:22.977 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.KeepServerAwake
2018-10-01 12:35:22.980 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.KeepServerAwake. Duration: 0.0023418 seconds
2018-10-01 12:35:22.980 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.LibraryChangedNotifier
2018-10-01 12:35:22.994 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.LibraryChangedNotifier. Duration: 0.0144641 seconds
2018-10-01 12:35:22.995 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.RecordingNotifier
2018-10-01 12:35:23.006 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.RecordingNotifier. Duration: 0.010825 seconds
2018-10-01 12:35:23.006 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.ServerEventNotifier
2018-10-01 12:35:23.027 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.ServerEventNotifier. Duration: 0.0206574 seconds
2018-10-01 12:35:23.027 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.StartupWizard
2018-10-01 12:35:23.031 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.StartupWizard. Duration: 0.0041611 seconds
2018-10-01 12:35:23.031 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.SystemEvents
2018-10-01 12:35:23.034 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.SystemEvents. Duration: 0.0029076 seconds
2018-10-01 12:35:23.035 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.UdpServerEntryPoint
2018-10-01 12:35:23.045 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.UdpServerEntryPoint. Duration: 0.0101215 seconds
2018-10-01 12:35:23.045 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.UsageEntryPoint
2018-10-01 12:35:23.059 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.UsageEntryPoint. Duration: 0.0138537 seconds
2018-10-01 12:35:23.059 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.UserDataChangeNotifier
2018-10-01 12:35:23.063 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.UserDataChangeNotifier. Duration: 0.0032424 seconds
2018-10-01 12:35:23.063 Info App: Starting entry point Emby.Server.Implementations.Devices.DeviceManagerEntryPoint
2018-10-01 12:35:23.071 Info App: Entry point completed: Emby.Server.Implementations.Devices.DeviceManagerEntryPoint. Duration: 0.0081096 seconds
2018-10-01 12:35:23.071 Info App: Starting entry point Emby.Server.Implementations.Collections.CollectionManagerEntryPoint
2018-10-01 12:35:23.081 Info App: Entry point completed: Emby.Server.Implementations.Collections.CollectionManagerEntryPoint. Duration: 0.0090558 seconds
2018-10-01 12:35:23.081 Info App: Starting entry point Emby.Server.Implementations.Activity.ActivityLogEntryPoint
2018-10-01 12:35:23.104 Info App: Entry point completed: Emby.Server.Implementations.Activity.ActivityLogEntryPoint. Duration: 0.0229715 seconds
2018-10-01 12:35:23.104 Info App: Starting entry point Emby.Server.MediaEncoding.Api.ApiEntryPoint
2018-10-01 12:35:23.113 Info App: Entry point completed: Emby.Server.MediaEncoding.Api.ApiEntryPoint. Duration: 0.0084853 seconds
2018-10-01 12:35:23.113 Info App: Starting entry point Emby.Notifications.Notifications
2018-10-01 12:35:23.117 Info App: Entry point completed: Emby.Notifications.Notifications. Duration: 0.003964 seconds
2018-10-01 12:35:23.117 Info App: Starting entry point MediaBrowser.XbmcMetadata.EntryPoint
2018-10-01 12:35:23.119 Info App: Entry point completed: MediaBrowser.XbmcMetadata.EntryPoint. Duration: 0.0013673 seconds
2018-10-01 12:35:23.119 Info App: Starting entry point Emby.Security.PluginSecurityManager
2018-10-01 12:35:23.119 Info App: Entry point completed: Emby.Security.PluginSecurityManager. Duration: 0.0002639 seconds
2018-10-01 12:35:23.119 Info App: Starting entry point Emby.Server.Sync.SyncManagerEntryPoint
2018-10-01 12:35:23.148 Info App: Sqlite version: 3.24.0
2018-10-01 12:35:23.148 Info App: Sqlite compiler options: COMPILER=gcc-6.4.1 20170707,ENABLE_COLUMN_METADATA,ENABLE_DBSTAT_VTAB,ENABLE_FTS3,ENABLE_FTS3_PARENTHESIS,ENABLE_FTS3_TOKENIZER,ENABLE_FTS5,ENABLE_JSON1,ENABLE_PREUPDATE_HOOK,ENABLE_RTREE,ENABLE_SESSION,ENABLE_UNLOCK_NOTIFY,ENABLE_UPDATE_DELETE_LIMIT,LIKE_DOESNT_MATCH_BLOBS,MAX_SCHEMA_RETRY=25,MAX_VARIABLE_NUMBER=250000,OMIT_LOOKASIDE,SECURE_DELETE,THREADSAFE=1
2018-10-01 12:35:23.153 Info App: Default journal_mode for /var/lib/emby/data/sync14.db is wal
2018-10-01 12:35:23.159 Info App: PRAGMA synchronous=1
2018-10-01 12:35:23.169 Info App: Entry point completed: Emby.Server.Sync.SyncManagerEntryPoint. Duration: 0.0499738 seconds
2018-10-01 12:35:23.170 Info App: Starting entry point Emby.Server.Sync.SyncNotificationEntryPoint
2018-10-01 12:35:23.181 Info App: Entry point completed: Emby.Server.Sync.SyncNotificationEntryPoint. Duration: 0.0111082 seconds
2018-10-01 12:35:23.181 Info App: All entry points have started
2018-10-01 12:35:24.553 Info LibraryMonitor: Watching directory /media/Music/Movies
2018-10-01 12:35:24.698 Info LibraryMonitor: Watching directory /media/TV/Movies
2018-10-01 12:35:25.551 Info LibraryMonitor: Watching directory /media/Movies/Movies
2018-10-01 12:35:30.583 Info LibraryMonitor: Watching directory /media/TV/TV Shows
2018-10-01 12:35:36.368 Info LibraryMonitor: Watching directory /media/Music/Music
2018-10-01 12:35:42.878 Info App: App Activity: app: DLNA, version: 3.5.3.0, deviceId: uuid:e2efb2f5-5e83-4818-9282-4369ef09465f, deviceName: Network Device
2018-10-01 12:35:42.900 Info Dlna: No matching device profile found. The default will need to be used.
DeviceDescription:
FriendlyName:bed
Manufacturer:SoftMedia Inc.
ManufacturerUrl:http://www.remotetogo.com
ModelDescription:AirReceiver - Media Renderer
ModelName:AirReceiver
ModelNumber:01
ModelUrl:http://www.remotetogo.com
SerialNumber:
2018-10-01 12:35:42.903 Info Dlna: DLNA Session created for bed - AirReceiver
2018-10-01 12:36:07.082 Info TaskManager: IntervalTrigger fired for task: Download missing subtitles
2018-10-01 12:36:07.086 Info TaskManager: Queueing task SubtitleScheduledTask
2018-10-01 12:36:07.102 Info TaskManager: Executing Download missing subtitles
2018-10-01 12:36:07.109 Info TaskManager: IntervalTrigger fired for task: Refresh people
2018-10-01 12:36:07.109 Info TaskManager: Queueing task PeopleValidationTask
2018-10-01 12:36:07.110 Info TaskManager: Executing Refresh people
2018-10-01 12:36:07.135 Info TaskManager: IntervalTrigger fired for task: Check for plugin updates
2018-10-01 12:36:07.135 Info TaskManager: Queueing task PluginUpdateTask
2018-10-01 12:36:07.136 Info TaskManager: Executing Check for plugin updates
2018-10-01 12:36:07.142 Info TaskManager: IntervalTrigger fired for task: Scan media library
2018-10-01 12:36:07.142 Info TaskManager: Queueing task RefreshMediaLibraryTask
2018-10-01 12:36:07.143 Info TaskManager: Executing Scan media library
2018-10-01 12:36:07.149 Info TaskManager: IntervalTrigger fired for task: Check for application updates
2018-10-01 12:36:07.149 Info TaskManager: Queueing task SystemUpdateTask
2018-10-01 12:36:07.149 Info TaskManager: Executing Check for application updates
2018-10-01 12:36:07.178 Info TaskManager: IntervalTrigger fired for task: Log file cleanup
2018-10-01 12:36:07.178 Info TaskManager: Queueing task DeleteLogFileTask
2018-10-01 12:36:07.178 Info TaskManager: Executing Log file cleanup
2018-10-01 12:36:07.215 Info TaskManager: IntervalTrigger fired for task: Refresh Users
2018-10-01 12:36:07.216 Info TaskManager: Queueing task RefreshUsersMetadata
2018-10-01 12:36:07.216 Info TaskManager: IntervalTrigger fired for task: Convert media
2018-10-01 12:36:07.217 Info TaskManager: Queueing task SyncConvertScheduledTask
2018-10-01 12:36:07.217 Info TaskManager: Executing Convert media
2018-10-01 12:36:07.267 Info TaskManager: Download missing subtitles Completed after 0 minute(s) and 0 seconds
2018-10-01 12:36:07.284 Info TaskManager: Log file cleanup Completed after 0 minute(s) and 0 seconds
2018-10-01 12:36:07.293 Info LibraryMonitor: Stopping directory watching for path /media/Movies/Movies
2018-10-01 12:36:07.306 Info LibraryMonitor: Stopping directory watching for path /media/TV/Movies
2018-10-01 12:36:07.309 Info LibraryMonitor: Stopping directory watching for path /media/TV/TV Shows
2018-10-01 12:36:07.315 Info LibraryMonitor: Stopping directory watching for path /media/Music/Music
2018-10-01 12:36:07.325 Info LibraryMonitor: Stopping directory watching for path /media/Music/Movies
2018-10-01 12:36:07.386 Info App: Validating media library
2018-10-01 12:36:07.477 Info TaskManager: Check for application updates Completed after 0 minute(s) and 0 seconds
2018-10-01 12:36:07.491 Info TaskManager: ExecuteQueuedTasks
2018-10-01 12:36:07.491 Info TaskManager: ExecuteQueuedTasks
2018-10-01 12:36:07.491 Info TaskManager: ExecuteQueuedTasks
2018-10-01 12:36:07.770 Info TaskManager: IntervalTrigger fired for task: Cache file cleanup
2018-10-01 12:36:07.770 Info TaskManager: Queueing task DeleteCacheFileTask
2018-10-01 12:36:07.895 Info TaskManager: IntervalTrigger fired for task: Refresh Guide
2018-10-01 12:36:07.895 Info TaskManager: Queueing task RefreshChannelsScheduledTask
2018-10-01 12:36:07.895 Info TaskManager: Executing Cache file cleanup
2018-10-01 12:36:07.895 Info TaskManager: Executing Refresh Guide
2018-10-01 12:36:08.160 Info TaskManager: Queueing task ServerSyncScheduledTask
2018-10-01 12:36:08.162 Info TaskManager: Convert media Completed after 0 minute(s) and 0 seconds
2018-10-01 12:36:08.170 Info TaskManager: ExecuteQueuedTasks
2018-10-01 12:36:08.252 Info TaskManager: Executing Cloud & Folder Sync
2018-10-01 12:36:08.333 Info TaskManager: IntervalTrigger fired for task: Cloud & Folder Sync
2018-10-01 12:36:08.333 Info TaskManager: Queueing task ServerSyncScheduledTask
2018-10-01 12:36:08.336 Info TaskManager: IntervalTrigger fired for task: Refresh Channels
2018-10-01 12:36:08.336 Info TaskManager: Queueing task RefreshChannelsScheduledTask
2018-10-01 12:36:08.336 Info TaskManager: Executing Refresh Channels
2018-10-01 12:36:08.437 Info TaskManager: Cloud & Folder Sync Completed after 0 minute(s) and 0 seconds
2018-10-01 12:36:08.444 Info TaskManager: ExecuteQueuedTasks
2018-10-01 12:36:08.620 Info TaskManager: Executing Cloud & Folder Sync
2018-10-01 12:36:08.625 Info TaskManager: Cloud & Folder Sync Completed after 0 minute(s) and 0 seconds
2018-10-01 12:36:08.627 Info TaskManager: ExecuteQueuedTasks
2018-10-01 12:36:08.696 Info TaskManager: Executing Refresh Users
2018-10-01 12:36:08.847 Info TaskManager: Refresh Channels Completed after 0 minute(s) and 0 seconds
2018-10-01 12:36:08.855 Info TaskManager: ExecuteQueuedTasks
2018-10-01 12:36:08.973 Info TaskManager: Check for plugin updates Completed after 0 minute(s) and 1 seconds
2018-10-01 12:36:08.991 Info TaskManager: ExecuteQueuedTasks
2018-10-01 12:36:09.783 Info TaskManager: Refresh Users Completed after 0 minute(s) and 1 seconds
2018-10-01 12:36:09.789 Info TaskManager: ExecuteQueuedTasks
2018-10-01 12:36:10.089 Info TaskManager: Cache file cleanup Completed after 0 minute(s) and 2 seconds
2018-10-01 12:36:10.093 Info TaskManager: ExecuteQueuedTasks
2018-10-01 12:36:11.526 Info App: Refreshing guide with 7 days of guide data
2018-10-01 12:36:11.552 Info App: Loading live tv data from /var/lib/emby/data/livetv/seriestimers
2018-10-01 12:36:11.595 Info TaskManager: Refresh Guide Completed after 0 minute(s) and 3 seconds
2018-10-01 12:36:11.597 Info TaskManager: ExecuteQueuedTasks
2018-10-01 12:36:13.561 Info ImageProcessor: Creating image collage and saving to /var/lib/emby/cache/temp/dbe877eab4034a918d7a34488425ed41.png
2018-10-01 12:36:16.194 Info ImageProcessor: Completed creation of image collage and saved to /var/lib/emby/cache/temp/dbe877eab4034a918d7a34488425ed41.png
2018-10-01 12:36:16.521 Info ImageProcessor: Creating image collage and saving to /var/lib/emby/cache/temp/8c41b18c89194428b3cad317fb8faa81.png
2018-10-01 12:36:17.859 Info ImageProcessor: Completed creation of image collage and saved to /var/lib/emby/cache/temp/8c41b18c89194428b3cad317fb8faa81.png
2018-10-01 12:38:54.264 Error App: Error in ffprobe
*** Error Report ***
Version: 3.5.3.0
Command line: /opt/emby-server/system/EmbyServer.dll -programdata /var/lib/emby -ffmpeg /opt/emby-server/bin/ffmpeg -ffprobe /opt/emby-server/bin/ffprobe -restartexitcode 3 -updatepackage emby-server-deb_{version}_armhf.deb
Operating system: Unix 4.4.126.0
64-Bit OS: False
64-Bit Process: False
User Interactive: True
Processor count: 6
Program data path: /var/lib/emby
Application directory: /opt/emby-server/system
System.Exception: ffprobe failed for file:"/media/TV/Movies/Suspiria [1977].mp4" - streams and format are both null.
at Emby.Server.MediaEncoding.Encoder.MediaEncoder.GetMediaInfoInternal(String inputPath, String primaryPath, MediaProtocol protocol, Boolean extractChapters, String probeSizeArgument, Boolean isAudio, VideoType videoType, Boolean forceEnableLogging, CancellationToken cancellationToken)
at Emby.Server.MediaEncoding.Encoder.MediaEncoder.GetMediaInfoInternal(String inputPath, String primaryPath, MediaProtocol protocol, Boolean extractChapters, String probeSizeArgument, Boolean isAudio, VideoType videoType, Boolean forceEnableLogging, CancellationToken cancellationToken)
at MediaBrowser.Providers.MediaInfo.FFProbeVideoInfo.ProbeVideo[T](T item, MetadataRefreshOptions options, CancellationToken cancellationToken)
at MediaBrowser.Providers.Manager.MetadataService`2.RunCustomProvider(ICustomMetadataProvider`1 provider, TItemType item, String logName, MetadataRefreshOptions options, RefreshResult refreshResult, CancellationToken cancellationToken)
System.Exception
at Emby.Server.MediaEncoding.Encoder.MediaEncoder.GetMediaInfoInternal(String inputPath, String primaryPath, MediaProtocol protocol, Boolean extractChapters, String probeSizeArgument, Boolean isAudio, VideoType videoType, Boolean forceEnableLogging, CancellationToken cancellationToken)
at Emby.Server.MediaEncoding.Encoder.MediaEncoder.GetMediaInfoInternal(String inputPath, String primaryPath, MediaProtocol protocol, Boolean extractChapters, String probeSizeArgument, Boolean isAudio, VideoType videoType, Boolean forceEnableLogging, CancellationToken cancellationToken)
at MediaBrowser.Providers.MediaInfo.FFProbeVideoInfo.ProbeVideo[T](T item, MetadataRefreshOptions options, CancellationToken cancellationToken)
at MediaBrowser.Providers.Manager.MetadataService`2.RunCustomProvider(ICustomMetadataProvider`1 provider, TItemType item, String logName, MetadataRefreshOptions options, RefreshResult refreshResult, CancellationToken cancellationToken)

2018-10-01 12:39:09.449 Info HttpClient: GET https://api.themoviedb.org/3/configuration?api_key=f6bd687ffa63cd282b6ff2c6877f2669
2018-10-01 12:39:09.868 Info App: MovieDbProvider: Finding id for item: GameChangers
2018-10-01 12:39:09.893 Info HttpClient: GET https://api.themoviedb.org/3/search/movie?api_key=f6bd687ffa63cd282b6ff2c6877f2669&query=GameChangers&language=en
2018-10-01 12:39:10.204 Info HttpClient: GET https://api.themoviedb.org/3/movie/525081?api_key=f6bd687ffa63cd282b6ff2c6877f2669&append_to_response=casts,releases,images,keywords,trailers&language=en&include_image_language=en,null
2018-10-01 12:39:11.269 Info HttpClient: GET https://private.omdbapi.com?apikey=fe53f97e&i=tt7945360&plot=short&tomatoes=true&r=json
2018-10-01 12:39:11.948 Info HttpClient: GET https://api.themoviedb.org/3/movie/525081?api_key=f6bd687ffa63cd282b6ff2c6877f2669&append_to_response=casts,releases,images,keywords,trailers
2018-10-01 12:39:12.654 Info HttpClient: GET https://webservice.fanart.tv/v3/movies/525081?api_key=5c6b04c68e904cfed1e6cbc9a9e683d4
2018-10-01 12:39:14.723 Info App: MovieDbProvider: Finding id for item: LA.92
2018-10-01 12:39:14.724 Info HttpClient: GET https://api.themoviedb.org/3/search/movie?api_key=f6bd687ffa63cd282b6ff2c6877f2669&query=LA.92&language=en
2018-10-01 12:39:15.024 Info HttpClient: GET https://api.themoviedb.org/3/movie/450875?api_key=f6bd687ffa63cd282b6ff2c6877f2669&append_to_response=casts,releases,images,keywords,trailers&language=en&include_image_language=en,null
2018-10-01 12:39:15.277 Info HttpClient: GET https://private.omdbapi.com?apikey=fe53f97e&i=tt6794424&plot=short&tomatoes=true&r=json
2018-10-01 12:39:15.550 Info HttpClient: GET https://api.themoviedb.org/3/movie/450875?api_key=f6bd687ffa63cd282b6ff2c6877f2669&append_to_response=casts,releases,images,keywords,trailers
2018-10-01 12:39:15.671 Info HttpClient: GET https://webservice.fanart.tv/v3/movies/450875?api_key=5c6b04c68e904cfed1e6cbc9a9e683d4
2018-10-01 12:39:17.089 Info App: MovieDbProvider: Finding id for item: Screwed
2018-10-01 12:39:17.090 Info HttpClient: GET https://api.themoviedb.org/3/search/movie?api_key=f6bd687ffa63cd282b6ff2c6877f2669&query=Screwed&language=en
2018-10-01 12:39:17.390 Info HttpClient: GET https://api.themoviedb.org/3/movie/19419?api_key=f6bd687ffa63cd282b6ff2c6877f2669&append_to_response=casts,releases,images,keywords,trailers&language=en&include_image_language=en,null
2018-10-01 12:39:17.687 Info HttpClient: GET https://private.omdbapi.com?apikey=fe53f97e&i=tt0156323&plot=short&tomatoes=true&r=json
2018-10-01 12:39:18.025 Info HttpClient: GET https://api.themoviedb.org/3/movie/19419?api_key=f6bd687ffa63cd282b6ff2c6877f2669&append_to_response=casts,releases,images,keywords,trailers
2018-10-01 12:39:18.342 Info HttpClient: GET https://webservice.fanart.tv/v3/movies/19419?api_key=5c6b04c68e904cfed1e6cbc9a9e683d4
2018-10-01 12:39:19.598 Info App: MovieDbProvider: Finding id for item: The.Piano
2018-10-01 12:39:19.599 Info HttpClient: GET https://api.themoviedb.org/3/search/movie?api_key=f6bd687ffa63cd282b6ff2c6877f2669&query=The.Piano&language=en
2018-10-01 12:39:19.900 Info HttpClient: GET https://api.themoviedb.org/3/search/movie?api_key=f6bd687ffa63cd282b6ff2c6877f2669&query=The+Piano&language=en
2018-10-01 12:39:20.200 Info HttpClient: GET https://api.themoviedb.org/3/movie/713?api_key=f6bd687ffa63cd282b6ff2c6877f2669&append_to_response=casts,releases,images,keywords,trailers&language=en&include_image_language=en,null
2018-10-01 12:39:20.378 Info HttpClient: GET https://private.omdbapi.com?apikey=fe53f97e&i=tt0107822&plot=short&tomatoes=true&r=json
2018-10-01 12:39:20.646 Info HttpClient: GET https://api.themoviedb.org/3/movie/713?api_key=f6bd687ffa63cd282b6ff2c6877f2669&append_to_response=casts,releases,images,keywords,trailers
2018-10-01 12:39:20.775 Info HttpClient: GET https://webservice.fanart.tv/v3/movies/713?api_key=5c6b04c68e904cfed1e6cbc9a9e683d4
2018-10-01 12:40:01.511 Error App: Error in ffprobe
*** Error Report ***
Version: 3.5.3.0
Command line: /opt/emby-server/system/EmbyServer.dll -programdata /var/lib/emby -ffmpeg /opt/emby-server/bin/ffmpeg -ffprobe /opt/emby-server/bin/ffprobe -restartexitcode 3 -updatepackage emby-server-deb_{version}_armhf.deb
Operating system: Unix 4.4.126.0
64-Bit OS: False
64-Bit Process: False
User Interactive: True
Processor count: 6
Program data path: /var/lib/emby
Application directory: /opt/emby-server/system
System.Exception: ffprobe failed for file:"/media/TV/TV Shows/SCTV/SCTV - 094 - SCTV Staff Christmas Party.mp4" - streams and format are both null.
at Emby.Server.MediaEncoding.Encoder.MediaEncoder.GetMediaInfoInternal(String inputPath, String primaryPath, MediaProtocol protocol, Boolean extractChapters, String probeSizeArgument, Boolean isAudio, VideoType videoType, Boolean forceEnableLogging, CancellationToken cancellationToken)
at Emby.Server.MediaEncoding.Encoder.MediaEncoder.GetMediaInfoInternal(String inputPath, String primaryPath, MediaProtocol protocol, Boolean extractChapters, String probeSizeArgument, Boolean isAudio, VideoType videoType, Boolean forceEnableLogging, CancellationToken cancellationToken)
at MediaBrowser.Providers.MediaInfo.FFProbeVideoInfo.ProbeVideo[T](T item, MetadataRefreshOptions options, CancellationToken cancellationToken)
at MediaBrowser.Providers.Manager.MetadataService`2.RunCustomProvider(ICustomMetadataProvider`1 provider, TItemType item, String logName, MetadataRefreshOptions options, RefreshResult refreshResult, CancellationToken cancellationToken)
System.Exception
at Emby.Server.MediaEncoding.Encoder.MediaEncoder.GetMediaInfoInternal(String inputPath, String primaryPath, MediaProtocol protocol, Boolean extractChapters, String probeSizeArgument, Boolean isAudio, VideoType videoType, Boolean forceEnableLogging, CancellationToken cancellationToken)
at Emby.Server.MediaEncoding.Encoder.MediaEncoder.GetMediaInfoInternal(String inputPath, String primaryPath, MediaProtocol protocol, Boolean extractChapters, String probeSizeArgument, Boolean isAudio, VideoType videoType, Boolean forceEnableLogging, CancellationToken cancellationToken)
at MediaBrowser.Providers.MediaInfo.FFProbeVideoInfo.ProbeVideo[T](T item, MetadataRefreshOptions options, CancellationToken cancellationToken)
at MediaBrowser.Providers.Manager.MetadataService`2.RunCustomProvider(ICustomMetadataProvider`1 provider, TItemType item, String logName, MetadataRefreshOptions options, RefreshResult refreshResult, CancellationToken cancellationToken)

2018-10-01 12:40:01.597 Info HttpClient: GET https://www.thetvdb.com/api/B89CE93890E9419B/series/186241/all/en.zip
2018-10-01 12:40:02.704 Info App: Skipping image extraction due to missing DefaultVideoStreamIndex for /media/TV/TV Shows/SCTV/SCTV - 094 - SCTV Staff Christmas Party.mp4.
2018-10-01 12:40:02.801 Info App: Creating Season Specials entry for SCTV
2018-10-01 12:40:27.851 Info App: Removing virtual season Saturday Night Live 44
2018-10-01 12:40:27.874 Info App: Deleting item, Type: Season, Name: Season 44, Path: , Id: 02eda5f3-bc2e-7668-1105-25f07196d927
2018-10-01 12:40:28.901 Info HttpClient: GET https://www.thetvdb.com/api/B89CE93890E9419B/series/76177/all/en.zip
2018-10-01 12:40:32.982 Error ProviderManager: UnauthorizedAccessException - Access to path /media/TV/TV Shows/Saturday Night Live (1975)/Season 44/saturday.night.live.s44e01.adam.driver.720p.web.x264-tbs-thumb.jpg is denied. Will retry saving to /var/lib/emby/metadata/library/5e/5ed5c8e1fc23141db3447d6390004e44/poster.jpg
2018-10-01 12:40:33.227 Error ProviderManager: Error in metadata saver
*** Error Report ***
Version: 3.5.3.0
Command line: /opt/emby-server/system/EmbyServer.dll -programdata /var/lib/emby -ffmpeg /opt/emby-server/bin/ffmpeg -ffprobe /opt/emby-server/bin/ffprobe -restartexitcode 3 -updatepackage emby-server-deb_{version}_armhf.deb
Operating system: Unix 4.4.126.0
64-Bit OS: False
64-Bit Process: False
User Interactive: True
Processor count: 6
Program data path: /var/lib/emby
Application directory: /opt/emby-server/system
System.UnauthorizedAccessException: Access to the path '/media/TV/TV Shows/Saturday Night Live (1975)/Season 44/saturday.night.live.s44e01.adam.driver.720p.web.x264-tbs.nfo' is denied. ---> System.IO.IOException: Permission denied
--- End of inner exception stack trace ---
at Interop.ThrowExceptionForIoErrno(ErrorInfo errorInfo, String path, Boolean isDirectory, Func`2 errorRewriter)
at Microsoft.Win32.SafeHandles.SafeFileHandle.Open(String path, OpenFlags flags, Int32 mode)
at System.IO.FileStream.OpenHandle(FileMode mode, FileShare share, FileOptions options)
at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize, FileOptions options)
at Emby.Server.Implementations.IO.ManagedFileSystem.GetFileStream(String path, FileOpenMode mode, FileAccessMode access, FileShareMode share, FileOpenOptions fileOpenOptions)
at Emby.Server.Implementations.IO.ManagedFileSystem.GetFileStream(String path, FileOpenMode mode, FileAccessMode access, FileShareMode share, Boolean isAsync)
at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.SaveToFile(Stream stream, String path)
at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.Save(BaseItem item, CancellationToken cancellationToken)
at MediaBrowser.Providers.Manager.ProviderManager.SaveMetadata(BaseItem item, ItemUpdateType updateType, IEnumerable`1 savers)
System.UnauthorizedAccessException
at Interop.ThrowExceptionForIoErrno(ErrorInfo errorInfo, String path, Boolean isDirectory, Func`2 errorRewriter)
at Microsoft.Win32.SafeHandles.SafeFileHandle.Open(String path, OpenFlags flags, Int32 mode)
at System.IO.FileStream.OpenHandle(FileMode mode, FileShare share, FileOptions options)
at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize, FileOptions options)
at Emby.Server.Implementations.IO.ManagedFileSystem.GetFileStream(String path, FileOpenMode mode, FileAccessMode access, FileShareMode share, FileOpenOptions fileOpenOptions)
at Emby.Server.Implementations.IO.ManagedFileSystem.GetFileStream(String path, FileOpenMode mode, FileAccessMode access, FileShareMode share, Boolean isAsync)
at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.SaveToFile(Stream stream, String path)
at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.Save(BaseItem item, CancellationToken cancellationToken)
at MediaBrowser.Providers.Manager.ProviderManager.SaveMetadata(BaseItem item, ItemUpdateType updateType, IEnumerable`1 savers)
InnerException: System.IO.IOException
System.IO.IOException: Permission denied

2018-10-01 12:40:34.051 Info App: Creating virtual unaired episode Saturday Night Live 44x3
2018-10-01 12:40:39.483 Info App: Removing virtual season Family Guy 17
2018-10-01 12:40:39.483 Info App: Deleting item, Type: Season, Name: Season 17, Path: , Id: 1268f84c-2b3e-a4e8-279f-6cfe83310838
2018-10-01 12:40:39.968 Info App: Creating virtual unaired episode Family Guy 17x1
2018-10-01 12:40:39.995 Info HttpClient: GET https://www.thetvdb.com/api/B89CE93890E9419B/series/75978/all/en.zip
2018-10-01 12:40:40.700 Info App: Creating virtual unaired episode Family Guy 17x2
2018-10-01 12:40:40.755 Info App: Creating virtual unaired episode Family Guy 17x3
2018-10-01 12:40:41.120 Error ProviderManager: Error in metadata saver
*** Error Report ***
Version: 3.5.3.0
Command line: /opt/emby-server/system/EmbyServer.dll -programdata /var/lib/emby -ffmpeg /opt/emby-server/bin/ffmpeg -ffprobe /opt/emby-server/bin/ffprobe -restartexitcode 3 -updatepackage emby-server-deb_{version}_armhf.deb
Operating system: Unix 4.4.126.0
64-Bit OS: False
64-Bit Process: False
User Interactive: True
Processor count: 6
Program data path: /var/lib/emby
Application directory: /opt/emby-server/system
System.UnauthorizedAccessException: Access to the path '/media/TV/TV Shows/Family Guy (1999)/Season 17/season.nfo' is denied. ---> System.IO.IOException: Permission denied
--- End of inner exception stack trace ---
at Interop.ThrowExceptionForIoErrno(ErrorInfo errorInfo, String path, Boolean isDirectory, Func`2 errorRewriter)
at Microsoft.Win32.SafeHandles.SafeFileHandle.Open(String path, OpenFlags flags, Int32 mode)
at System.IO.FileStream.OpenHandle(FileMode mode, FileShare share, FileOptions options)
at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize, FileOptions options)
at Emby.Server.Implementations.IO.ManagedFileSystem.GetFileStream(String path, FileOpenMode mode, FileAccessMode access, FileShareMode share, FileOpenOptions fileOpenOptions)
at Emby.Server.Implementations.IO.ManagedFileSystem.GetFileStream(String path, FileOpenMode mode, FileAccessMode access, FileShareMode share, Boolean isAsync)
at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.SaveToFile(Stream stream, String path)
at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.Save(BaseItem item, CancellationToken cancellationToken)
at MediaBrowser.Providers.Manager.ProviderManager.SaveMetadata(BaseItem item, ItemUpdateType updateType, IEnumerable`1 savers)
System.UnauthorizedAccessException
at Interop.ThrowExceptionForIoErrno(ErrorInfo errorInfo, String path, Boolean isDirectory, Func`2 errorRewriter)
at Microsoft.Win32.SafeHandles.SafeFileHandle.Open(String path, OpenFlags flags, Int32 mode)
at System.IO.FileStream.OpenHandle(FileMode mode, FileShare share, FileOptions options)
at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize, FileOptions options)
at Emby.Server.Implementations.IO.ManagedFileSystem.GetFileStream(String path, FileOpenMode mode, FileAccessMode access, FileShareMode share, FileOpenOptions fileOpenOptions)
at Emby.Server.Implementations.IO.ManagedFileSystem.GetFileStream(String path, FileOpenMode mode, FileAccessMode access, FileShareMode share, Boolean isAsync)
at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.SaveToFile(Stream stream, String path)
at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.Save(BaseItem item, CancellationToken cancellationToken)
at MediaBrowser.Providers.Manager.ProviderManager.SaveMetadata(BaseItem item, ItemUpdateType updateType, IEnumerable`1 savers)
InnerException: System.IO.IOException
System.IO.IOException: Permission denied

2018-10-01 12:40:41.685 Info App: Deleting dead Person 94a08aedf3786122695f5d928d4e9eaa Paul Giamati.
2018-10-01 12:40:41.686 Info App: Deleting item, Type: Person, Name: Paul Giamati, Path: /var/lib/emby/metadata/People/P/Paul Giamati, Id: 94a08aed-f378-6122-695f-5d928d4e9eaa
2018-10-01 12:40:41.691 Info App: Deleting dead Person 2ddaa9f662965699fb68dffabd6f96d5 Nick Di Paolo.
2018-10-01 12:40:41.691 Info App: Deleting item, Type: Person, Name: Nick Di Paolo, Path: /var/lib/emby/metadata/People/N/Nick Di Paolo, Id: 2ddaa9f6-6296-5699-fb68-dffabd6f96d5
2018-10-01 12:40:41.697 Info App: Deleting dead Person 79a07d1e02c1aae4dbb024c415b91f58 Kevin Kane.
2018-10-01 12:40:41.697 Info App: Deleting item, Type: Person, Name: Kevin Kane, Path: /var/lib/emby/metadata/People/K/Kevin Kane, Id: 79a07d1e-02c1-aae4-dbb0-24c415b91f58
2018-10-01 12:40:41.704 Info App: Deleting dead Person 8e08ab64ddb743c29df120cd9939be93 George Riddle.
2018-10-01 12:40:41.704 Info App: Deleting item, Type: Person, Name: George Riddle, Path: /var/lib/emby/metadata/People/G/George Riddle, Id: 8e08ab64-ddb7-43c2-9df1-20cd9939be93
2018-10-01 12:40:41.710 Info App: Deleting dead Person 748c48a8531f0f0c5bd41eae1a7c90f0 Chris Beetem.
2018-10-01 12:40:41.710 Info App: Deleting item, Type: Person, Name: Chris Beetem, Path: /var/lib/emby/metadata/People/C/Chris Beetem, Id: 748c48a8-531f-0f0c-5bd4-1eae1a7c90f0
2018-10-01 12:40:41.716 Info App: Deleting dead Person 5e4263e3cd2ae7996e48936e8b8f38f2 Mike Housten.
2018-10-01 12:40:41.716 Info App: Deleting item, Type: Person, Name: Mike Housten, Path: /var/lib/emby/metadata/People/M/Mike Housten, Id: 5e4263e3-cd2a-e799-6e48-936e8b8f38f2
2018-10-01 12:40:41.722 Info App: Deleting dead Person fde4cb01aaca3358ee3755cd9ca1a6b6 Artie Lang.
2018-10-01 12:40:41.722 Info App: Deleting item, Type: Person, Name: Artie Lang, Path: /var/lib/emby/metadata/People/A/Artie Lang, Id: fde4cb01-aaca-3358-ee37-55cd9ca1a6b6
2018-10-01 12:40:41.727 Info App: Deleting dead Person 0e12cc43cf517b15e65d040870412d1e Kathy Najimy.
2018-10-01 12:40:41.727 Info App: Deleting item, Type: Person, Name: Kathy Najimy, Path: /var/lib/emby/metadata/People/K/Kathy Najimy, Id: 0e12cc43-cf51-7b15-e65d-040870412d1e
2018-10-01 12:40:41.730 Info App: Deleting dead Person e8baf84a9696dfac12195a0ff5f38833 Deborah Rush.
2018-10-01 12:40:41.730 Info App: Deleting item, Type: Person, Name: Deborah Rush, Path: /var/lib/emby/metadata/People/D/Deborah Rush, Id: e8baf84a-9696-dfac-1219-5a0ff5f38833
2018-10-01 12:40:41.735 Info App: Deleting dead Person 2fe604c10f17b99fe630dedd6585dbd6 Chrissy Teigen.
2018-10-01 12:40:41.735 Info App: Deleting item, Type: Person, Name: Chrissy Teigen, Path: /var/lib/emby/metadata/People/C/Chrissy Teigen, Id: 2fe604c1-0f17-b99f-e630-dedd6585dbd6
2018-10-01 12:40:41.739 Info App: Deleting dead Person 72b3eb786775b2866b4fe20a7a520c45 Reggie Watts.
2018-10-01 12:40:41.739 Info App: Deleting item, Type: Person, Name: Reggie Watts, Path: /var/lib/emby/metadata/People/R/Reggie Watts, Id: 72b3eb78-6775-b286-6b4f-e20a7a520c45
2018-10-01 12:40:41.743 Info App: Deleting dead Person ac9068f4bd2cc9c349d52e85397b7c7f Jessie Jo Thomas.
2018-10-01 12:40:41.743 Info App: Deleting item, Type: Person, Name: Jessie Jo Thomas, Path: /var/lib/emby/metadata/People/J/Jessie Jo Thomas, Id: ac9068f4-bd2c-c9c3-49d5-2e85397b7c7f
2018-10-01 12:40:41.746 Info App: Deleting dead Person 7b26a8fb84cc7fab93c8aede811505c5 Ema Parker.
2018-10-01 12:40:41.746 Info App: Deleting item, Type: Person, Name: Ema Parker, Path: /var/lib/emby/metadata/People/E/Ema Parker, Id: 7b26a8fb-84cc-7fab-93c8-aede811505c5
2018-10-01 12:40:41.753 Info App: People validation complete
2018-10-01 12:40:41.753 Info TaskManager: Refresh people Completed after 4 minute(s) and 34 seconds
2018-10-01 12:40:41.755 Info TaskManager: ExecuteQueuedTasks
2018-10-01 12:40:43.413 Error ProviderManager: Error in metadata saver
*** Error Report ***
Version: 3.5.3.0
Command line: /opt/emby-server/system/EmbyServer.dll -programdata /var/lib/emby -ffmpeg /opt/emby-server/bin/ffmpeg -ffprobe /opt/emby-server/bin/ffprobe -restartexitcode 3 -updatepackage emby-server-deb_{version}_armhf.deb
Operating system: Unix 4.4.126.0
64-Bit OS: False
64-Bit Process: False
User Interactive: True
Processor count: 6
Program data path: /var/lib/emby
Application directory: /opt/emby-server/system
System.UnauthorizedAccessException: Access to the path '/media/TV/TV Shows/Family Guy (1999)/Season 17/family.guy.s17e01.720p.web.x264-tbs.nfo' is denied. ---> System.IO.IOException: Permission denied
--- End of inner exception stack trace ---
at Interop.ThrowExceptionForIoErrno(ErrorInfo errorInfo, String path, Boolean isDirectory, Func`2 errorRewriter)
at Microsoft.Win32.SafeHandles.SafeFileHandle.Open(String path, OpenFlags flags, Int32 mode)
at System.IO.FileStream.OpenHandle(FileMode mode, FileShare share, FileOptions options)
at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize, FileOptions options)
at Emby.Server.Implementations.IO.ManagedFileSystem.GetFileStream(String path, FileOpenMode mode, FileAccessMode access, FileShareMode share, FileOpenOptions fileOpenOptions)
at Emby.Server.Implementations.IO.ManagedFileSystem.GetFileStream(String path, FileOpenMode mode, FileAccessMode access, FileShareMode share, Boolean isAsync)
at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.SaveToFile(Stream stream, String path)
at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.Save(BaseItem item, CancellationToken cancellationToken)
at MediaBrowser.Providers.Manager.ProviderManager.SaveMetadata(BaseItem item, ItemUpdateType updateType, IEnumerable`1 savers)
System.UnauthorizedAccessException
at Interop.ThrowExceptionForIoErrno(ErrorInfo errorInfo, String path, Boolean isDirectory, Func`2 errorRewriter)
at Microsoft.Win32.SafeHandles.SafeFileHandle.Open(String path, OpenFlags flags, Int32 mode)
at System.IO.FileStream.OpenHandle(FileMode mode, FileShare share, FileOptions options)
at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize, FileOptions options)
at Emby.Server.Implementations.IO.ManagedFileSystem.GetFileStream(String path, FileOpenMode mode, FileAccessMode access, FileShareMode share, FileOpenOptions fileOpenOptions)
at Emby.Server.Implementations.IO.ManagedFileSystem.GetFileStream(String path, FileOpenMode mode, FileAccessMode access, FileShareMode share, Boolean isAsync)
at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.SaveToFile(Stream stream, String path)
at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.Save(BaseItem item, CancellationToken cancellationToken)
at MediaBrowser.Providers.Manager.ProviderManager.SaveMetadata(BaseItem item, ItemUpdateType updateType, IEnumerable`1 savers)
InnerException: System.IO.IOException
System.IO.IOException: Permission denied

2018-10-01 12:40:43.757 Info App: Deleting item, Type: Episode, Name: Married... With Cancer (1), Path: , Id: d937b84c-48b1-c543-3de7-aa7d5e02f172
2018-10-01 12:40:43.919 Info App: Creating virtual unaired episode Family Guy 17x4
2018-10-01 12:40:52.620 Info HttpClient: GET https://www.thetvdb.com/api/B89CE93890E9419B/series/72514/all/en.zip
2018-10-01 12:40:53.771 Error App: Error reading existing xml tags from /media/TV/TV Shows/Looney Tunes/Season 1941/Looney Tunes - s1941e35 - Rookie Revue.nfo.
*** Error Report ***
Version: 3.5.3.0
Command line: /opt/emby-server/system/EmbyServer.dll -programdata /var/lib/emby -ffmpeg /opt/emby-server/bin/ffmpeg -ffprobe /opt/emby-server/bin/ffprobe -restartexitcode 3 -updatepackage emby-server-deb_{version}_armhf.deb
Operating system: Unix 4.4.126.0
64-Bit OS: False
64-Bit Process: False
User Interactive: True
Processor count: 6
Program data path: /var/lib/emby
Application directory: /opt/emby-server/system
System.Xml.XmlException: Root element is missing.
at System.Xml.XmlTextReaderImpl.Throw(Exception e)
at System.Xml.XmlTextReaderImpl.ParseDocumentContent()
at System.Xml.XmlTextReaderImpl.Read()
at System.Xml.XmlReader.MoveToContent()
at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.AddCustomTags(String path, List`1 xmlTagsUsed, XmlWriter writer, ILogger logger, IFileSystem fileSystem)
System.Xml.XmlException
at System.Xml.XmlTextReaderImpl.Throw(Exception e)
at System.Xml.XmlTextReaderImpl.ParseDocumentContent()
at System.Xml.XmlTextReaderImpl.Read()
at System.Xml.XmlReader.MoveToContent()
at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.AddCustomTags(String path, List`1 xmlTagsUsed, XmlWriter writer, ILogger logger, IFileSystem fileSystem)
Edited by Mr_christer
mastrmind11
Posted

You have a bunch of permission problems.  Make sure emby has RW access to your mounts.

Create an account or sign in to comment

You need to be a member in order to leave a comment

Create an account

Sign up for a new account in our community. It's easy!

Register a new account

Sign in

Already have an account? Sign in here.

Sign In Now
×
×
  • Create New...