Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

UMS 6.0.0 broken on NixOS #829

Closed
thall opened this issue Feb 7, 2016 · 16 comments
Closed

UMS 6.0.0 broken on NixOS #829

thall opened this issue Feb 7, 2016 · 16 comments

Comments

@thall
Copy link

thall commented Feb 7, 2016

Since 6.0.0 UMS is broken, since it tries to initialize database in a read-only path.

The problem is https://github.com/UniversalMediaServer/UniversalMediaServer/blob/master/src/main/java/net/pms/dlna/DLNAMediaDatabase.java#L90 , which returns a new file relative to where it executes if it not windows. It should return a file which is relative to a user directory.

DEBUG 2016-02-07 12:26:07.200 [main] Base path set to file:///home/thall/.config/UMS/UMS.conf
DEBUG 2016-02-07 12:26:07.211 [main] FileName set to UMS.conf
DEBUG 2016-02-07 12:26:07.211 [main] Base path set to /home/thall/.config/UMS
INFO 2016-02-07 12:26:07.215 [main] Language not specified, defaulting to OS language.
DEBUG 2016-02-07 12:26:07.232 [main] Reading log_level: "TRACE" (default: "DEBUG")
TRACE 2016-02-07 12:26:07.234 [main] getDefaultLogFileFolder: System is Linux, trying "/var/log/UMS/thall/"
TRACE 2016-02-07 12:26:07.234 [main] getDefaultLogFileFolder: Trying to create: "/var/log/UMS/thall"
DEBUG 2016-02-07 12:26:07.240 [main] Could not create "/var/log/UMS/thall": Unable to create directory /var/log/UMS/thall
TRACE 2016-02-07 12:26:07.241 [main] getDefaultLogFileFolder: "/var/log/UMS/thall" not found, falling back to profile folder for logging: File "/var/log/UMS/thall" not found
TRACE 2016-02-07 12:26:07.246 [main] isAdmin: Executing "id -Gn"
TRACE 2016-02-07 12:26:07.260 [main] isAdmin: "id -Gn" returned users wheel disk audio cdrom docker
TRACE 2016-02-07 12:26:07.260 [main] isAdmin: UMS does not have root privileges
DEBUG 2016-02-07 12:26:07.260 [main] Default logfile folder set to: /home/thall/.config/UMS
DEBUG 2016-02-07 12:26:07.444 [main] LogBack started with configuration file: /nix/store/9gsj45v056d4w0jaw90j0k0lmzfpv14w-ums-6.0.0/logback.xml
DEBUG 2016-02-07 12:26:07.463 [main] Sun Feb 07 12:26:07 CET 2016
DEBUG 2016-02-07 12:26:07.464 [main] Reading cred.path: "/home/thall/.config/UMS/UMS.cred" (default: "")
DEBUG 2016-02-07 12:26:07.469 [main] Writing PID: 23348
DEBUG 2016-02-07 12:26:17.692 [main]
INFO 2016-02-07 12:26:17.693 [main] Starting Universal Media Server 6.0.0
INFO 2016-02-07 12:26:17.693 [main] Based on PS3 Media Server by shagrath, copyright 2008-2014
INFO 2016-02-07 12:26:17.693 [main] http://www.universalmediaserver.com
INFO 2016-02-07 12:26:17.693 [main]
INFO 2016-02-07 12:26:17.693 [main] Build: 547f273 (2016-01-30)
INFO 2016-02-07 12:26:17.693 [main] Java: OpenJDK 64-Bit Server VM 1.8.0_76 64-bit by Oracle Corporation
INFO 2016-02-07 12:26:17.693 [main] OS: Linux 64-bit 4.1.15
INFO 2016-02-07 12:26:17.693 [main] Encoding: UTF-8
INFO 2016-02-07 12:26:17.694 [main] Memory: 683 MB
INFO 2016-02-07 12:26:17.700 [main] Language: English (United States)
INFO 2016-02-07 12:26:17.701 [main]
INFO 2016-02-07 12:26:17.701 [main] Working directory: /nix/store/9gsj45v056d4w0jaw90j0k0lmzfpv14w-ums-6.0.0
INFO 2016-02-07 12:26:17.701 [main] Temporary directory: /tmp/UMS-thall
INFO 2016-02-07 12:26:17.701 [main] Logging configuration file: /nix/store/9gsj45v056d4w0jaw90j0k0lmzfpv14w-ums-6.0.0/logback.xml
INFO 2016-02-07 12:26:17.702 [main] Logfile: /home/thall/.config/UMS/debug.log
INFO 2016-02-07 12:26:17.702 [main]
INFO 2016-02-07 12:26:17.702 [main] Profile directory: /home/thall/.config/UMS
INFO 2016-02-07 12:26:17.702 [main] Profile directory permissions: drwx
INFO 2016-02-07 12:26:17.702 [main] Profile configuration file: /home/thall/.config/UMS/UMS.conf
INFO 2016-02-07 12:26:17.703 [main] Profile configuration file permissions: -rw-
INFO 2016-02-07 12:26:17.723 [main] Profile name: thall
INFO 2016-02-07 12:26:17.724 [main]
DEBUG 2016-02-07 12:26:17.724 [main] Reading web_conf: "/home/thall/.config/UMS/WEB.conf" (previous: null, default: "/home/thall/.config/UMS/WEB.conf")
INFO 2016-02-07 12:26:17.724 [main] Web configuration file: /home/thall/.config/UMS/WEB.conf
WARN 2016-02-07 12:26:17.724 [main] Web configuration file not found: File "/home/thall/.config/UMS/WEB.conf" not found
INFO 2016-02-07 12:26:17.725 [main]
TRACE 2016-02-07 12:26:17.732 [main] Could not read name filter: File "/home/thall/.config/UMS/UMS.deny" not found
DEBUG 2016-02-07 12:26:17.741 [main] Using database URL: jdbc:h2:/nix/store/9gsj45v056d4w0jaw90j0k0lmzfpv14w-ums-6.0.0/database/medias
INFO 2016-02-07 12:26:17.741 [main] Using database located at: /nix/store/9gsj45v056d4w0jaw90j0k0lmzfpv14w-ums-6.0.0/database
ERROR 2016-02-07 12:26:19.173 [main] Database connection error: Error while creating file "/nix/store/9gsj45v056d4w0jaw90j0k0lmzfpv14w-ums-6.0.0/database" [90062-191]
TRACE 2016-02-07 12:26:19.178 [main]
org.h2.jdbc.JdbcSQLException: Error while creating file "/nix/store/9gsj45v056d4w0jaw90j0k0lmzfpv14w-ums-6.0.0/database" [90062-191]
at org.h2.message.DbException.getJdbcSQLException(DbException.java:345) ~[ums.jar:6.0.0]
at org.h2.message.DbException.get(DbException.java:179) ~[ums.jar:6.0.0]
at org.h2.message.DbException.get(DbException.java:155) ~[ums.jar:6.0.0]
at org.h2.store.fs.FilePathDisk.createDirectory(FilePathDisk.java:274) ~[ums.jar:6.0.0]
at org.h2.store.fs.FileUtils.createDirectory(FileUtils.java:42) ~[ums.jar:6.0.0]
at org.h2.store.fs.FileUtils.createDirectories(FileUtils.java:309) ~[ums.jar:6.0.0]
at org.h2.mvstore.db.MVTableEngine.init(MVTableEngine.java:73) ~[ums.jar:6.0.0]
at org.h2.engine.Database.getPageStore(Database.java:2460) ~[ums.jar:6.0.0]
at org.h2.engine.Database.open(Database.java:692) ~[ums.jar:6.0.0]
at org.h2.engine.Database.openDatabase(Database.java:270) ~[ums.jar:6.0.0]
at org.h2.engine.Database.(Database.java:264) ~[ums.jar:6.0.0]
at org.h2.engine.Engine.openSession(Engine.java:65) ~[ums.jar:6.0.0]
at org.h2.engine.Engine.openSession(Engine.java:175) ~[ums.jar:6.0.0]
at org.h2.engine.Engine.createSessionAndValidate(Engine.java:153) ~[ums.jar:6.0.0]
at org.h2.engine.Engine.createSession(Engine.java:136) ~[ums.jar:6.0.0]
at org.h2.engine.Engine.createSession(Engine.java:28) ~[ums.jar:6.0.0]
at org.h2.engine.SessionRemote.connectEmbeddedOrServer(SessionRemote.java:349) ~[ums.jar:6.0.0]
at org.h2.jdbc.JdbcConnection.(JdbcConnection.java:107) ~[ums.jar:6.0.0]
at org.h2.jdbc.JdbcConnection.(JdbcConnection.java:91) ~[ums.jar:6.0.0]
at org.h2.Driver.connect(Driver.java:72) ~[ums.jar:6.0.0]
at org.h2.jdbcx.JdbcDataSource.getJdbcConnection(JdbcDataSource.java:190) ~[ums.jar:6.0.0]
at org.h2.jdbcx.JdbcDataSource.getXAConnection(JdbcDataSource.java:353) ~[ums.jar:6.0.0]
at org.h2.jdbcx.JdbcDataSource.getPooledConnection(JdbcDataSource.java:385) ~[ums.jar:6.0.0]
at org.h2.jdbcx.JdbcConnectionPool.getConnectionNow(JdbcConnectionPool.java:227) ~[ums.jar:6.0.0]
at org.h2.jdbcx.JdbcConnectionPool.getConnection(JdbcConnectionPool.java:199) ~[ums.jar:6.0.0]
at net.pms.dlna.DLNAMediaDatabase.getConnection(DLNAMediaDatabase.java:131) ~[ums.jar:6.0.0]
at net.pms.dlna.DLNAMediaDatabase.init(DLNAMediaDatabase.java:142) ~[ums.jar:6.0.0]
at net.pms.PMS.getDatabase(PMS.java:272) [ums.jar:6.0.0]
at net.pms.database.Tables.(Tables.java:45) [ums.jar:6.0.0]
at net.pms.PMS.init(PMS.java:418) [ums.jar:6.0.0]
at net.pms.PMS.createInstance(PMS.java:1062) [ums.jar:6.0.0]
at net.pms.PMS.main(PMS.java:1220) [ums.jar:6.0.0]

@thall
Copy link
Author

thall commented Feb 7, 2016

And this is introduces since the change in PMS.init() , https://github.com/UniversalMediaServer/UniversalMediaServer/blob/master/src/main/java/net/pms/PMS.java#L418

@Nadahar
Copy link
Contributor

Nadahar commented Feb 7, 2016

@thall I don't quite follow your logic. The path calculation has been like it is since 5.0.0. The new call in 6.0.0 just initializes the database earlier - I can't quite see how 6.0.0 could have changed the location itself. Are you sure this hasn't failed also in earlier versions, but that you haven't noticed since the failure hasn't aborted initialization?

What I find strange is why the database is placed relative to the "current dir" for non Windows installations. @skeptical Can you shed some light on that?

@thall
Copy link
Author

thall commented Feb 7, 2016

@Nadahar , It have been working for both 5.3 and 5.4. Yes i also find it strange it worked in earlier versions. See trace log below. It seems that the database isn't initialize at all. So that would explain why it worked before.

But the path calculation i also find strange.

DEBUG 2016-02-07 18:25:18.399 [main] Base path set to file:///home/thall/.config/UMS/UMS.conf
DEBUG 2016-02-07 18:25:18.417 [main] FileName set to UMS.conf
DEBUG 2016-02-07 18:25:18.417 [main] Base path set to /home/thall/.config/UMS
INFO 2016-02-07 18:25:18.423 [main] Language not specified, defaulting to OS language.
DEBUG 2016-02-07 18:25:18.457 [main] Reading log_level: "TRACE" (default: "DEBUG")
TRACE 2016-02-07 18:25:18.460 [main] getDefaultLogFileFolder: System is Linux, trying "/var/log/UMS/thall/"
TRACE 2016-02-07 18:25:18.460 [main] getDefaultLogFileFolder: Trying to create: "/var/log/UMS/thall"
DEBUG 2016-02-07 18:25:18.467 [main] Could not create "/var/log/UMS/thall": Unable to create directory /var/log/UMS/thall
TRACE 2016-02-07 18:25:18.467 [main] getDefaultLogFileFolder: "/var/log/UMS/thall" not found, falling back to profile folder for logging: File "/var/log/UMS/thall" not found
DEBUG 2016-02-07 18:25:18.486 [main] Default logfile folder set to: /home/thall/.config/UMS
DEBUG 2016-02-07 18:25:18.726 [main] LogBack started with configuration file: /nix/store/21v5q6yrm0wjkjpmvxz7i71ka5mnzn3g-ums-5.4.0/logback.xml
DEBUG 2016-02-07 18:25:18.754 [main] Sun Feb 07 18:25:18 CET 2016
DEBUG 2016-02-07 18:25:18.755 [main] Reading cred.path: "/home/thall/.config/UMS/UMS.cred" (default: "")
DEBUG 2016-02-07 18:25:18.791 [main] Writing PID: 24243
DEBUG 2016-02-07 18:25:18.794 [main]
INFO 2016-02-07 18:25:18.795 [main] Starting Universal Media Server 5.4.0
INFO 2016-02-07 18:25:18.795 [main] Based on PS3 Media Server by shagrath, copyright 2008-2014
INFO 2016-02-07 18:25:18.795 [main] http://www.universalmediaserver.com
INFO 2016-02-07 18:25:18.795 [main]
INFO 2016-02-07 18:25:18.795 [main] Build: d1ff429 (2015-12-28)
INFO 2016-02-07 18:25:18.796 [main] Java: OpenJDK 64-Bit Server VM 1.8.0_76 64-bit by Oracle Corporation
INFO 2016-02-07 18:25:18.796 [main] OS: Linux 64-bit 4.1.15
INFO 2016-02-07 18:25:18.796 [main] Encoding: UTF-8
INFO 2016-02-07 18:25:18.796 [main] Memory: 683 MB
INFO 2016-02-07 18:25:18.796 [main] Language: English (United States)
INFO 2016-02-07 18:25:18.797 [main]
INFO 2016-02-07 18:25:18.797 [main] Working directory: /nix/store/21v5q6yrm0wjkjpmvxz7i71ka5mnzn3g-ums-5.4.0
INFO 2016-02-07 18:25:18.797 [main] Temp directory: /tmp/UMS-thall
INFO 2016-02-07 18:25:18.797 [main] Logging config file: /nix/store/21v5q6yrm0wjkjpmvxz7i71ka5mnzn3g-ums-5.4.0/logback.xml
INFO 2016-02-07 18:25:18.798 [main] Logfile: /home/thall/.config/UMS/debug.log
INFO 2016-02-07 18:25:18.798 [main]
INFO 2016-02-07 18:25:18.798 [main] Profile directory: /home/thall/.config/UMS
INFO 2016-02-07 18:25:18.798 [main] Profile directory permissions: drwx
INFO 2016-02-07 18:25:18.798 [main] Profile configuration file: /home/thall/.config/UMS/UMS.conf
TRACE 2016-02-07 18:25:18.800 [main] isAdmin: Executing "id -Gn"
TRACE 2016-02-07 18:25:18.802 [main] isAdmin: "id -Gn" returned users wheel disk audio cdrom docker
TRACE 2016-02-07 18:25:18.803 [main] isAdmin: UMS does not have root privileges
INFO 2016-02-07 18:25:18.803 [main] Profile configuration file permissions: -rw-
INFO 2016-02-07 18:25:18.805 [main] Profile name: thall
INFO 2016-02-07 18:25:18.806 [main]
DEBUG 2016-02-07 18:25:18.806 [main] Reading web_conf: "/home/thall/.config/UMS/WEB.conf" (previous: null, default: "/home/thall/.config/UMS/WEB.conf")
INFO 2016-02-07 18:25:18.806 [main] Web configuration file: /home/thall/.config/UMS/WEB.conf
WARN 2016-02-07 18:25:18.806 [main] Web configuration file not found: File "/home/thall/.config/UMS/WEB.conf" not found
INFO 2016-02-07 18:25:18.807 [main]
TRACE 2016-02-07 18:25:18.820 [main] Could not read name filter: File "/home/thall/.config/UMS/UMS.deny" not found
INFO 2016-02-07 18:25:25.044 [main] Configuration saved to: /home/thall/.config/UMS/UMS.conf
INFO 2016-02-07 18:25:29.357 [main] Configuration saved to: /home/thall/.config/UMS/UMS.conf
INFO 2016-02-07 18:25:33.563 [main] Configuration saved to: /home/thall/.config/UMS/UMS.conf
INFO 2016-02-07 18:25:34.735 [main] Configuration saved to: /home/thall/.config/UMS/UMS.conf
DEBUG 2016-02-07 18:25:34.781 [main] Reading hide_advanced_options: false (default: true)
DEBUG 2016-02-07 18:25:36.984 [main] Reading minimized: true (default: false)
DEBUG 2016-02-07 18:25:37.210 [main] Reading run_wizard: false (previous: true, default: true)
TRACE 2016-02-07 18:25:37.224 [main] checkNetworkInterface(parent = null, child interfaces = java.net.NetworkInterface$2@40a4337a)
TRACE 2016-02-07 18:25:37.224 [main] checking wlp4s0, display name: wlp4s0
TRACE 2016-02-07 18:25:37.225 [main] available addresses for wlp4s0 is: [/192.168.1.109]
TRACE 2016-02-07 18:25:37.225 [main] non loopback/ipv4 addresses: [/192.168.1.109]
TRACE 2016-02-07 18:25:37.225 [main] checkNetworkInterface(parent = wlp4s0, child interfaces = java.net.NetworkInterface$1subIFs@6025e1b6)
TRACE 2016-02-07 18:25:37.225 [main] checkNetworkInterface(parent = wlp4s0) finished
TRACE 2016-02-07 18:25:37.225 [main] sub address for wlp4s0 is []
TRACE 2016-02-07 18:25:37.225 [main] checking /192.168.1.109 on wlp4s0
TRACE 2016-02-07 18:25:37.225 [main] found wlp4s0 -> 192.168.1.109
TRACE 2016-02-07 18:25:37.226 [main] checking lo, display name: lo
TRACE 2016-02-07 18:25:37.226 [main] available addresses for lo is: [/127.0.0.1]
TRACE 2016-02-07 18:25:37.226 [main] non loopback/ipv4 addresses: []
TRACE 2016-02-07 18:25:37.226 [main] checkNetworkInterface(parent = lo, child interfaces = java.net.NetworkInterface$1subIFs@4c402120)
TRACE 2016-02-07 18:25:37.226 [main] checkNetworkInterface(parent = lo) finished
TRACE 2016-02-07 18:25:37.226 [main] sub address for lo is []
TRACE 2016-02-07 18:25:37.226 [main] checking /127.0.0.1 on lo
TRACE 2016-02-07 18:25:37.226 [main] has /127.0.0.1, which is skipped, because loopback=true, ipv6=false
TRACE 2016-02-07 18:25:37.226 [main] found lo, without valid address
TRACE 2016-02-07 18:25:37.226 [main] checkNetworkInterface(parent = null) finished
DEBUG 2016-02-07 18:25:37.333 [main] Reading maximum_bitrate: "0" (default: "90")
TRACE 2016-02-07 18:25:37.750 [main] An invalid plugin was ignored (2)
INFO 2016-02-07 18:25:38.146 [main] Couldn't find help file "/nix/store/21v5q6yrm0wjkjpmvxz7i71ka5mnzn3g-ums-5.4.0/documentation/index.html". Help will not be available.
DEBUG 2016-02-07 18:25:38.295 [main] Checking dbgpack property in UMS.conf
DEBUG 2016-02-07 18:25:38.305 [main] adding /home/thall/.config/UMS/WEB.conf
DEBUG 2016-02-07 18:25:38.305 [main] adding /home/thall/.config/UMS/UMS.conf
DEBUG 2016-02-07 18:25:38.305 [main] adding /home/thall/.config/UMS/debug.log.prev
DEBUG 2016-02-07 18:25:38.305 [main] adding /home/thall/.config/UMS/debug.log
INFO 2016-02-07 18:25:38.347 [main] Loading MediaInfo library
INFO 2016-02-07 18:25:39.568 [main] Loaded MediaInfoLib - v0.7.81
DEBUG 2016-02-07 18:25:39.573 [main] Option 'File_TestContinuousFileNames' is set to: 0
DEBUG 2016-02-07 18:25:39.574 [main] Option 'ParseSpeed' is set to: 0.000
INFO 2016-02-07 18:25:39.580 [main] Loading renderer configurations from /nix/store/21v5q6yrm0wjkjpmvxz7i71ka5mnzn3g-ums-5.4.0/renderers
DEBUG 2016-02-07 18:25:39.602 [main] Added file watch at renderers: renderers/AnyCast.conf
DEBUG 2016-02-07 18:25:39.606 [main] Added file watch at renderers: renderers/Apple-iDevice-AirPlayer.conf
DEBUG 2016-02-07 18:25:39.610 [main] Added file watch at renderers: renderers/Apple-iDevice-VLC.conf
DEBUG 2016-02-07 18:25:39.613 [main] Added file watch at renderers: renderers/Apple-iDevice.conf
DEBUG 2016-02-07 18:25:39.624 [main] Added file watch at renderers: renderers/BlackBerry-PlayBook-KalemSoftMP.conf
DEBUG 2016-02-07 18:25:39.629 [main] Added file watch at renderers: renderers/CambridgeAudio-AzurBD.conf
DEBUG 2016-02-07 18:25:39.632 [main] Added file watch at renderers: renderers/DLink-DSM510.conf
DEBUG 2016-02-07 18:25:39.638 [main] Added file watch at renderers: renderers/DefaultRenderer.conf
DEBUG 2016-02-07 18:25:39.641 [main] Added file watch at renderers: renderers/DirecTV.conf
DEBUG 2016-02-07 18:25:39.644 [main] Added file watch at renderers: renderers/Free-Freebox.conf
DEBUG 2016-02-07 18:25:39.645 [main] Added file watch at renderers: renderers/Freecom-MusicPal.conf
DEBUG 2016-02-07 18:25:39.650 [main] Added file watch at renderers: renderers/Google-Android-Chromecast.conf
DEBUG 2016-02-07 18:25:39.656 [main] Added file watch at renderers: renderers/Google-Android.conf
DEBUG 2016-02-07 18:25:39.660 [main] Added file watch at renderers: renderers/Hama-IR320.conf
DEBUG 2016-02-07 18:25:39.665 [main] Added file watch at renderers: renderers/Hisense-K680.conf
DEBUG 2016-02-07 18:25:39.673 [main] Added file watch at renderers: renderers/Kodi.conf
DEBUG 2016-02-07 18:25:39.675 [main] Added file watch at renderers: renderers/LG-LA6200.conf
DEBUG 2016-02-07 18:25:39.680 [main] Added file watch at renderers: renderers/LG-LA644V.conf
DEBUG 2016-02-07 18:25:39.686 [main] Added file watch at renderers: renderers/LG-LB.conf
DEBUG 2016-02-07 18:25:39.693 [main] Added file watch at renderers: renderers/LG-LM620.conf
DEBUG 2016-02-07 18:25:39.701 [main] Added file watch at renderers: renderers/LG-LM660.conf
DEBUG 2016-02-07 18:25:39.707 [main] Added file watch at renderers: renderers/LG-LS5700.conf
DEBUG 2016-02-07 18:25:39.710 [main] Added file watch at renderers: renderers/LG-ST600.conf
DEBUG 2016-02-07 18:25:39.716 [main] Added file watch at renderers: renderers/LG-UB820V.conf
DEBUG 2016-02-07 18:25:39.723 [main] Added file watch at renderers: renderers/LG-WebOS.conf
DEBUG 2016-02-07 18:25:39.725 [main] Added file watch at renderers: renderers/Logitech-Squeezebox.conf
DEBUG 2016-02-07 18:25:39.729 [main] Added file watch at renderers: renderers/Microsoft-WindowsMediaPlayer.conf
DEBUG 2016-02-07 18:25:39.735 [main] Added file watch at renderers: renderers/Microsoft-Xbox360.conf
DEBUG 2016-02-07 18:25:39.739 [main] Added file watch at renderers: renderers/Microsoft-XboxOne.conf
DEBUG 2016-02-07 18:25:39.741 [main] Added file watch at renderers: renderers/Miracast-M806.conf
DEBUG 2016-02-07 18:25:39.744 [main] Added file watch at renderers: renderers/Netgear-NeoTV.conf
DEBUG 2016-02-07 18:25:39.745 [main] Added file watch at renderers: renderers/Netgem-N7700.conf
DEBUG 2016-02-07 18:25:39.747 [main] Added file watch at renderers: renderers/Nokia-N900.conf
DEBUG 2016-02-07 18:25:39.749 [main] Added file watch at renderers: renderers/OPPO-BDP.conf
DEBUG 2016-02-07 18:25:39.750 [main] Added file watch at renderers: renderers/OPPO-BDP83.conf
DEBUG 2016-02-07 18:25:39.751 [main] Added file watch at renderers: renderers/Onkyo-TXNR717.conf
DEBUG 2016-02-07 18:25:39.752 [main] Added file watch at renderers: renderers/Panasonic-DMPBDT.conf
DEBUG 2016-02-07 18:25:39.754 [main] Added file watch at renderers: renderers/Panasonic-DMPBDT220.conf
DEBUG 2016-02-07 18:25:39.755 [main] Added file watch at renderers: renderers/Panasonic-DMPBDT360.conf
DEBUG 2016-02-07 18:25:39.758 [main] Added file watch at renderers: renderers/Panasonic-SCBTT.conf
DEBUG 2016-02-07 18:25:39.766 [main] Added file watch at renderers: renderers/Panasonic-Viera.conf
DEBUG 2016-02-07 18:25:39.769 [main] Added file watch at renderers: renderers/Panasonic-VieraAS600E.conf
DEBUG 2016-02-07 18:25:39.774 [main] Added file watch at renderers: renderers/Panasonic-VieraAS650.conf
DEBUG 2016-02-07 18:25:39.778 [main] Added file watch at renderers: renderers/Panasonic-VieraET60.conf
DEBUG 2016-02-07 18:25:39.782 [main] Added file watch at renderers: renderers/Panasonic-VieraS60.conf
DEBUG 2016-02-07 18:25:39.788 [main] Added file watch at renderers: renderers/Panasonic-VieraTHPU30Z.conf
DEBUG 2016-02-07 18:25:39.790 [main] Added file watch at renderers: renderers/Panasonic-VieraTXL32V10E.conf
DEBUG 2016-02-07 18:25:39.793 [main] Added file watch at renderers: renderers/Panasonic-VieraVT60.conf
DEBUG 2016-02-07 18:25:39.795 [main] Added file watch at renderers: renderers/Philips-AureaAndNetTV.conf
DEBUG 2016-02-07 18:25:39.800 [main] Added file watch at renderers: renderers/Philips-PFL.conf
DEBUG 2016-02-07 18:25:39.804 [main] Added file watch at renderers: renderers/Philips-Streamium.conf
DEBUG 2016-02-07 18:25:39.806 [main] Added file watch at renderers: renderers/Pioneer-Kuro.conf
DEBUG 2016-02-07 18:25:39.808 [main] Added file watch at renderers: renderers/PopcornHour.conf
DEBUG 2016-02-07 18:25:39.811 [main] Added file watch at renderers: renderers/Realtek.conf
DEBUG 2016-02-07 18:25:39.813 [main] Added file watch at renderers: renderers/Roku-Roku3-3.conf
DEBUG 2016-02-07 18:25:39.818 [main] Added file watch at renderers: renderers/Roku-Roku3-5.conf
DEBUG 2016-02-07 18:25:39.821 [main] Added file watch at renderers: renderers/Samsung-BDC6800.conf
DEBUG 2016-02-07 18:25:39.823 [main] Added file watch at renderers: renderers/Samsung-BDH6500.conf
DEBUG 2016-02-07 18:25:39.828 [main] Added file watch at renderers: renderers/Samsung-C6600.conf
DEBUG 2016-02-07 18:25:39.830 [main] Added file watch at renderers: renderers/Samsung-CD.conf
DEBUG 2016-02-07 18:25:39.834 [main] Added file watch at renderers: renderers/Samsung-D6400.conf
DEBUG 2016-02-07 18:25:39.837 [main] Added file watch at renderers: renderers/Samsung-D7000.conf
DEBUG 2016-02-07 18:25:39.840 [main] Added file watch at renderers: renderers/Samsung-EH5300.conf
DEBUG 2016-02-07 18:25:39.847 [main] Added file watch at renderers: renderers/Samsung-EH6070.conf
DEBUG 2016-02-07 18:25:39.851 [main] Added file watch at renderers: renderers/Samsung-ES6100.conf
DEBUG 2016-02-07 18:25:39.853 [main] Added file watch at renderers: renderers/Samsung-ES6575.conf
DEBUG 2016-02-07 18:25:39.860 [main] Added file watch at renderers: renderers/Samsung-ES8000.conf
DEBUG 2016-02-07 18:25:39.864 [main] Added file watch at renderers: renderers/Samsung-ES8005.conf
DEBUG 2016-02-07 18:25:39.866 [main] Added file watch at renderers: renderers/Samsung-F5100.conf
DEBUG 2016-02-07 18:25:39.869 [main] Added file watch at renderers: renderers/Samsung-F5505.conf
DEBUG 2016-02-07 18:25:39.871 [main] Added file watch at renderers: renderers/Samsung-F5900.conf
DEBUG 2016-02-07 18:25:39.873 [main] Added file watch at renderers: renderers/Samsung-GalaxyS5.conf
DEBUG 2016-02-07 18:25:39.877 [main] Added file watch at renderers: renderers/Samsung-H4500.conf
DEBUG 2016-02-07 18:25:39.880 [main] Added file watch at renderers: renderers/Samsung-H6203.conf
DEBUG 2016-02-07 18:25:39.883 [main] Added file watch at renderers: renderers/Samsung-H6400.conf
DEBUG 2016-02-07 18:25:39.885 [main] Added file watch at renderers: renderers/Samsung-HTE3.conf
DEBUG 2016-02-07 18:25:39.886 [main] Added file watch at renderers: renderers/Samsung-HTF4.conf
DEBUG 2016-02-07 18:25:39.889 [main] Added file watch at renderers: renderers/Samsung-HU7000.conf
DEBUG 2016-02-07 18:25:39.891 [main] Added file watch at renderers: renderers/Samsung-HU9000.conf
DEBUG 2016-02-07 18:25:39.894 [main] Added file watch at renderers: renderers/Samsung-J55xx.conf
DEBUG 2016-02-07 18:25:39.896 [main] Added file watch at renderers: renderers/Samsung-JU6400.conf
DEBUG 2016-02-07 18:25:39.899 [main] Added file watch at renderers: renderers/Samsung-Mobile.conf
DEBUG 2016-02-07 18:25:39.900 [main] Added file watch at renderers: renderers/Samsung-NotCD.conf
DEBUG 2016-02-07 18:25:39.903 [main] Added file watch at renderers: renderers/Samsung-SMTG7400.conf
DEBUG 2016-02-07 18:25:39.907 [main] Added file watch at renderers: renderers/Samsung-WiseLink.conf
DEBUG 2016-02-07 18:25:39.909 [main] Added file watch at renderers: renderers/Sharp-Aquos.conf
DEBUG 2016-02-07 18:25:39.911 [main] Added file watch at renderers: renderers/Showtime3.conf
DEBUG 2016-02-07 18:25:39.915 [main] Added file watch at renderers: renderers/Showtime4.conf
DEBUG 2016-02-07 18:25:39.917 [main] Added file watch at renderers: renderers/Sony-Bluray.conf
DEBUG 2016-02-07 18:25:39.920 [main] Added file watch at renderers: renderers/Sony-Bluray2013.conf
DEBUG 2016-02-07 18:25:39.924 [main] Added file watch at renderers: renderers/Sony-Bravia4500.conf
DEBUG 2016-02-07 18:25:39.925 [main] Added file watch at renderers: renderers/Sony-Bravia5500.conf
DEBUG 2016-02-07 18:25:39.927 [main] Added file watch at renderers: renderers/Sony-BraviaBX305.conf
DEBUG 2016-02-07 18:25:39.929 [main] Added file watch at renderers: renderers/Sony-BraviaEX.conf
DEBUG 2016-02-07 18:25:39.932 [main] Added file watch at renderers: renderers/Sony-BraviaEX620.conf
DEBUG 2016-02-07 18:25:39.933 [main] Added file watch at renderers: renderers/Sony-BraviaHX.conf
DEBUG 2016-02-07 18:25:39.935 [main] Added file watch at renderers: renderers/Sony-BraviaHX75.conf
DEBUG 2016-02-07 18:25:39.937 [main] Added file watch at renderers: renderers/Sony-BraviaNX70x.conf
DEBUG 2016-02-07 18:25:39.953 [main] Added file watch at renderers: renderers/Sony-BraviaNX800.conf
DEBUG 2016-02-07 18:25:39.955 [main] Added file watch at renderers: renderers/Sony-BraviaW.conf
DEBUG 2016-02-07 18:25:39.958 [main] Added file watch at renderers: renderers/Sony-BraviaXBR.conf
DEBUG 2016-02-07 18:25:39.961 [main] Added file watch at renderers: renderers/Sony-HomeTheatreSystem.conf
DEBUG 2016-02-07 18:25:39.965 [main] Added file watch at renderers: renderers/Sony-PlayStation3.conf
DEBUG 2016-02-07 18:25:39.966 [main] Added file watch at renderers: renderers/Sony-PlayStation4.conf
DEBUG 2016-02-07 18:25:39.967 [main] Added file watch at renderers: renderers/Sony-PlayStationVita.conf
DEBUG 2016-02-07 18:25:39.969 [main] Added file watch at renderers: renderers/Sony-SMPN100.conf
DEBUG 2016-02-07 18:25:39.970 [main] Added file watch at renderers: renderers/Sony-STR5800ES.conf
DEBUG 2016-02-07 18:25:39.972 [main] Added file watch at renderers: renderers/Sony-Xperia.conf
DEBUG 2016-02-07 18:25:39.974 [main] Added file watch at renderers: renderers/Sony-XperiaZ3.conf
DEBUG 2016-02-07 18:25:39.975 [main] Added file watch at renderers: renderers/Technisat-S1Plus.conf
DEBUG 2016-02-07 18:25:39.977 [main] Added file watch at renderers: renderers/Telefunken-TV.conf
DEBUG 2016-02-07 18:25:39.978 [main] Added file watch at renderers: renderers/Telstra-Tbox.conf
DEBUG 2016-02-07 18:25:39.979 [main] Added file watch at renderers: renderers/Thomson-U3.conf
DEBUG 2016-02-07 18:25:39.981 [main] Added file watch at renderers: renderers/VideoWeb-VideoWebTV.conf
DEBUG 2016-02-07 18:25:39.982 [main] Added file watch at renderers: renderers/Vizio-SmartTV.conf
DEBUG 2016-02-07 18:25:39.983 [main] Added file watch at renderers: renderers/WesternDigital-WDTVLive.conf
DEBUG 2016-02-07 18:25:39.985 [main] Added file watch at renderers: renderers/XBMC.conf
DEBUG 2016-02-07 18:25:39.985 [main] Added file watch at renderers: renderers/Yamaha-RN500.conf
DEBUG 2016-02-07 18:25:39.986 [main] Added file watch at renderers: renderers/Yamaha-RXA1010.conf
DEBUG 2016-02-07 18:25:39.987 [main] Added file watch at renderers: renderers/Yamaha-RXV3900.conf
DEBUG 2016-02-07 18:25:39.988 [main] Added file watch at renderers: renderers/Yamaha-RXV671.conf
INFO 2016-02-07 18:25:39.989 [main] Enabled 121 configurations, listed in order of loading priority:
INFO 2016-02-07 18:25:39.991 [main] : Panasonic AS600
INFO 2016-02-07 18:25:39.992 [main] : Panasonic AS650
INFO 2016-02-07 18:25:39.993 [main] : Panasonic BDT220
INFO 2016-02-07 18:25:39.994 [main] : Panasonic BDT360
INFO 2016-02-07 18:25:39.994 [main] : Panasonic ET60
INFO 2016-02-07 18:25:39.994 [main] : Panasonic Home Theater (SC-BTT)
INFO 2016-02-07 18:25:39.995 [main] : Panasonic S60
INFO 2016-02-07 18:25:39.995 [main] : Panasonic VT60
INFO 2016-02-07 18:25:39.995 [main] : Sony Blu-ray (2013)
INFO 2016-02-07 18:25:39.996 [main] : Xperia Z3
INFO 2016-02-07 18:25:40.057 [main] : Chromecast
INFO 2016-02-07 18:25:40.058 [main] : Hisense K680
INFO 2016-02-07 18:25:40.058 [main] : LG LCD TV (2014)
INFO 2016-02-07 18:25:40.076 [main] : LG LM660
INFO 2016-02-07 18:25:40.077 [main] : LG LS5700
INFO 2016-02-07 18:25:40.077 [main] : LG UB820V
INFO 2016-02-07 18:25:40.077 [main] : LG WebOS TV
INFO 2016-02-07 18:25:40.078 [main] : Panasonic TV
INFO 2016-02-07 18:25:40.078 [main] : Samsung BD-C6800
INFO 2016-02-07 18:25:40.078 [main] : Samsung D6400
INFO 2016-02-07 18:25:40.078 [main] : Samsung D7000
INFO 2016-02-07 18:25:40.079 [main] : Samsung EH5300
INFO 2016-02-07 18:25:40.079 [main] : Samsung EH6070
INFO 2016-02-07 18:25:40.079 [main] : Samsung ES6100
INFO 2016-02-07 18:25:40.080 [main] : Samsung ES6575
INFO 2016-02-07 18:25:40.080 [main] : Samsung ES8000
INFO 2016-02-07 18:25:40.080 [main] : Samsung ES8005
INFO 2016-02-07 18:25:40.081 [main] : Samsung F5100
INFO 2016-02-07 18:25:40.081 [main] : Samsung F5505
INFO 2016-02-07 18:25:40.081 [main] : Samsung F5900
INFO 2016-02-07 18:25:40.081 [main] : Samsung H4500
INFO 2016-02-07 18:25:40.082 [main] : Samsung H6203
INFO 2016-02-07 18:25:40.082 [main] : Samsung H6400
INFO 2016-02-07 18:25:40.082 [main] : Samsung H6500
INFO 2016-02-07 18:25:40.082 [main] : Samsung HT-E3 Series
INFO 2016-02-07 18:25:40.083 [main] : Samsung HT-F4
INFO 2016-02-07 18:25:40.083 [main] : Samsung HU7000
INFO 2016-02-07 18:25:40.083 [main] : Samsung HU9000
INFO 2016-02-07 18:25:40.083 [main] : Samsung J55xx Series
INFO 2016-02-07 18:25:40.084 [main] : Samsung JU6400
INFO 2016-02-07 18:25:40.084 [main] : VLC for iOS
INFO 2016-02-07 18:25:40.084 [main] : Xbox One
INFO 2016-02-07 18:25:40.084 [main] : AirPlayer
INFO 2016-02-07 18:25:40.085 [main] : Android
INFO 2016-02-07 18:25:40.085 [main] : AnyCast
INFO 2016-02-07 18:25:40.085 [main] : Apple iPad / iPhone
INFO 2016-02-07 18:25:40.086 [main] : Azur BD
INFO 2016-02-07 18:25:40.086 [main] : BRAVIA KDL-xxNX800
INFO 2016-02-07 18:25:40.086 [main] : D-Link DSM-510
INFO 2016-02-07 18:25:40.087 [main] : DirecTV
INFO 2016-02-07 18:25:40.087 [main] : Freebox
INFO 2016-02-07 18:25:40.087 [main] : Freecom MusicPal
INFO 2016-02-07 18:25:40.088 [main] : Hama IR320
INFO 2016-02-07 18:25:40.088 [main] : KalemSoft Media Player
INFO 2016-02-07 18:25:40.088 [main] : Kodi
INFO 2016-02-07 18:25:40.089 [main] : LG LA6200
INFO 2016-02-07 18:25:40.089 [main] : LG LA644V
INFO 2016-02-07 18:25:40.090 [main] : LG LM620
INFO 2016-02-07 18:25:40.090 [main] : LG Smart TV Upgrader
INFO 2016-02-07 18:25:40.090 [main] : Logitech Squeezebox
INFO 2016-02-07 18:25:40.090 [main] : Miracast M806
INFO 2016-02-07 18:25:40.091 [main] : Netgear NeoTV
INFO 2016-02-07 18:25:40.091 [main] : Netgem N7700
INFO 2016-02-07 18:25:40.091 [main] : Nokia N900
INFO 2016-02-07 18:25:40.092 [main] : Onkyo TX-NR717
INFO 2016-02-07 18:25:40.092 [main] : OPPO BDP
INFO 2016-02-07 18:25:40.092 [main] : OPPO BDP-83
INFO 2016-02-07 18:25:40.092 [main] : Panasonic BDT
INFO 2016-02-07 18:25:40.093 [main] : Panasonic TX-L32V10E
INFO 2016-02-07 18:25:40.093 [main] : Panasonic U30Z
INFO 2016-02-07 18:25:40.093 [main] : Philips Aurea
INFO 2016-02-07 18:25:40.094 [main] : Philips TV
INFO 2016-02-07 18:25:40.094 [main] : Pioneer Kuro
INFO 2016-02-07 18:25:40.095 [main] : PlayStation 3
INFO 2016-02-07 18:25:40.095 [main] : PlayStation 4
INFO 2016-02-07 18:25:40.095 [main] : PlayStation Vita
INFO 2016-02-07 18:25:40.096 [main] : Popcorn Hour
INFO 2016-02-07 18:25:40.096 [main] : Realtek
INFO 2016-02-07 18:25:40.096 [main] : Roku 3 (NSP 3)
INFO 2016-02-07 18:25:40.097 [main] : Roku 3 (NSP 5)
INFO 2016-02-07 18:25:40.097 [main] : Samsung C/D Series
INFO 2016-02-07 18:25:40.097 [main] : Samsung C6600
INFO 2016-02-07 18:25:40.098 [main] : Samsung E+ Series
INFO 2016-02-07 18:25:40.116 [main] : Samsung Galaxy S5
INFO 2016-02-07 18:25:40.117 [main] : Samsung Mobile
INFO 2016-02-07 18:25:40.117 [main] : Samsung SMT-G7400
INFO 2016-02-07 18:25:40.122 [main] : Samsung WiseLink
INFO 2016-02-07 18:25:40.123 [main] : Sharp Aquos
INFO 2016-02-07 18:25:40.123 [main] : Showtime 3
INFO 2016-02-07 18:25:40.123 [main] : Showtime 4
INFO 2016-02-07 18:25:40.123 [main] : Sony Blu-ray
INFO 2016-02-07 18:25:40.124 [main] : Sony Bravia 4500
INFO 2016-02-07 18:25:40.124 [main] : Sony Bravia 5500
INFO 2016-02-07 18:25:40.124 [main] : Sony Bravia BX305
INFO 2016-02-07 18:25:40.125 [main] : Sony Bravia EX
INFO 2016-02-07 18:25:40.125 [main] : Sony Bravia EX620
INFO 2016-02-07 18:25:40.125 [main] : Sony Bravia HX
INFO 2016-02-07 18:25:40.125 [main] : Sony Bravia HX75
INFO 2016-02-07 18:25:40.125 [main] : Sony Bravia NX70x
INFO 2016-02-07 18:25:40.126 [main] : Sony Bravia W
INFO 2016-02-07 18:25:40.126 [main] : Sony Bravia XBR
INFO 2016-02-07 18:25:40.126 [main] : Sony Home Theatre System
INFO 2016-02-07 18:25:40.127 [main] : Sony SMP-N100
INFO 2016-02-07 18:25:40.128 [main] : Sony STR-DA5800ES
INFO 2016-02-07 18:25:40.128 [main] : Sony Xperia Z/ZL/ZQ/Z1/Z2
INFO 2016-02-07 18:25:40.152 [main] : Streamium
INFO 2016-02-07 18:25:40.152 [main] : Technisat S1+
INFO 2016-02-07 18:25:40.152 [main] : Telefunken TV
INFO 2016-02-07 18:25:40.153 [main] : Telstra T-Box
INFO 2016-02-07 18:25:40.153 [main] : Thomson U3 Series
INFO 2016-02-07 18:25:40.153 [main] : Unknown renderer
INFO 2016-02-07 18:25:40.153 [main] : VideoWeb TV
INFO 2016-02-07 18:25:40.154 [main] : Vizio Smart TV
INFO 2016-02-07 18:25:40.154 [main] : WD TV Live
INFO 2016-02-07 18:25:40.154 [main] : WMP
INFO 2016-02-07 18:25:40.154 [main] : XBMC
INFO 2016-02-07 18:25:40.154 [main] : Xbox 360
INFO 2016-02-07 18:25:40.155 [main] : Yamaha R-N500
INFO 2016-02-07 18:25:40.173 [main] : Yamaha RX-A1010
INFO 2016-02-07 18:25:40.173 [main] : Yamaha RX-V3900
INFO 2016-02-07 18:25:40.173 [main] : Yamaha RX-V671
DEBUG 2016-02-07 18:25:40.288 [main] UPNP Services are online, listening for media renderers
DEBUG 2016-02-07 18:25:40.290 [main] Attached http player control handler to web server
INFO 2016-02-07 18:25:40.369 [main] Checking the fontconfig cache in the background, this can take two minutes or so.
DEBUG 2016-02-07 18:25:40.375 [mplayer-0] Starting mplayer dummy
DEBUG 2016-02-07 18:25:40.392 [ffmpeg64-1] Starting /nix/store/21v5q6yrm0wjkjpmvxz7i71ka5mnzn3g-ums-5.4.0/linux/ffmpeg64 -y -f lavfi -i nullsrc=s=720x480:d=1:r=1 -vf ass=DummyInput.ass -target ntsc-dvd -
INFO 2016-02-07 18:25:40.395 [main] Searching for plugins in /nix/store/21v5q6yrm0wjkjpmvxz7i71ka5mnzn3g-ums-5.4.0/plugins
INFO 2016-02-07 18:25:40.398 [main] No plugins found
DEBUG 2016-02-07 18:25:40.439 [ffmpeg64-1-2] ffmpeg version N-51684-gdf2ce13-static http://johnvansickle.com/ffmpeg/ Copyright (c) 2000-2015 the FFmpeg developers
DEBUG 2016-02-07 18:25:40.440 [ffmpeg64-1-2] built with gcc 5.3.0 (Debian 5.3.0-3) 20151204
DEBUG 2016-02-07 18:25:40.440 [ffmpeg64-1-2] configuration: --enable-gpl --enable-version3 --disable-shared --disable-debug --enable-runtime-cpudetect --enable-libmp3lame --enable-libx264 --enable-libx265 --enable-libwebp --enable-libspeex --enable-libvorbis --enable-libvpx --enable-libfreetype --enable-fontconfig --enable-libxvid --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libtheora --enable-libvo-aacenc --enable-libvo-amrwbenc --enable-gray --enable-libopenjpeg --enable-libopus --enable-libass --enable-gnutls --enable-libvidstab --enable-libsoxr --enable-frei0r --enable-libfribidi --disable-indev=sndio --disable-outdev=sndio --cc=gcc
DEBUG 2016-02-07 18:25:40.440 [ffmpeg64-1-2] libavutil 55. 10.100 / 55. 10.100
DEBUG 2016-02-07 18:25:40.440 [ffmpeg64-1-2] libavcodec 57. 17.100 / 57. 17.100
DEBUG 2016-02-07 18:25:40.444 [ffmpeg64-1-2] libavformat 57. 19.100 / 57. 19.100
DEBUG 2016-02-07 18:25:40.444 [ffmpeg64-1-2] libavdevice 57. 0.100 / 57. 0.100
DEBUG 2016-02-07 18:25:40.444 [ffmpeg64-1-2] libavfilter 6. 20.100 / 6. 20.100
DEBUG 2016-02-07 18:25:40.444 [ffmpeg64-1-2] libswscale 4. 0.100 / 4. 0.100
DEBUG 2016-02-07 18:25:40.444 [ffmpeg64-1-2] libswresample 2. 0.101 / 2. 0.101
DEBUG 2016-02-07 18:25:40.448 [ffmpeg64-1-2] libpostproc 54. 0.100 / 54. 0.100
DEBUG 2016-02-07 18:25:40.448 [ffmpeg64-1-2] Input #0, lavfi, from 'nullsrc=s=720x480:d=1:r=1':
DEBUG 2016-02-07 18:25:40.448 [ffmpeg64-1-2] Duration: N/A, start: 0.000000, bitrate: N/A
DEBUG 2016-02-07 18:25:40.448 [ffmpeg64-1-2] Stream #0:0: Video: rawvideo (I420 / 0x30323449), yuv420p, 720x480 [SAR 1:1 DAR 3:2], 1 tbr, 1 tbn, 1 tbc
DEBUG 2016-02-07 18:25:40.452 [ffmpeg64-1-2] [Parsed_ass_0 @ 0x4ac22c0] Shaper: FriBidi 0.19.7 (SIMPLE)
INFO 2016-02-07 18:25:40.456 [main] Registering transcoding engine: FFmpeg Audio
INFO 2016-02-07 18:25:40.494 [main] Registering transcoding engine: MEncoder
INFO 2016-02-07 18:25:40.495 [main] Registering transcoding engine: FFmpeg
INFO 2016-02-07 18:25:40.500 [main] Registering transcoding engine: VLC
TRACE 2016-02-07 18:25:40.537 [mplayer-0] Successfully initialized buffer to 52,428,800 bytes.
DEBUG 2016-02-07 18:25:40.537 [mplayer-0] Attaching thread: mplayer-0
DEBUG 2016-02-07 18:25:40.540 [mplayer-0-2] File not found: 'dummy'
DEBUG 2016-02-07 18:25:40.540 [mplayer-0-2] Failed to open dummy.
TRACE 2016-02-07 18:25:40.543 [ffmpeg64-1] Successfully initialized buffer to 52,428,800 bytes.
DEBUG 2016-02-07 18:25:40.543 [ffmpeg64-1] Attaching thread: ffmpeg64-1
DEBUG 2016-02-07 18:25:40.550 [mplayer-0] Unix process ID (mplayer): 24290
TRACE 2016-02-07 18:25:40.551 [mplayer-0] EOF
DEBUG 2016-02-07 18:25:40.551 [main] Error reading ffmpeg web filters: File '/home/thall/.config/UMS/ffmpeg.webfilters' does not exist
TRACE 2016-02-07 18:25:40.551 [mplayer-0] Ready to Stop: true
DEBUG 2016-02-07 18:25:40.552 [ffmpeg64-1] Unix process ID (/nix/store/21v5q6yrm0wjkjpmvxz7i71ka5mnzn3g-ums-5.4.0/linux/ffmpeg64): 24292
DEBUG 2016-02-07 18:25:40.568 [main] FFmpeg supported protocols: [async, cache, concat, crypto, data, ffrtmphttp, file, ftp, gopher, hls, http, httpproxy, https, mmsh, mmst, pipe, rtmp, rtmps, rtmpt, rtmpts, rtp, sctp, srtp, subfile, tcp, tls, udp, udplite, unix, mms]
INFO 2016-02-07 18:25:40.568 [main] Registering transcoding engine: FFmpeg Web Video
INFO 2016-02-07 18:25:40.569 [main] Registering transcoding engine: MEncoder Web
INFO 2016-02-07 18:25:40.569 [main] Registering transcoding engine: VLC Web Video
INFO 2016-02-07 18:25:40.571 [main] Registering transcoding engine: tsMuxeR
INFO 2016-02-07 18:25:40.572 [main] Registering transcoding engine: Audio High Fidelity
INFO 2016-02-07 18:25:40.572 [main] Registering transcoding engine: VLC Web Audio (Legacy)
INFO 2016-02-07 18:25:40.572 [main] Registering transcoding engine: VLC Web Video (Legacy)
INFO 2016-02-07 18:25:40.574 [main] Registering transcoding engine: dcraw Thumbnailer
DEBUG 2016-02-07 18:25:40.614 [ffmpeg64-1-2] [Parsed_ass_0 @ 0x4ac22c0] Using font provider fontconfig
DEBUG 2016-02-07 18:25:40.614 [ffmpeg64-1-2] [Parsed_ass_0 @ 0x4ac22c0] Added subtitle file: 'DummyInput.ass' (6 styles, 1 events)
DEBUG 2016-02-07 18:25:40.629 [ffmpeg64-1-2] Output #0, dvd, to 'pipe:':
DEBUG 2016-02-07 18:25:40.629 [ffmpeg64-1-2] Metadata:
DEBUG 2016-02-07 18:25:40.629 [ffmpeg64-1-2] encoder : Lavf57.19.100
DEBUG 2016-02-07 18:25:40.629 [ffmpeg64-1-2] Stream #0:0: Video: mpeg2video, yuv420p, 720x480 [SAR 1:1 DAR 3:2], q=2-31, 6000 kb/s, 29.97 fps, 90k tbn, 29.97 tbc
DEBUG 2016-02-07 18:25:40.629 [ffmpeg64-1-2] Metadata:
DEBUG 2016-02-07 18:25:40.629 [ffmpeg64-1-2] encoder : Lavc57.17.100 mpeg2video
DEBUG 2016-02-07 18:25:40.629 [ffmpeg64-1-2] Stream mapping:
DEBUG 2016-02-07 18:25:40.629 [ffmpeg64-1-2] Stream #0:0 -> #0:0 (rawvideo (native) -> mpeg2video (native))
DEBUG 2016-02-07 18:25:40.630 [ffmpeg64-1-2] Press [q] to stop, [?] for help
DEBUG 2016-02-07 18:25:40.637 [ffmpeg64-1-2] frame= 1 fps=0.0 q=1.6 Lsize= 10kB time=00:00:00.03 bitrate=2455.1kbits/s
DEBUG 2016-02-07 18:25:40.637 [ffmpeg64-1-2] video:8kB audio:0kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: 27.094452%
TRACE 2016-02-07 18:25:40.666 [ffmpeg64-1] EOF
TRACE 2016-02-07 18:25:40.666 [ffmpeg64-1] Ready to Stop: true
DEBUG 2016-02-07 18:25:40.675 [pool-2-thread-1] Found chromecast Audio Chromecast
TRACE 2016-02-07 18:25:40.985 [main] default network interface address from [InterfaceAssociation(addr=/192.168.1.109, iface=name:wlp4s0 (wlp4s0), parent=null), InterfaceAssociation(addr=null, iface=name:lo (lo), parent=null)]
TRACE 2016-02-07 18:25:40.986 [main] first network interface: InterfaceAssociation(addr=/192.168.1.109, iface=name:wlp4s0 (wlp4s0), parent=null)
INFO 2016-02-07 18:25:40.986 [main] Using address /192.168.1.109 found on network interface: name:wlp4s0 (wlp4s0)
INFO 2016-02-07 18:25:40.986 [main] Created socket: /192.168.1.109:5001
INFO 2016-02-07 18:25:41.125 [main] WEB interface is available at: http://192.168.1.109:9001
DEBUG 2016-02-07 18:25:41.131 [main] Using database URL: jdbc:h2:/nix/store/21v5q6yrm0wjkjpmvxz7i71ka5mnzn3g-ums-5.4.0/database/medias
INFO 2016-02-07 18:25:41.131 [main] Using database located at: /nix/store/21v5q6yrm0wjkjpmvxz7i71ka5mnzn3g-ums-5.4.0/database
DEBUG 2016-02-07 18:25:42.484 [pool-2-thread-1] Reading MimeTypesChanges: "video/mpeg=video/mp4" (default: "")
DEBUG 2016-02-07 18:25:42.484 [pool-2-thread-1] Reading MediaInfo: true (default: false)
DEBUG 2016-02-07 18:25:42.485 [pool-2-thread-1] Reading RendererName: "Chromecast" (default: "Unknown renderer")
DEBUG 2016-02-07 18:25:42.485 [pool-2-thread-1] Adding status button for Audio Chromecast
DEBUG 2016-02-07 18:25:42.488 [pool-2-thread-1] Reading RendererIcon: "chromecast.png" (default: "unknown.png")
DEBUG 2016-02-07 18:25:42.546 [pool-2-thread-1] Found chromecast Video Chromecast
DEBUG 2016-02-07 18:25:42.665 [main] Cache connection error: Error while creating file "/nix/store/21v5q6yrm0wjkjpmvxz7i71ka5mnzn3g-ums-5.4.0/database" [90062-190]
DEBUG 2016-02-07 18:25:42.671 [pool-2-thread-1] Reading MimeTypesChanges: "video/mpeg=video/mp4" (default: "")
DEBUG 2016-02-07 18:25:42.671 [pool-2-thread-1] Reading MediaInfo: true (default: false)
DEBUG 2016-02-07 18:25:42.671 [pool-2-thread-1] Reading RendererName: "Chromecast" (default: "Unknown renderer")
DEBUG 2016-02-07 18:25:42.671 [pool-2-thread-1] Adding status button for Video Chromecast
DEBUG 2016-02-07 18:25:42.671 [pool-2-thread-1] Reading RendererIcon: "chromecast.png" (default: "unknown.png")
DEBUG 2016-02-07 18:25:42.694 [main] Reading use_cache: false (previous: true, default: true)
TRACE 2016-02-07 18:25:42.694 [main] Adding new child "All Audio Tracks" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.697 [main] Adding new child "All Audio Playlists" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.697 [main] Adding new child "By Artist" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.697 [main] Adding new child "By Album" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.698 [main] Adding new child "By Genre" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.698 [main] Adding new child "By Artist/Album" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.698 [main] Adding new child "By Genre/Artist/Album" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.698 [main] Adding new child "By Date" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.698 [main] Adding new child "By Letter/Artist/Album" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.698 [main] Adding new child "Audio" with class "net.pms.dlna.virtual.VirtualFolder"
TRACE 2016-02-07 18:25:42.699 [main] Adding new child "All Photos" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.699 [main] Adding new child "By Date" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.699 [main] Adding new child "By camera model" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.699 [main] Adding new child "By ISO settings" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.699 [main] Adding new child "Photo" with class "net.pms.dlna.virtual.VirtualFolder"
TRACE 2016-02-07 18:25:42.700 [main] Adding new child "All Videos" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.700 [main] Adding new child "By Date" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.700 [main] Adding new child "HD Videos" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.700 [main] Adding new child "SD Videos" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.700 [main] Adding new child "DVD Images" with class "net.pms.dlna.virtual.MediaLibraryFolder"
TRACE 2016-02-07 18:25:42.700 [main] Adding new child "Video" with class "net.pms.dlna.virtual.VirtualFolder"
INFO 2016-02-07 18:25:42.700 [main] A tiny cache admin interface is available at: http://192.168.1.109:5001/console/home
DEBUG 2016-02-07 18:25:42.853 [main] Sending ALIVE...
TRACE 2016-02-07 18:25:42.853 [main] Sending message from multicast socket on network interface: name:0.0.0.0
TRACE 2016-02-07 18:25:42.853 [main] Multicast socket is on interface: /0.0.0.0
TRACE 2016-02-07 18:25:42.853 [main] Socket Timeout: 0
TRACE 2016-02-07 18:25:42.854 [main] Socket TTL: 32
INFO 2016-02-07 18:25:42.854 [main] Generated new random UUID: b08d6375-1508-4117-b688-94923f297e27
INFO 2016-02-07 18:25:42.857 [main] Configuration saved to: /home/thall/.config/UMS/UMS.conf
INFO 2016-02-07 18:25:42.858 [main] Using the following UUID configured in UMS.conf: b08d6375-1508-4117-b688-94923f297e27
TRACE 2016-02-07 18:25:42.859 [main] Waiting 250 milliseconds...
TRACE 2016-02-07 18:25:42.945 [New I/O worker #1] Ignoring self-originating request from /192.168.1.109:56407
TRACE 2016-02-07 18:25:42.947 [New I/O worker #2] Ignoring self-originating request from /192.168.1.109:56408
DEBUG 2016-02-07 18:25:43.058 [mplayer-0-Cleanup] Stopping Unix process 24290: mplayer-0
TRACE 2016-02-07 18:25:43.058 [mplayer-0-Cleanup] Killing the Unix process: 24290
TRACE 2016-02-07 18:25:43.060 [mplayer-0-Cleanup] Destroying buffer
INFO 2016-02-07 18:25:43.110 [main] The server is now available for renderers to find
TRACE 2016-02-07 18:25:43.111 [UPNPHelper] Setting multicast network interface: name:wlp4s0 (wlp4s0)
DEBUG 2016-02-07 18:25:43.176 [ffmpeg64-1-Cleanup] Stopping Unix process 24292: ffmpeg64-1
TRACE 2016-02-07 18:25:43.176 [ffmpeg64-1-Cleanup] Killing the Unix process: 24292
TRACE 2016-02-07 18:25:43.177 [ffmpeg64-1-Cleanup] Destroying buffer
DEBUG 2016-02-07 18:25:53.111 [UPNP-AliveMessageSender] Sending ALIVE...
TRACE 2016-02-07 18:25:53.120 [New I/O worker #3] Ignoring self-originating request from /192.168.1.109:56409
TRACE 2016-02-07 18:25:53.121 [New I/O worker #4] Ignoring self-originating request from /192.168.1.109:56410
DEBUG 2016-02-07 18:26:13.112 [UPNP-AliveMessageSender] Sending ALIVE...
TRACE 2016-02-07 18:26:13.119 [New I/O worker #1] Ignoring self-originating request from /192.168.1.109:56411
TRACE 2016-02-07 18:26:13.120 [New I/O worker #2] Ignoring self-originating request from /192.168.1.109:56412
INFO 2016-02-07 18:26:24.537 [UPNPHelper] No IP filter specified, access granted to /192.168.1.109
TRACE 2016-02-07 18:26:24.540 [UPNPHelper] Receiving a M-SEARCH from [192.168.1.109:35055]

@Nadahar
Copy link
Contributor

Nadahar commented Feb 7, 2016

@thall
When it comes to previous versions, I think that the database only would have been initalized if use_cache = true and you started to browse from a renderer. Could you try that?

@thall
Copy link
Author

thall commented Feb 7, 2016

@Nadahar , i changed it, everything worked.

...
INFO 18:59:19.630 [main] Using database located at: /nix/store/21v5q6yrm0wjkjpmvxz7i71ka5mnzn3g-ums-5.4.0/database
..

But the database doesn't exists.

[~]$ ls /nix/store/21v5q6yrm0wjkjpmvxz7i71ka5mnzn3g-ums-5.4.0/database
ls: cannot access /nix/store/21v5q6yrm0wjkjpmvxz7i71ka5mnzn3g-ums-5.4.0/database: No such file or directory

Seems like the database isn't used or an exception is ignored.

@Nadahar
Copy link
Contributor

Nadahar commented Feb 7, 2016

I'm pretty sure the exception is ignored and that's what's actually different here. We've reorganized the database initialization because the database is used for more things and initializing it "on demand" was no longer practical. The result is that a hidden bug is revealed.

While it would be simple to just ignore the Exception, that's not a solution as I see it. The problem here is the path used. I'd really like if @skeptical could shed some light on this solution.

@thall
Copy link
Author

thall commented Feb 7, 2016

Alright. Nice then this bug has revealed it self. I agree that wouldn't be an solution.

Will be interesting to hear what @skeptical says.

@Nadahar
Copy link
Contributor

Nadahar commented Feb 10, 2016

@skeptical Do you know why the database file is placed relative to the binary and not in the profile folder on Linux and OS X?

@skeptical
Copy link
Collaborator

@thall In my case the database is relative to my program folder too, but I'm using a 'standalone' installation (i.e. I just untar UMS to a local user-accessible path), and so it naturally has write privileges there. I've never really tried a system install in linux (it doesn't seem necessary to me).

DEBUG 2016-02-10 14:18:46.562 [main] Using database URL: jdbc:h2:/f/git/ums/database/medias
INFO  2016-02-10 14:18:46.562 [main] Using database located at: /f/git/ums/database
DEBUG 2016-02-10 14:18:47.103 [main] Database file count: 705

@Nadahar
Copy link
Contributor

Nadahar commented Feb 11, 2016

@skeptical Still it would make sense if it worked for people that did install it "normally", that is without write permission to binaries. That, and the fact that it will cripple multiple profiles as they will share the database.

My question is if there's any reason to keep it like that, or if we should just move the database to the profile folder where it naturally belongs and write permission can be expected.

@skeptical
Copy link
Collaborator

@Nadahar I've never looked at the linux db logic as such, so I don't know if there was some special reason or whether it's just an oversight or something. I think you've looked into this stuff much deeper than me.

Just for the sake of discussion, per the wiki the "normal" way to do a linux install of UMS is a simple untar. There are a couple of over-thought/wrought user-contrived how-tos for system installation (and pms used to have an unofficial deb package for a while) but java is supposed to be (ideally, in theory anyway :-) 'unpack and run' as I think we discussed once before. But I agree in the real world it's best to support system installs properly, since many will choose to go that route.

@thall
Copy link
Author

thall commented Apr 8, 2016

Is this something that will be prioritized in any foreseeable future?

@SubJunk
Copy link
Member

SubJunk commented Apr 9, 2016

@thall this should be resolved in 1842635 and will be released later today

@SubJunk SubJunk closed this as completed Apr 9, 2016
@SubJunk
Copy link
Member

SubJunk commented Apr 9, 2016

Please let me know if this is resolved in 6.2.2

@thall
Copy link
Author

thall commented Apr 9, 2016

It works now, thanks :) Made an pull request to NixOS/nixpkgs#14555

@SubJunk
Copy link
Member

SubJunk commented Apr 9, 2016

Thanks for confirming, and for that PR :)

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

4 participants