Wow, where do I start. It looks like I have other problems that may be contributing, but after looking through multiple posts and suggestions, I've got just one player configured named Jukebox (all others have been deleted). Max bitrate is No limit, Player has a dynamic IP address and Control player automatically are both checked. My library is all flac so I have tested with mp3 audio active transcoding both checked and unchecked. And I still get no output when playing in jukebox mode.
My specs:
Playback Application and version: I'm just using the Subsonic web interface that displays when you log onto the server from a remote workstation.
Subsonic Version: 6.1.2 (build 09b0ab) – October 22, 2017
Server Version: jetty-6.1.x, java 1.8.0_152, HSQLDB, Linux (141.9 MB / 352.5 MB) (Java is Oracle, not openjdk)
Hardware Platform: Linux Ubuntu 16.04 LTS, completely up to date. Server configuration - headless
Java Memory Limit: I have added --max-memory=768 in my /etc/default/subsonic file
Output from ffmpeg -i:
ffmpeg -i /var/www/html/music/Led\ Zeppelin/1976-The\ Song\ Remains\ The\ Same/01-Rock\ And\ Roll\ \[Live\].flac
- Code: Select all
ffmpeg version 2.8.11-0ubuntu0.16.04.1 Copyright (c) 2000-2017 the FFmpeg developers
built with gcc 5.4.0 (Ubuntu 5.4.0-6ubuntu1~16.04.4) 20160609
configuration: --prefix=/usr --extra-version=0ubuntu0.16.04.1 --build-suffix=-ffmpeg --toolchain=hardened --libdir=/usr/lib/x86_64-linux-gnu --incdir=/usr/include/x86_64-linux-gnu --cc=cc --cxx=g++ --enable-gpl --enable-shared --disable-stripping --disable-decoder=libopenjpeg --disable-decoder=libschroedinger --enable-avresample --enable-avisynth --enable-gnutls --enable-ladspa --enable-libass --enable-libbluray --enable-libbs2b --enable-libcaca --enable-libcdio --enable-libflite --enable-libfontconfig --enable-libfreetype --enable-libfribidi --enable-libgme --enable-libgsm --enable-libmodplug --enable-libmp3lame --enable-libopenjpeg --enable-libopus --enable-libpulse --enable-librtmp --enable-libschroedinger --enable-libshine --enable-libsnappy --enable-libsoxr --enable-libspeex --enable-libssh --enable-libtheora --enable-libtwolame --enable-libvorbis --enable-libvpx --enable-libwavpack --enable-libwebp --enable-libx265 --enable-libxvid --enable-libzvbi --enable-openal --enable-opengl --enable-x11grab --enable-libdc1394 --enable-libiec61883 --enable-libzmq --enable-frei0r --enable-libx264 --enable-libopencv
libavutil 54. 31.100 / 54. 31.100
libavcodec 56. 60.100 / 56. 60.100
libavformat 56. 40.101 / 56. 40.101
libavdevice 56. 4.100 / 56. 4.100
libavfilter 5. 40.101 / 5. 40.101
libavresample 2. 1. 0 / 2. 1. 0
libswscale 3. 1.101 / 3. 1.101
libswresample 1. 2.101 / 1. 2.101
libpostproc 53. 3.100 / 53. 3.100
Input #0, flac, from '/var/www/html/music/Led Zeppelin/1976-The Song Remains The Same/01-Rock And Roll [Live].flac':
Metadata:
TRACKTOTAL : 15
TITLE : Rock And Roll [Live];Rock And Roll [Live]
ARTIST : Led Zeppelin;Led Zeppelin
disc : 01
DATE : 1976;1976
ALBUM : The Song Remains The Same;The Song Remains The Same
track : 01
GENRE : Rock;Rock
DISCID : 890e2e0a
Duration: 00:03:56.35, start: 0.000000, bitrate: 1032 kb/s
Stream #0:0: Audio: flac, 44100 Hz, stereo, s16
At least one output file must be specified
Troubleshooting Steps:
aplay -L
- Code: Select all
iec958:CARD=Intel,DEV=0
HDA Intel, STAC9271X Digital
IEC958 (S/PDIF) Digital Audio Output
speaker-test -Diec958:Intel -c 2 -l 2 cycles through the front speakers
lspci -s 00:1b.0 -v
- Code: Select all
00:1b.0 Audio device: Intel Corporation 82801H (ICH8 Family) HD Audio Controller (rev 02)
Subsystem: Gateway, Inc. 82801H (ICH8 Family) HD Audio Controller
Flags: bus master, fast devsel, latency 0, IRQ 34
Memory at e2320000 (64-bit, non-prefetchable) [size=16K]
Capabilities: [50] Power Management version 2
Capabilities: [60] MSI: Enable+ Count=1/1 Maskable- 64bit+
Capabilities: [70] Express Root Complex Integrated Endpoint, MSI 00
Capabilities: [100] Virtual Channel
Capabilities: [130] Root Complex Link
Kernel driver in use: snd_hda_intel
Kernel modules: snd_hda_intel
Following some hints in post: http://forum.subsonic.org/forum/viewtopic.php?f=2&t=2038&start=30, I compiled and ran the audioDevList applet and those results are
- Code: Select all
Available mixers:
default [default]
Intel [plughw:0,0]
Intel [plughw:0,1]
Intel [plughw:0,2]
Port Intel [hw:0]
I can run cmus directly on the server and play flac files through the stereo. When trying to cycle through the available mixers that are listed from the audioDevList applet (when added to the /usr/share/subsonic/subsonic.sh script) none of the options results in sound output when trying to play.
The section of code from the subsonic.sh script (of course it's still set to the last one from the audioDevList list of output mixers...)
- Code: Select all
${JAVA} -Xmx${SUBSONIC_MAX_MEMORY}m \
-Dsubsonic.home=${SUBSONIC_HOME} \
-Dsubsonic.host=${SUBSONIC_HOST} \
-Dsubsonic.port=${SUBSONIC_PORT} \
-Dsubsonic.httpsPort=${SUBSONIC_HTTPS_PORT} \
-Dsubsonic.contextPath=${SUBSONIC_CONTEXT_PATH} \
-Dsubsonic.db="${SUBSONIC_DB}" \
-Dsubsonic.defaultMusicFolder=${SUBSONIC_DEFAULT_MUSIC_FOLDER} \
-Dsubsonic.defaultPodcastFolder=${SUBSONIC_DEFAULT_PODCAST_FOLDER} \
-Dsubsonic.defaultPlaylistFolder=${SUBSONIC_DEFAULT_PLAYLIST_FOLDER} \
-Djava.awt.headless=true \
'-Djavax.sound.sampled.SourceDataLine=#Port Intel [hw:0]' \
-verbose:gc \
-jar subsonic-booter-jar-with-dependencies.jar > ${LOG} 2>&1 &
Last ten log file lines:
- Code: Select all
[10/29/17 1:53:28 AM EDT] INFO HsqlDaoHelper Checking database schema.
[10/29/17 1:53:30 AM EDT] INFO HsqlDaoHelper Done checking database schema.
[10/29/17 1:53:30 AM EDT] INFO SettingsService Java: 1.8.0_152, OS: Linux
[10/29/17 1:53:30 AM EDT] INFO VersionService Resolved local Subsonic version to: 6.1.2
[10/29/17 1:53:31 AM EDT] INFO MediaScannerService Automatic media library scanning scheduled to run every 1 day(s), starting at Sun Oct 29 03:00:00 EDT 2017
[10/29/17 1:53:31 AM EDT] INFO UPnPService Starting UPnP service...
[10/29/17 1:53:31 AM EDT] INFO PodcastService Automatic Podcast update scheduled to run every 24 hour(s), starting at Sun Oct 29 01:58:31 EDT 2017
[10/29/17 1:53:32 AM EDT] INFO UPnPService Enabling UPnP/DLNA media server
[10/29/17 1:53:32 AM EDT] INFO UPnPService Starting UPnP service - Done!
[10/29/17 1:53:38 AM EDT] INFO BootstrapVerificationFilter Servlet container: jetty-6.1.x
[10/29/17 1:53:41 AM EDT] WARN NetworkService No UPnP router found.
[10/29/17 1:53:50 AM EDT] INFO VersionService Resolved latest Subsonic final version to: 6.1.2
[10/29/17 1:53:50 AM EDT] INFO VersionService Resolved latest Subsonic beta version to: 6.1.beta2
[10/29/17 1:54:13 AM EDT] INFO JukeboxService osh starting jukebox for "2005-Under Cover/02-In My Life.flac"
[10/29/17 1:56:11 AM EDT] INFO JukeboxService osh stopping jukebox for "2005-Under Cover/02-In My Life.flac"
[10/29/17 1:56:11 AM EDT] INFO JukeboxService osh starting jukebox for "Led Zeppelin III/01-Immigrant Song.mp3"
[10/29/17 1:58:31 AM EDT] INFO PodcastService Starting scheduled Podcast refresh.
[10/29/17 1:58:31 AM EDT] INFO PodcastService Completed scheduled Podcast refresh.
[10/29/17 1:58:36 AM EDT] INFO JukeboxService osh stopping jukebox for "Led Zeppelin III/01-Immigrant Song.mp3"
[10/29/17 1:58:36 AM EDT] INFO JukeboxService osh starting jukebox for "Led Zeppelin III/02-Friends.mp3"
[10/29/17 2:02:29 AM EDT] INFO JukeboxService osh stopping jukebox for "Led Zeppelin III/02-Friends.mp3"
[10/29/17 2:02:29 AM EDT] INFO JukeboxService osh starting jukebox for "Led Zeppelin III/03-Celebration Day.mp3"
[10/29/17 2:05:58 AM EDT] INFO JukeboxService osh stopping jukebox for "Led Zeppelin III/03-Celebration Day.mp3"
[10/29/17 2:05:58 AM EDT] INFO JukeboxService osh starting jukebox for "Led Zeppelin III/04-Since I've Been Loving You.mp3"
[10/29/17 2:13:02 AM EDT] INFO JukeboxService osh stopping jukebox for "Led Zeppelin III/04-Since I've Been Loving You.mp3"
[10/29/17 2:13:02 AM EDT] INFO JukeboxService osh starting jukebox for "2011-Reanimate- The Covers EP/01-Slave To The Grind (Skid Row cover).mp3"
[10/29/17 2:16:33 AM EDT] INFO JukeboxService osh stopping jukebox for "2011-Reanimate- The Covers EP/01-Slave To The Grind (Skid Row cover).mp3"
[10/29/17 2:16:33 AM EDT] INFO JukeboxService osh starting jukebox for "2011-Reanimate- The Covers EP/02-Bad Romance (Lady Gaga cover).mp3"
[10/29/17 2:20:42 AM EDT] INFO JukeboxService osh stopping jukebox for "2011-Reanimate- The Covers EP/02-Bad Romance (Lady Gaga cover).mp3"
[10/29/17 2:20:42 AM EDT] INFO JukeboxService osh starting jukebox for "2011-Reanimate- The Covers EP/03-Hunger Strike (Temple Of The Dog cover).mp3"
Logs:
subsonic.log
- Code: Select all
[2017-10-29 01:53:28,847] INFO HsqlDaoHelper - Checking database schema.
[2017-10-29 01:53:30,712] INFO HsqlDaoHelper - Done checking database schema.
[2017-10-29 01:53:30,878] INFO SettingsService - Java: 1.8.0_152, OS: Linux
[2017-10-29 01:53:30,902] INFO VersionService - Resolved local Subsonic version to: 6.1.2
[2017-10-29 01:53:31,520] INFO MediaScannerService - Automatic media library scanning scheduled to run every 1 day(s), starting at Sun Oct 29 03:00:00 EDT 2017
[2017-10-29 01:53:31,703] INFO UPnPService - Starting UPnP service...
[2017-10-29 01:53:31,962] INFO PodcastService - Automatic Podcast update scheduled to run every 24 hour(s), starting at Sun Oct 29 01:58:31 EDT 2017
[2017-10-29 01:53:32,588] INFO UPnPService - Enabling UPnP/DLNA media server
[2017-10-29 01:53:32,589] INFO UPnPService - Starting UPnP service - Done!
[2017-10-29 01:53:38,328] INFO BootstrapVerificationFilter - Servlet container: jetty-6.1.x
[2017-10-29 01:53:41,722] WARN NetworkService - No UPnP router found.
[2017-10-29 01:53:50,657] INFO VersionService - Resolved latest Subsonic final version to: 6.1.2
[2017-10-29 01:53:50,658] INFO VersionService - Resolved latest Subsonic beta version to: 6.1.beta2
[2017-10-29 01:54:13,393] INFO JukeboxService - osh starting jukebox for "2005-Under Cover/02-In My Life.flac"
[2017-10-29 01:56:11,735] INFO JukeboxService - osh stopping jukebox for "2005-Under Cover/02-In My Life.flac"
[2017-10-29 01:56:11,759] INFO JukeboxService - osh starting jukebox for "Led Zeppelin III/01-Immigrant Song.mp3"
[2017-10-29 01:58:31,959] INFO PodcastService - Starting scheduled Podcast refresh.
[2017-10-29 01:58:31,960] INFO PodcastService - Completed scheduled Podcast refresh.
[2017-10-29 01:58:36,229] INFO JukeboxService - osh stopping jukebox for "Led Zeppelin III/01-Immigrant Song.mp3"
subsonic_sh.log
- Code: Select all
10395 [main] INFO org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'subsonic': initialization started
10396 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@74ecacc3: display name [WebApplicationContext for namespace 'subsonic-servlet']; startup date [Sun Oct 29 01:53:36 EDT 2017]; parent: org.springframework.web.context.support.XmlWebApplicationContext@2d1ef81a
10396 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/subsonic-servlet.xml]
10461 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@74ecacc3]: org.springframework.beans.factory.support.DefaultListableBeanFactory@1687eb01
10552 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@1687eb01: defining beans [leftController,artistsController,rightController,statusController,mainController,playlistController,playlistsController,bookmarksController,importPlaylistController,topController,helpController,moreController,uploadController,lyricsController,allmusicController,podcastController,podcastChannelsController,internetRadioController,internetRadioStreamController,podcastChannelController,podcastReceiverAdminController,setMusicFileInfoController,shareManagementController,setRatingController,randomPlayQueueController,changeCoverArtController,nowPlayingController,starredController,searchController,settingsController,playerSettingsController,dlnaSettingsController,sonosSettingsController,shareSettingsController,musicFolderSettingsController,networkSettingsController,transcodingSettingsController,internetRadioSettingsController,podcastSettingsController,videoConversionSettingsController,generalSettingsController,advancedSettingsController,personalSettingsController,avatarUploadController,userSettingsController,passwordSettingsController,homeController,editTagsController,playQueueController,coverArtController,artistImageController,captionsController,avatarController,statusChartController,userChartController,m3uController,streamController,hlsController,segmentController,videoPlayerController,videoConverterController,externalPlayerController,downloadController,premiumSettingsController,multiController,wapController,restController,dbController,premiumSettingsValidator,userSettingsValidator,passwordSettingsValidator,urlMapping,messageSource,themeSource,themeResolver,viewResolver]; parent: org.springframework.beans.factory.support.DefaultListableBeanFactory@18df8434
[GC (Allocation Failure) 174977K->57496K(285184K), 0.0391242 secs]
11685 [main] INFO org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'subsonic': initialization completed in 1290 ms
11702 [main] INFO org.mortbay.log - Started SelectChannelConnector@0.0.0.0:4040
Subsonic running on: http://localhost:4040/
11751 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Looking for RMI registry at port '9412'
11764 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Could not detect RMI registry - creating new one
11803 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Binding service 'SubsonicDeployerService' to RMI registry: RegistryImpl[UnicastServerRef [liveRef: [endpoint:[127.0.1.1:9412](local),objID:[0:0:0, 0]]]]
[GC (Allocation Failure) 188056K->58194K(279040K), 0.0464027 secs]
24678 [btpool0-7] INFO org.directwebremoting.log.startup - Starting: DwrServlet v3.0.0-FINAL on jetty-6.1.x / JDK 1.8.0_152 from Oracle Corporation at
25266 [btpool0-7] INFO org.directwebremoting.log.startup - Starting: Using container abstraction org.directwebremoting.server.servlet2.Servlet24ContainerAbstraction
25282 [btpool0-7] INFO org.directwebremoting.servlet.UrlProcessor - Probably not an issue: the url:/gi.js (org.directwebremoting.gi.GiHandler) Handler is not available. This is only an problem if you wanted to use it.
[GC (Allocation Failure) 210770K->72980K(302592K), 0.0916998 secs]
[GC (Metadata GC Threshold) 139579K->72453K(322048K), 0.0819020 secs]
[Full GC (Metadata GC Threshold) 72453K->66575K(360960K), 0.4322244 secs]
[GC (Allocation Failure) 237071K->68427K(363520K), 0.0093998 secs]
(Here's a good place to ask, what do the [GC (Allocation Failure) ...] and [Full GC (Metadata GC Threshold) ...] errors mean? How do I resolve them?)