search

PeteManchester / MediaPlayer

61 stars 20 forks source link

Web GUI / Player Status: missing track / album / artist / albumart... #63

Closed fupps closed 4 years ago

fupps commented 8 years ago

Hello, I have successfully installed your great software on my pi 2 / hifiberry combo (using raspbian jessie lite). Works and sounds great so far. But something is wrong with the web gui (or with my installation). It shows the correct time of each track that is played, but nothing more on the status page. No track name or album name or artist or cover. Do you have any idea what I could have done wrong? I tried PlugPlayer, Kazoo, Kinski and others in combination with twonky server, plex dlna, minimserver but that did not change anything. (All combinations work except the missing data in the web gui)

Thanks a lot Sebastian

PeteManchester commented 8 years ago

Hi Sebastian,

Strange, I just tested and it is ok for me..

What type/version of browser are you using? I've tested with IE and Chrome.

fupps commented 8 years ago

I tested with Safari and Chrome on my mac and firefox and IE on windows. All same result. I used the developer tools in chrome to set a breakpoint in PlayerStatus.js in the beginning of function setDisplay(text) . the JSON string is not filled at all: "{ ↵"details": " " ↵,"album_title": "" ↵,"artist": "" ↵,"album_artist": "" ↵,"title": "" ↵,"image_uri": "" ↵,"track_duration": "0" ↵,"genre": "" ↵,"time_played": "39" ↵,"lyrics": "Cannot+Find+Lyrics" ↵,"artist_biography": "" ↵}"

Just the 'time_played' counts up.

fupps commented 8 years ago

If the JSON string is empty that seems to mean that in PlayerStatus.java the update() is not called for 'EVENTTRACKCHANGED' or the track is null. There is some empty else case.. Is there something missing? As the time_played is working the event 'EVENTTIMEUPDATED' seems to work.

PeteManchester commented 8 years ago

Which version of MediaPlayer are you using?

Can you set the log level to debug and then add a track to the playlist.

When adding a track to the playlist you should see this log entry

2016-03-17 07:56:01,959 [Thread-10] DEBUG [org.rpi.channel.ChannelPlayList] Digital (Live)/dc:titleAlternative/upnp:genre1980-01-01/dc:dateStill/upnp:albumJoy Division/upnp:artistJoy Division/upnp:artistJoy Division/dc:creator20/upnp:originalTrackNumberhttp://192.168.1.205:9790/minimserver/*/Music/Joy*20Division/Still/1-20-Digital*20(Live).flac/$!picture-305-214207.jpg/upnp:albumArtURIhttp://192.168.1.205:9790/minimserver/*/Music/Joy*20Division/Still/1-20-Digital*20(Live).flacobject.item.audioItem.musicTrack/upnp:class

Then if you open the PlayerStatus page in your browser and start playing the track. You should see something like this in the log..

2016-03-17 08:00:39,468 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] http://lyrics.wikia.com/Joy_Division:Digital_(Live) 2016-03-17 08:00:44,038 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] ### Found Lyrics. URL: http://lyrics.wikia.com/Joy_Division:Digital_(Live) 2016-03-17 08:00:44,040 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] TrackChanged: 'Joy Division' 'Digital (Live)'

And the JSON I got at my Chrome Browser was:

{ "details": "Joy+Division Digital+%28Live%29 1980-01-01" ,"album_title": "Still" ,"artist": "Joy+Division" ,"album_artist": "Joy+Division" ,"title": "Digital+%28Live%29" ,"image_uri": "http://192.168.1.205:9790/minimserver/*/Music/Joy*20Division/Still/1-20-Digital*20(Live).flac/$!picture-305-214207.jpg" ,"track_duration": "234" ,"genre": "" ,"time_played": "73" ,"lyrics": "Feel+it+closing+in%2C+feel+it+closing+in%0D%0AThe+fear+of+whom+I+call%2C+every+time+I+call%0D%0AI+feel+it+closing+in%2C+I+feel+it+closing+in%0D%0ADay+in%2C+day+out%2C+day+in%2C+day+out%0D%0ADay+in%2C+day+out%2C+day+in%2C+day+out%0D%0ADay+in%2C+day+out%2C+day+in%2C+day+out%0D%0A%0D%0AI+feel+it+closing+in%2C+as+patterns+seem+to+form+%0D%0AI+feel+it+cold+and+warm%2C+the+shadows+start+to+fall%0D%0AI+feel+it+closing+in%2C+I+feel+it+closing+in%0D%0ADay+in%2C+day+out%2C+day+in%2C+day+out%0D%0ADay+in%2C+day+out%2C+day+in%2C+day+out%0D%0ADay+in%2C+day+out%0D%0A%0D%0AI%27d+have+the+world+to+see+just+what+happens%0D%0AStood+by+the+door+alone%2C+and+then+it%27s+fade+away%0D%0AI+see+you+fade+away%2C+don%27t+ever+fade+away%0D%0AI+need+you+here+today%2C+don%27t+ever+fade+away%0D%0ADon%27t+ever+fade+away%2C+don%27t+ever+fade+away%0D%0ADon%27t+ever+fade+away%2C+fade+away%0D%0AFade+away%2C+fade+away%0D%0AFade+away%2C+fade+away%0D%0AFade+away%2C+fade+away%0A%0A" ,"artist_biography": "Joy+Division+were+an+English+rock+band+formed+in+1976+in+Salford%2C+Greater+Manchester.+..." }

The empty else statement in the PlayerStatus.java is for if the Track in the EVENTTRACKCHANGED notification was NULL, if it's NULL then I can't do anything with it.

In your case I'm not yet sure what is happening, either the EVENTRACKCHANGED event is not being fired for some reason, or it is something very unlikely like your metadata is a completely different format (which I doubt)..

If you can send me the logs from your testing then I can have a quick look..

private PlayerStatus() {
    PlayManager.getInstance().observeInfoEvents(this);
    PlayManager.getInstance().observeProductEvents(this);
    PlayManager.getInstance().observeTimeEvents(this);
}

@Override
public void update(Observable o, Object evt) {
    EventBase base = (EventBase) evt;
    switch (base.getType()) {
    case EVENTTRACKCHANGED:
        EventTrackChanged etc = (EventTrackChanged) evt;
        ChannelBase track = etc.getTrack();
        if (track != null) {
            album_title = track.getAlbum();
            artist = track.getArtist();
            performer  = track.getPerformer();
            album_artist = track.getAlbumArtist();
            title = track.getTitle();
            image_uri = track.getAlbumArtUri();
            track_duration = track.getDuration();
            if(track_duration > 0)
            {
                track_duration = track_duration/1000;
            }
            genre = track.getGenre();
        } else {

        }

        break;
    case EVENTUPDATETRACKMETATEXT:
        EventUpdateTrackMetaText etm = (EventUpdateTrackMetaText) evt;
        title = etm.getTitle();
        album_artist = etm.getArtist();
        break;
    case EVENTTIMEUPDATED:
        EventTimeUpdate ed = (EventTimeUpdate) evt;
        time_played = ed.getTime();
        break;
    }

}
fupps commented 8 years ago

Well I found something. When I play a song with Kazoo it uses a playlist and the info in the Web gui is missing for me. If I use Kinsky instead and I use 'UPNP' as Source instead of 'Playlist' it works. Here my log playing a song with Kazoo (Playlist):

Friendly Name : PiBerry Version : 0.0.8.8 Host name : raspberrypi StartTime : Fri Mar 18 17:18:17 CET 2016 LocalTime : Fri Mar 18 17:18:17 CET 2016 DST : Offset = 1 Timezone : Europe/Berlin, Central European Time, Central European Summer Time

2016-03-18 17:18:17,619 [main] INFO [org.rpi.config.Config] Logging Configured 2016-03-18 17:18:17,623 [main] FATAL [org.rpi.config.Config] ###Start of app.properties### 2016-03-18 17:18:17,625 [main] FATAL [org.rpi.config.Config] 'mplayer_cache_min' : '80' 2016-03-18 17:18:17,626 [main] FATAL [org.rpi.config.Config] 'mplayer_playlist' : 'asx,b4s,kpl,m3u,pls,ram,rm,smil,wax,wvx' 2016-03-18 17:18:17,626 [main] FATAL [org.rpi.config.Config] 'airplay_latency_enabled' : 'true' 2016-03-18 17:18:17,627 [main] FATAL [org.rpi.config.Config] 'mplayer_path' : '/usr/bin/mplayer' 2016-03-18 17:18:17,627 [main] FATAL [org.rpi.config.Config] 'mediaplayer_enable_receiver' : 'true' 2016-03-18 17:18:17,628 [main] FATAL [org.rpi.config.Config] 'mplayer_cache_size' : '520' 2016-03-18 17:18:17,628 [main] FATAL [org.rpi.config.Config] 'java_sound_software_mixer_enabled' : 'false' 2016-03-18 17:18:17,628 [main] FATAL [org.rpi.config.Config] 'mediaplayer_player' : 'mpd' 2016-03-18 17:18:17,629 [main] FATAL [org.rpi.config.Config] 'airplay_enabled' : 'true' 2016-03-18 17:18:17,629 [main] FATAL [org.rpi.config.Config] 'mediaplayer_playlist_max' : '1000' 2016-03-18 17:18:17,630 [main] FATAL [org.rpi.config.Config] 'log_file_name' : 'mediaplayer.log' 2016-03-18 17:18:17,630 [main] FATAL [org.rpi.config.Config] 'mpd_port' : '6600' 2016-03-18 17:18:17,630 [main] FATAL [org.rpi.config.Config] 'web_server_enabled' : 'true' 2016-03-18 17:18:17,631 [main] FATAL [org.rpi.config.Config] 'log_console_level' : 'off' 2016-03-18 17:18:17,631 [main] FATAL [org.rpi.config.Config] 'airplay_port' : '5002' 2016-03-18 17:18:17,632 [main] FATAL [org.rpi.config.Config] 'web_server_port' : '80' 2016-03-18 17:18:17,632 [main] FATAL [org.rpi.config.Config] 'airplay_master_volume_enabled' : 'false' 2016-03-18 17:18:17,632 [main] FATAL [org.rpi.config.Config] 'mpd_preload_timer' : '3' 2016-03-18 17:18:17,633 [main] FATAL [org.rpi.config.Config] 'mpd_host' : 'localhost' 2016-03-18 17:18:17,633 [main] FATAL [org.rpi.config.Config] 'airplay_audio_start_delay' : 'false' 2016-03-18 17:18:17,633 [main] FATAL [org.rpi.config.Config] 'mediaplayer_friendly_name' : 'PiBerry' 2016-03-18 17:18:17,633 [main] FATAL [org.rpi.config.Config] 'mediaplayer_save_local_playlist' : 'true' 2016-03-18 17:18:17,634 [main] FATAL [org.rpi.config.Config] 'openhome_port' : '52821' 2016-03-18 17:18:17,634 [main] FATAL [org.rpi.config.Config] 'log_file_level' : 'debug' 2016-03-18 17:18:17,634 [main] FATAL [org.rpi.config.Config] 'songcast_latency_enabled' : 'false' 2016-03-18 17:18:17,635 [main] FATAL [org.rpi.config.Config] 'mediaplayer_max_volume' : '100' 2016-03-18 17:18:17,635 [main] FATAL [org.rpi.config.Config] 'mediaplayer_startup_volume' : '-1' 2016-03-18 17:18:17,635 [main] FATAL [org.rpi.config.Config] 'openhome_log_level' : 'Error' 2016-03-18 17:18:17,636 [main] FATAL [org.rpi.config.Config] 'mediaplayer_enable_avTransport' : 'true' 2016-03-18 17:18:17,636 [main] FATAL [org.rpi.config.Config] 'java_soundcard_suffix' : '[PLUGHW:0,0]--PRIMARY SOUND DRIVER' 2016-03-18 17:18:17,636 [main] FATAL [org.rpi.config.Config] ###End of app.properties#### 2016-03-18 17:18:17,637 [main] INFO [org.rpi.main.StartMe] Starting...... 2016-03-18 17:18:17,637 [main] INFO [org.rpi.main.StartMe] Getting Network Interfaces 2016-03-18 17:18:17,644 [main] INFO [org.rpi.main.StartMe] Network Interface Display Name: 'wlan0' 2016-03-18 17:18:17,645 [main] INFO [org.rpi.main.StartMe] NIC Name: 'wlan0' 2016-03-18 17:18:17,645 [main] INFO [org.rpi.main.StartMe] IPAddress for Network Interface: wlan0 : fe80:0:0:0:36ab:ebda:6d8d:5b44%3 2016-03-18 17:18:17,646 [main] INFO [org.rpi.main.StartMe] Network Interface Display Name: 'eth0' 2016-03-18 17:18:17,646 [main] INFO [org.rpi.main.StartMe] NIC Name: 'eth0' 2016-03-18 17:18:17,646 [main] INFO [org.rpi.main.StartMe] IPAddress for Network Interface: eth0 : 2003:71:e1d:4748:8e2:31a3:a347:bfce%2 2016-03-18 17:18:17,647 [main] INFO [org.rpi.main.StartMe] IPAddress for Network Interface: eth0 : fe80:0:0:0:2f5d:ba48:cc03:a2c%2 2016-03-18 17:18:17,647 [main] INFO [org.rpi.main.StartMe] IPAddress for Network Interface: eth0 : 192.168.2.121 2016-03-18 17:18:17,647 [main] INFO [org.rpi.main.StartMe] Network Interface Display Name: 'lo' 2016-03-18 17:18:17,648 [main] INFO [org.rpi.main.StartMe] NIC Name: 'lo' 2016-03-18 17:18:17,648 [main] INFO [org.rpi.main.StartMe] IPAddress for Network Interface: lo : 0:0:0:0:0:0:0:1%1 2016-03-18 17:18:17,648 [main] INFO [org.rpi.main.StartMe] IPAddress for Network Interface: lo : 127.0.0.1 2016-03-18 17:18:17,648 [main] INFO [org.rpi.main.StartMe] End Of Network Interfaces 2016-03-18 17:18:17,649 [main] INFO [org.rpi.main.StartMe] Available Audio Devices: 2016-03-18 17:18:17,717 [main] INFO [org.rpi.main.StartMe] 'sndrpihifiberry [default]' 2016-03-18 17:18:17,719 [main] INFO [org.rpi.main.StartMe] 'sndrpihifiberry [plughw:0,0]' 2016-03-18 17:18:17,729 [main] INFO [org.rpi.main.StartMe] 'Port sndrpihifiberry [hw:0]' 2016-03-18 17:18:17,730 [main] INFO [org.rpi.main.StartMe] End Of Audio Devices 2016-03-18 17:18:17,730 [main] FATAL [org.rpi.main.StartMe] ###Setting Sound Card Name: #sndrpihifiberry [plughw:0,0] 2016-03-18 17:18:17,730 [main] INFO [org.rpi.main.StartMe] JVM Version: 1.7.0_60 2016-03-18 17:18:17,731 [main] FATAL [org.rpi.main.StartMe] #####Start of System Properties######### 2016-03-18 17:18:17,736 [main] FATAL [org.rpi.main.StartMe] awt.toolkit=sun.awt.X11.XToolkit 2016-03-18 17:18:17,736 [main] FATAL [org.rpi.main.StartMe] file.encoding=UTF-8 2016-03-18 17:18:17,737 [main] FATAL [org.rpi.main.StartMe] file.encoding.pkg=sun.io 2016-03-18 17:18:17,737 [main] FATAL [org.rpi.main.StartMe] file.separator=/ 2016-03-18 17:18:17,737 [main] FATAL [org.rpi.main.StartMe] java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment 2016-03-18 17:18:17,737 [main] FATAL [org.rpi.main.StartMe] java.awt.printerjob=sun.print.PSPrinterJob 2016-03-18 17:18:17,738 [main] FATAL [org.rpi.main.StartMe] java.class.path=/opt/mediaplayer/mediaplayer.jar 2016-03-18 17:18:17,738 [main] FATAL [org.rpi.main.StartMe] java.class.version=51.0 2016-03-18 17:18:17,738 [main] FATAL [org.rpi.main.StartMe] java.endorsed.dirs=/usr/lib/jvm/jdk-7-oracle-arm-vfp-hflt/jre/lib/endorsed 2016-03-18 17:18:17,738 [main] FATAL [org.rpi.main.StartMe] java.ext.dirs=/usr/lib/jvm/jdk-7-oracle-arm-vfp-hflt/jre/lib/ext:/usr/java/packages/lib/ext 2016-03-18 17:18:17,739 [main] FATAL [org.rpi.main.StartMe] java.home=/usr/lib/jvm/jdk-7-oracle-arm-vfp-hflt/jre 2016-03-18 17:18:17,739 [main] FATAL [org.rpi.main.StartMe] java.io.tmpdir=/tmp 2016-03-18 17:18:17,739 [main] FATAL [org.rpi.main.StartMe] java.library.path=/usr/java/packages/lib/arm:/lib:/usr/lib 2016-03-18 17:18:17,740 [main] FATAL [org.rpi.main.StartMe] java.runtime.name=Java(TM) SE Runtime Environment 2016-03-18 17:18:17,740 [main] FATAL [org.rpi.main.StartMe] java.runtime.version=1.7.0_60-b19 2016-03-18 17:18:17,771 [main] FATAL [org.rpi.main.StartMe] java.specification.name=Java Platform API Specification 2016-03-18 17:18:17,771 [main] FATAL [org.rpi.main.StartMe] java.specification.vendor=Oracle Corporation 2016-03-18 17:18:17,771 [main] FATAL [org.rpi.main.StartMe] java.specification.version=1.7 2016-03-18 17:18:17,772 [main] FATAL [org.rpi.main.StartMe] java.vendor=Oracle Corporation 2016-03-18 17:18:17,772 [main] FATAL [org.rpi.main.StartMe] java.vendor.url=http://java.oracle.com/ 2016-03-18 17:18:17,772 [main] FATAL [org.rpi.main.StartMe] java.vendor.url.bug=http://bugreport.sun.com/bugreport/ 2016-03-18 17:18:17,772 [main] FATAL [org.rpi.main.StartMe] java.version=1.7.0_60 2016-03-18 17:18:17,772 [main] FATAL [org.rpi.main.StartMe] java.vm.info=mixed mode 2016-03-18 17:18:17,773 [main] FATAL [org.rpi.main.StartMe] java.vm.name=Java HotSpot(TM) Client VM 2016-03-18 17:18:17,773 [main] FATAL [org.rpi.main.StartMe] java.vm.specification.name=Java Virtual Machine Specification 2016-03-18 17:18:17,773 [main] FATAL [org.rpi.main.StartMe] java.vm.specification.vendor=Oracle Corporation 2016-03-18 17:18:17,773 [main] FATAL [org.rpi.main.StartMe] java.vm.specification.version=1.7 2016-03-18 17:18:17,774 [main] FATAL [org.rpi.main.StartMe] java.vm.vendor=Oracle Corporation 2016-03-18 17:18:17,774 [main] FATAL [org.rpi.main.StartMe] java.vm.version=24.60-b09 2016-03-18 17:18:17,774 [main] FATAL [org.rpi.main.StartMe] javax.sound.sampled.SourceDataLine=#sndrpihifiberry [plughw:0,0] 2016-03-18 17:18:17,774 [main] FATAL [org.rpi.main.StartMe] line.separator=

2016-03-18 17:18:17,775 [main] FATAL [org.rpi.main.StartMe] os.arch=arm 2016-03-18 17:18:17,775 [main] FATAL [org.rpi.main.StartMe] os.name=Linux 2016-03-18 17:18:17,775 [main] FATAL [org.rpi.main.StartMe] os.version=4.1.19-v7+ 2016-03-18 17:18:17,775 [main] FATAL [org.rpi.main.StartMe] path.separator=: 2016-03-18 17:18:17,775 [main] FATAL [org.rpi.main.StartMe] sun.arch.data.model=32 2016-03-18 17:18:17,776 [main] FATAL [org.rpi.main.StartMe] sun.boot.class.path=/usr/lib/jvm/jdk-7-oracle-arm-vfp-hflt/jre/lib/resources.jar:/usr/lib/jvm/jdk-7-oracle-arm-vfp-hflt/jre/lib/rt.jar:/usr/lib/jvm/jdk-7-oracle-arm-vfp-hflt/jre/lib/sunrsasign.jar:/usr/lib/jvm/jdk-7-oracle-arm-vfp-hflt/jre/lib/jsse.jar:/usr/lib/jvm/jdk-7-oracle-arm-vfp-hflt/jre/lib/jce.jar:/usr/lib/jvm/jdk-7-oracle-arm-vfp-hflt/jre/lib/charsets.jar:/usr/lib/jvm/jdk-7-oracle-arm-vfp-hflt/jre/lib/jfr.jar:/usr/lib/jvm/jdk-7-oracle-arm-vfp-hflt/jre/classes 2016-03-18 17:18:17,776 [main] FATAL [org.rpi.main.StartMe] sun.boot.library.path=/usr/lib/jvm/jdk-7-oracle-arm-vfp-hflt/jre/lib/arm 2016-03-18 17:18:17,776 [main] FATAL [org.rpi.main.StartMe] sun.cpu.endian=little 2016-03-18 17:18:17,776 [main] FATAL [org.rpi.main.StartMe] sun.cpu.isalist= 2016-03-18 17:18:17,777 [main] FATAL [org.rpi.main.StartMe] sun.io.unicode.encoding=UnicodeLittle 2016-03-18 17:18:17,777 [main] FATAL [org.rpi.main.StartMe] sun.java.command=/opt/mediaplayer/mediaplayer.jar 2016-03-18 17:18:17,777 [main] FATAL [org.rpi.main.StartMe] sun.java.launcher=SUN_STANDARD 2016-03-18 17:18:17,777 [main] FATAL [org.rpi.main.StartMe] sun.jnu.encoding=UTF-8 2016-03-18 17:18:17,777 [main] FATAL [org.rpi.main.StartMe] sun.management.compiler=HotSpot Client Compiler 2016-03-18 17:18:17,778 [main] FATAL [org.rpi.main.StartMe] sun.os.patch.level=unknown 2016-03-18 17:18:17,778 [main] FATAL [org.rpi.main.StartMe] user.country=GB 2016-03-18 17:18:17,778 [main] FATAL [org.rpi.main.StartMe] user.dir=/opt/mediaplayer 2016-03-18 17:18:17,778 [main] FATAL [org.rpi.main.StartMe] user.home=/root 2016-03-18 17:18:17,779 [main] FATAL [org.rpi.main.StartMe] user.language=en 2016-03-18 17:18:17,779 [main] FATAL [org.rpi.main.StartMe] user.name=root 2016-03-18 17:18:17,779 [main] FATAL [org.rpi.main.StartMe] user.timezone=Europe/Berlin 2016-03-18 17:18:17,779 [main] FATAL [org.rpi.main.StartMe] #####End of System Properties######### 2016-03-18 17:18:17,779 [main] FATAL [org.rpi.main.StartMe] 2016-03-18 17:18:17,784 [main] FATAL [org.rpi.main.StartMe] #####Start of System Variables######### 2016-03-18 17:18:17,789 [main] FATAL [org.rpi.main.StartMe] OLDPWD=/ 2016-03-18 17:18:17,789 [main] FATAL [org.rpi.main.StartMe] PWD=/opt/mediaplayer 2016-03-18 17:18:17,789 [main] FATAL [org.rpi.main.StartMe] XFILESEARCHPATH=/usr/dt/app-defaults/%L/Dt 2016-03-18 17:18:17,790 [main] FATAL [org.rpi.main.StartMe] PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin 2016-03-18 17:18:17,790 [main] FATAL [org.rpi.main.StartMe] NLSPATH=/usr/dt/lib/nls/msg/%L/%N.cat 2016-03-18 17:18:17,790 [main] FATAL [org.rpi.main.StartMe] LANG=en_GB.UTF-8 2016-03-18 17:18:17,790 [main] FATAL [org.rpi.main.StartMe] #####End of System Variables######### 2016-03-18 17:18:17,791 [main] FATAL [org.rpi.main.StartMe] 2016-03-18 17:18:17,860 [main] DEBUG [org.rpi.player.PlayManager] MPD Player Selected 2016-03-18 17:18:17,912 [main] INFO [org.rpi.mpdplayer.TCPConnector] MPD Version: MPD 0.19.0 2016-03-18 17:18:17,918 [Thread-0] DEBUG [org.rpi.mpdplayer.StatusMonitor] Status Changed From : To: Stopped 2016-03-18 17:18:17,924 [main] DEBUG [org.rpi.main.SimpleDevice] Creating Simple Device version: 0.0.8.8 2016-03-18 17:18:17,928 [Thread-0] DEBUG [org.rpi.mpdplayer.MPDPlayer] Status Changed: Stopped 2016-03-18 17:18:17,932 [main] DEBUG [org.rpi.os.OSManager] Initializing OSManager 2016-03-18 17:18:17,933 [main] DEBUG [org.rpi.os.OSManager] Find Class, ClassName: org.rpi.os.OSManager 2016-03-18 17:18:17,933 [main] DEBUG [org.rpi.os.OSManager] Find Class, Full ClassName: /org/rpi/os/OSManager.class 2016-03-18 17:18:17,934 [main] DEBUG [org.rpi.os.OSManager] Find Class, Proper ClassName: OSManager.class 2016-03-18 17:18:17,934 [main] DEBUG [org.rpi.os.OSManager] Find Class, ClassURL: file:/opt/mediaplayer/mediaplayer.jar!/org/rpi/os/OSManager.class 2016-03-18 17:18:17,935 [Thread-0] DEBUG [org.rpi.player.PlayManager] Track Stopped, get Next Track 2016-03-18 17:18:17,935 [main] DEBUG [org.rpi.os.OSManager] Find Class, This is a JarFile: /opt/mediaplayer/mediaplayer.jar!/org/rpi/os/OSManager.class 2016-03-18 17:18:17,935 [Thread-0] DEBUG [org.rpi.player.PlayManager] EventStatusChanged: Stopped 2016-03-18 17:18:17,936 [main] DEBUG [org.rpi.os.OSManager] Find File: Returning JarPath: /opt/mediaplayer/ 2016-03-18 17:18:17,936 [Thread-0] DEBUG [org.rpi.player.PlayManager] SetStatus: Stopped 2016-03-18 17:18:17,936 [main] DEBUG [org.rpi.os.OSManager] Path ended with '/'. Updated Path to be: /opt/mediaplayer 2016-03-18 17:18:17,937 [main] DEBUG [org.rpi.os.OSManager] Path of this File is: /opt/mediaplayer 2016-03-18 17:18:17,937 [main] DEBUG [org.rpi.os.OSManager] OS Name: LINUX 2016-03-18 17:18:17,937 [main] DEBUG [org.rpi.os.OSManager] Its an ARM device, now check, which revision 2016-03-18 17:18:17,967 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Attribute Section: aeabi 2016-03-18 17:18:17,968 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : File Attributes 2016-03-18 17:18:17,968 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_CPU_name: "6" 2016-03-18 17:18:17,968 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_CPU_arch: v6 2016-03-18 17:18:17,969 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_ARM_ISA_use: Yes 2016-03-18 17:18:17,969 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_THUMB_ISA_use: Thumb-1 2016-03-18 17:18:17,969 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_FP_arch: VFPv2 2016-03-18 17:18:17,969 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_ABI_PCS_wchar_t: 4 2016-03-18 17:18:17,970 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_ABI_FP_rounding: Needed 2016-03-18 17:18:17,970 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_ABI_FP_denormal: Needed 2016-03-18 17:18:17,970 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_ABI_FP_exceptions: Needed 2016-03-18 17:18:17,970 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_ABI_FP_number_model: IEEE 754 2016-03-18 17:18:17,971 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_ABI_align_needed: 8-byte 2016-03-18 17:18:17,971 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_ABI_align_preserved: 8-byte, except leaf SP 2016-03-18 17:18:17,971 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_ABI_enum_size: int 2016-03-18 17:18:17,972 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_ABI_HardFP_use: SP and DP 2016-03-18 17:18:17,972 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_ABI_VFP_args: VFP registers 2016-03-18 17:18:17,973 [main] DEBUG [org.rpi.utils.Utils] Result of /usr/bin/readelf -A /proc/self/exe : Tag_CPU_unaligned_access: v6 2016-03-18 17:18:17,973 [main] DEBUG [org.rpi.os.OSManager] We think this is a Raspi 2016-03-18 17:18:17,985 [main] DEBUG [org.rpi.utils.Utils] Result of bash --version : GNU bash, version 4.3.30(1)-release (arm-unknown-linux-gnueabihf) 2016-03-18 17:18:17,985 [main] DEBUG [org.rpi.utils.Utils] Result of bash --version : Copyright (C) 2013 Free Software Foundation, Inc. 2016-03-18 17:18:17,986 [main] DEBUG [org.rpi.utils.Utils] Result of bash --version : License GPLv3+: GNU GPL version 3 or later http://gnu.org/licenses/gpl.html 2016-03-18 17:18:17,986 [main] DEBUG [org.rpi.utils.Utils] Result of bash --version : 2016-03-18 17:18:17,986 [main] DEBUG [org.rpi.utils.Utils] Result of bash --version : This is free software; you are free to change and redistribute it. 2016-03-18 17:18:17,986 [main] DEBUG [org.rpi.utils.Utils] Result of bash --version : There is NO WARRANTY, to the extent permitted by law. 2016-03-18 17:18:17,987 [main] DEBUG [org.rpi.os.OSManager] This is a HardFloat 2016-03-18 17:18:17,987 [main] WARN [org.rpi.os.OSManager] Using full_path /opt/mediaplayer/mediaplayer_lib/ohNet/linux/armv6hf 2016-03-18 17:18:17,987 [main] DEBUG [org.rpi.os.OSManager] Adding Path: /opt/mediaplayer/mediaplayer_lib/ohNet/linux/armv6hf 2016-03-18 17:18:18,059 [main] DEBUG [org.rpi.main.SimpleDevice] Created StandardDevice: device-PiBerry-raspberrypi-MediaRenderer 2016-03-18 17:18:18,147 [main] DEBUG [org.rpi.providers.PrvProduct] Creating CustomProduct 2016-03-18 17:18:18,204 [main] DEBUG [org.rpi.providers.PrvVolume] Creating CustomVolume 2016-03-18 17:18:18,256 [main] DEBUG [org.rpi.providers.PrvPlayList] Creating CustomPlayList 2016-03-18 17:18:18,543 [main] DEBUG [org.rpi.providers.PrvInfo] Creating Info 2016-03-18 17:18:18,563 [main] DEBUG [org.rpi.providers.PrvTime] Creating CustomTime 2016-03-18 17:18:18,581 [main] DEBUG [org.rpi.providers.PrvRadio] Creating CustomRadio 2016-03-18 17:18:18,641 [main] DEBUG [org.rpi.providers.PrvReceiver] Creating PrvReceiver 2016-03-18 17:18:18,672 [main] DEBUG [org.rpi.providers.PrvAVTransport] Creating AvTransport 2016-03-18 17:18:18,738 [main] DEBUG [org.rpi.providers.PrvRenderingControl] Creating CustomRenderingControl 2016-03-18 17:18:18,867 [main] WARN [org.rpi.sources.SourceReader] ListSources.xml Not Found 2016-03-18 17:18:18,872 [main] DEBUG [org.rpi.main.SimpleDevice] Adding Source: Name: Radio 2016-03-18 17:18:18,875 [main] DEBUG [org.rpi.providers.PrvProduct] SourceXML

RadioRadiotrue

2016-03-18 17:18:18,876 [main] DEBUG [org.rpi.main.SimpleDevice] Adding Source: Name: Receiver 2016-03-18 17:18:18,876 [main] DEBUG [org.rpi.providers.PrvProduct] SourceXML

RadioRadiotrueReceiverReceivertrue

2016-03-18 17:18:18,876 [main] DEBUG [org.rpi.main.SimpleDevice] Adding Source: Name: PiBerry 2016-03-18 17:18:18,877 [main] DEBUG [org.rpi.providers.PrvProduct] SourceXML

RadioRadiotrueReceiverReceivertruePiBerryUpnpAvfalse

2016-03-18 17:18:18,877 [main] DEBUG [org.rpi.main.SimpleDevice] Adding Source: Name: PlayList 2016-03-18 17:18:18,877 [main] DEBUG [org.rpi.providers.PrvProduct] SourceXML

RadioRadiotrueReceiverReceivertruePiBerryUpnpAvfalsePlayListPlaylisttrue

2016-03-18 17:18:18,877 [main] DEBUG [org.rpi.main.SimpleDevice] Adding Source: Name: AirPlay 2016-03-18 17:18:18,878 [main] DEBUG [org.rpi.providers.PrvProduct] SourceXML

RadioRadiotrueReceiverReceivertruePiBerryUpnpAvfalsePlayListPlaylisttrueAirPlayNetAuxfalse

2016-03-18 17:18:18,878 [main] DEBUG [org.rpi.providers.PrvProduct] Source Selected: Radio 2016-03-18 17:18:18,894 [main] DEBUG [org.rpi.main.SimpleDevice] Device Enabled UDN: device-PiBerry-raspberrypi-MediaRenderer 2016-03-18 17:18:18,894 [main] DEBUG [org.rpi.providers.PrvProduct] Source Selected: PlayList 2016-03-18 17:18:19,008 [main] INFO [org.rpi.http.HttpServerGrizzly] HTTP Server URI: http://0.0.0.0:80/myapp/ 2016-03-18 17:18:19,008 [main] INFO [org.rpi.http.HttpServerGrizzly] Starting HTTP Server: http://0.0.0.0:80/myapp/ 2016-03-18 17:18:19,097 [RadioGetter] DEBUG [org.rpi.radio.ChannelReaderJSON] Get Stations Children 2016-03-18 17:18:19,185 [Thread-3] DEBUG [org.rpi.providers.PrvConnectionManager] GetProtocolInfo: Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:19,185 [Thread-3] DEBUG [org.rpi.providers.PrvConnectionManager] GetProtolInfo Source= Sink=http-get::audio/x-flac:,http-get::audio/wav:,http-get::audio/wave:,http-get::audio/x-wav:,http-get::audio/mpeg:,http-get::audio/x-mpeg:,http-get::audio/mp1:,http-get::audio/aiff:,http-get::audio/x-aiff:,http-get::audio/x-m4a:,http-get::audio/x-ms-wma:,rtsp-rtp-udp::audio/x-ms-wma:,http-get::audio/x-scpls:,http-get::audio/x-mpegurl:,http-get::audio/x-ms-asf:,http-get::audio/x-ms-wax:,http-get::audio/x-ms-wvx:,http-get::text/xml:,http-get::audio/aac:,http-get::audio/aacp:,http-get::audio/mp4:,http-get::audio/ogg:,http-get::audio/x-ogg:,http-get::application/ogg:,http-get::video/mpeg:,http-get::video/mp4:,http-get::video/quicktime:,http-get::video/webm:,http-get::video/x-ms-wmv:,http-get::video/x-ms-asf:,http-get::video/x-msvideo:,http-get::video/x-ms-wax:,http-get::video/x-ms-wvx:,http-get::video/x-m4v:,http-get::video/x-matroska:,http-get::application/octet-stream: 2016-03-18 17:18:19,355 [RadioGetter] DEBUG [org.rpi.radio.ChannelReaderJSON] Could Not Parse ChannelID: 2016-03-18 17:18:19,391 [RadioGetter] DEBUG [org.rpi.channel.ChannelRadio] <?xml version="1.0" encoding="UTF-8" standalone="no"?>96.2 | The Revolution (Rock)/dc:title96.2 | The Revolution (Rock)/upnp:albumobject.item.audioItem/upnp:classhttp://opml.radiotime.com/Tune.ashx?id=s45508http://d1i6vahw24eb07.cloudfront.net/s45508q.png/upnp:albumArtURI 2016-03-18 17:18:19,407 [RadioGetter] INFO [org.rpi.radio.ChannelReaderJSON] Channel Name (For AlarmClock Config: '96.2 | The Revolution (Rock)' 2016-03-18 17:18:19,408 [RadioGetter] DEBUG [org.rpi.radio.ChannelReaderJSON] Added Channel: 1 - http://opml.radiotime.com/Tune.ashx?id=s45508 96.2 | The Revolution (Rock) - 96.2 | The Revolution (Rock) 2016-03-18 17:18:19,408 [RadioGetter] DEBUG [org.rpi.radio.ChannelReaderJSON] TuneIn PartnerId not configured, do not attempt to load TuneIn stations 2016-03-18 17:18:19,408 [RadioGetter] DEBUG [org.rpi.providers.PrvRadio] Start of AddRadioChannels 2016-03-18 17:18:19,409 [RadioGetter] DEBUG [org.rpi.providers.PrvRadio] Start of UpdateIdArray Radio 2016-03-18 17:18:19,413 [RadioGetter] DEBUG [org.rpi.providers.PrvRadio] End of UpdateIdArray Radio 2016-03-18 17:18:19,414 [RadioGetter] DEBUG [org.rpi.providers.PrvRadio] Added Radio Channels: 1 2016-03-18 17:18:20,029 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:20,738 [Thread-5] DEBUG [org.rpi.providers.PrvRenderingControl] getVolumeDBRange Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:20,746 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:20,777 [Thread-6] DEBUG [org.rpi.providers.PrvAVTransport] GetMediaInfo Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:24,543 [main] INFO [org.rpi.http.HttpServerGrizzly] BaseDir: sun.misc.Launcher$AppClassLoader@721ee7 2016-03-18 17:18:24,544 [main] INFO [org.rpi.http.HttpServerGrizzly] Jersey app started: http://0.0.0.0:80/myapp/ 2016-03-18 17:18:25,389 [main] DEBUG [org.rpi.player.PlayManager] Set Volume: 100 2016-03-18 17:18:25,395 [main] INFO [org.rpi.main.SimpleDevice] Start AirPlay Receiver 2016-03-18 17:18:26,015 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:26,141 [main] DEBUG [io.netty.util.internal.logging.InternalLoggerFactory] Using SLF4J as the default logging framework 2016-03-18 17:18:26,154 [main] DEBUG [io.netty.channel.MultithreadEventLoopGroup] -Dio.netty.eventLoopThreads: 8 2016-03-18 17:18:26,192 [main] DEBUG [io.netty.util.internal.PlatformDependent0] java.nio.Buffer.address: available 2016-03-18 17:18:26,193 [main] DEBUG [io.netty.util.internal.PlatformDependent0] sun.misc.Unsafe.theUnsafe: available 2016-03-18 17:18:26,195 [main] DEBUG [io.netty.util.internal.PlatformDependent0] sun.misc.Unsafe.copyMemory: available 2016-03-18 17:18:26,197 [main] DEBUG [io.netty.util.internal.PlatformDependent0] java.nio.Bits.unaligned: false 2016-03-18 17:18:26,199 [main] DEBUG [io.netty.util.internal.PlatformDependent] Java version: 7 2016-03-18 17:18:26,200 [main] DEBUG [io.netty.util.internal.PlatformDependent] -Dio.netty.noUnsafe: false 2016-03-18 17:18:26,200 [main] DEBUG [io.netty.util.internal.PlatformDependent] sun.misc.Unsafe: available 2016-03-18 17:18:26,202 [main] DEBUG [io.netty.util.internal.PlatformDependent] -Dio.netty.noJavassist: false 2016-03-18 17:18:26,720 [main] DEBUG [io.netty.util.internal.PlatformDependent] Javassist: available 2016-03-18 17:18:26,721 [main] DEBUG [io.netty.util.internal.PlatformDependent] -Dio.netty.tmpdir: /tmp (java.io.tmpdir) 2016-03-18 17:18:26,722 [main] DEBUG [io.netty.util.internal.PlatformDependent] -Dio.netty.bitMode: 32 (sun.arch.data.model) 2016-03-18 17:18:26,722 [main] DEBUG [io.netty.util.internal.PlatformDependent] -Dio.netty.noPreferDirect: false 2016-03-18 17:18:26,852 [main] DEBUG [io.netty.channel.nio.NioEventLoop] -Dio.netty.noKeySetOptimization: false 2016-03-18 17:18:26,853 [main] DEBUG [io.netty.channel.nio.NioEventLoop] -Dio.netty.selectorAutoRebuildThreshold: 512 2016-03-18 17:18:26,884 [Thread-7] DEBUG [org.rpi.airplay.AirPlayThread] Starting AirPlay Service... 2016-03-18 17:18:26,923 [Thread-7] DEBUG [io.netty.util.ResourceLeakDetector] -Dio.netty.leakDetectionLevel: simple 2016-03-18 17:18:26,923 [Thread-7] DEBUG [org.rpi.airplay.AirPlayThread] Create BouncyCastleProvider 2016-03-18 17:18:27,175 [main] INFO [org.quartz.impl.StdSchedulerFactory] Using default implementation for ThreadExecutor 2016-03-18 17:18:27,191 [main] INFO [org.quartz.simpl.SimpleThreadPool] Job execution threads will use class loader of thread: main 2016-03-18 17:18:27,706 [main] INFO [org.quartz.core.SchedulerSignalerImpl] Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 2016-03-18 17:18:27,717 [main] INFO [org.quartz.core.QuartzScheduler] Quartz Scheduler v.2.2.1 created. 2016-03-18 17:18:27,723 [main] INFO [org.quartz.simpl.RAMJobStore] RAMJobStore initialized. 2016-03-18 17:18:27,727 [main] INFO [org.quartz.core.QuartzScheduler] Scheduler meta-data: Quartz Scheduler (v2.2.1) 'DefaultQuartzScheduler' with instanceId 'NON_CLUSTERED' Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally. NOT STARTED. Currently in standby mode. Number of jobs executed: 0 Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 10 threads. Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.

2016-03-18 17:18:27,728 [main] INFO [org.quartz.impl.StdSchedulerFactory] Quartz scheduler 'DefaultQuartzScheduler' initialized from default resource file in Quartz package: 'quartz.properties' 2016-03-18 17:18:27,728 [main] INFO [org.quartz.impl.StdSchedulerFactory] Quartz scheduler version: 2.2.1 2016-03-18 17:18:27,729 [main] INFO [org.quartz.core.QuartzScheduler] Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started. 2016-03-18 17:18:27,741 [main] DEBUG [org.rpi.alarm.Alarm] Refreshing Alarms 2016-03-18 17:18:27,742 [main] DEBUG [org.rpi.alarm.Alarm] Clearing Schedules 2016-03-18 17:18:27,747 [main] INFO [org.rpi.os.OSManager] Start of LoadPlugins 2016-03-18 17:18:27,942 [main] INFO [org.rpi.os.OSManager] End of LoadPlugnis 2016-03-18 17:18:27,943 [main] DEBUG [org.rpi.main.SimpleDevice] Shut Down Hook Attached. 2016-03-18 17:18:28,025 [Thread-7] DEBUG [org.rpi.airplay.AirPlayThread] Created BouncyCastleProvider 2016-03-18 17:18:28,026 [Thread-7] DEBUG [org.rpi.airplay.AirPlayThread] Initiate an encrypt 2016-03-18 17:18:28,029 [Thread-7] INFO [org.rpi.utils.SecUtils] Start of EncryptRSA 2016-03-18 17:18:28,030 [Thread-7] DEBUG [org.rpi.utils.SecUtils] Create pemReader 2016-03-18 17:18:28,216 [Thread-7] DEBUG [org.rpi.utils.SecUtils] Created pemReader 2016-03-18 17:18:28,216 [Thread-7] DEBUG [org.rpi.utils.SecUtils] ReadObject 2016-03-18 17:18:28,386 [Thread-7] DEBUG [org.rpi.utils.SecUtils] getInstancer 2016-03-18 17:18:31,549 [Thread-7] DEBUG [org.rpi.utils.SecUtils] Cipher 2016-03-18 17:18:31,651 [Thread-7] INFO [org.rpi.utils.SecUtils] End of EncryptRSA 2016-03-18 17:18:32,005 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:32,036 [Thread-7] INFO [org.rpi.utils.NetworkUtils] Hardware address is 801F02F2DB8C (wlan0) 2016-03-18 17:18:32,036 [Thread-7] DEBUG [org.rpi.airplay.AirPlayThread] Check if Passsword is set 2016-03-18 17:18:32,125 [Thread-7] DEBUG [io.netty.util.internal.ThreadLocalRandom] -Dio.netty.initialSeedUniquifier: 0xd591be1a0dbdbe31 (took 5 ms) 2016-03-18 17:18:32,306 [Thread-7] DEBUG [io.netty.buffer.ByteBufUtil] -Dio.netty.allocator.type: unpooled 2016-03-18 17:18:32,307 [Thread-7] DEBUG [io.netty.buffer.ByteBufUtil] -Dio.netty.threadLocalDirectBufferSize: 65536 2016-03-18 17:18:32,316 [Thread-7] DEBUG [io.netty.util.NetUtil] Loopback interface: lo (lo, 0:0:0:0:0:0:0:1%1) 2016-03-18 17:18:32,317 [Thread-7] DEBUG [io.netty.util.NetUtil] /proc/sys/net/core/somaxconn: 128 2016-03-18 17:18:32,365 [nioEventLoopGroup-2-1] DEBUG [io.netty.util.internal.PlatformDependent] UID: 0 2016-03-18 17:18:32,368 [Thread-7] DEBUG [org.rpi.airplay.AirPlayThread] Registering AirTunes Services 2016-03-18 17:18:32,373 [Thread-7] DEBUG [org.rpi.airplay.BonjourEmitter] Starting Bonjour Service: 801F02F2DB8C@PiBerry@/2003:71:e1d:4748:8e2:31a3:a347:bfce%2 2016-03-18 17:18:37,996 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:40,200 [Thread-7] INFO [org.rpi.airplay.BonjourEmitter] Registered for Service: [ServiceInfoImpl@28217054 name: '801F02F2DB8C@PiBerry._raop._tcp.local.' address: 'null:5004 p200300710E1D474808E231A3A347BFCE.dip0.t-ipconnect.de/2003:71:e1d:4748:8e2:31a3:a347:bfce%2:5004 ' status: 'DNS: PiBerry-jmdns state: announced task: Renewer(PiBerry-jmdns) state: announced', has data ch: 2 sr: 44100 txtvers: 1 vn: 3 ek: 1 sm: false md: 0,2 cn: 0,1 tp: UDP et: 0,1 pw: false sv: false ss: 16 ] 2016-03-18 17:18:40,201 [Thread-7] DEBUG [org.rpi.airplay.AirPlayThread] Registered AirTunes service 'PiBerry' on p200300710E1D474808E231A3A347BFCE.dip0.t-ipconnect.de/2003:71:e1d:4748:8e2:31a3:a347:bfce%2 2016-03-18 17:18:40,201 [Thread-7] DEBUG [org.rpi.airplay.BonjourEmitter] Starting Bonjour Service: 801F02F2DB8C@PiBerry@/fe80:0:0:0:2f5d:ba48:cc03:a2c%2 2016-03-18 17:18:43,860 [Thread-5] DEBUG [org.rpi.providers.PrvPlayList] GetIdArray Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:43,886 [Thread-3] DEBUG [org.rpi.providers.PrvPlayList] Insert After: 0 URI: http://192.168.2.104:9790/minimserver/*/Musik/Norah*20Jones*20-*20Feels*20Like*20Home*20(2012)*20*5bHDtracks*5d*2024Bit*20192*20FLAC*20Beolab1700/1-Sunrise.flac MetaDate:

Sunrise/dc:title object.item.audioItem.musicTrack/upnp:class http://192.168.2.104:9790/minimserver/*/Musik/Norah*20Jones*20-*20Feels*20Like*20Home*20(2012)*20*5bHDtracks*5d*2024Bit*20192*20FLAC*20Beolab1700/1-Sunrise.flac/$!picture-371-201674.jpg?connection=close/upnp:albumArtURI Feels Like Home/upnp:album Norah Jones/upnp:artist Norah Jones/upnp:artist Norah Jones, Lee Alexander/upnp:artist 2012-01-01/dc:date Singer/Songwriter/upnp:genre http://192.168.2.104:9790/minimserver/*/Musik/Norah*20Jones*20-*20Feels*20Like*20Home*20(2012)*20*5bHDtracks*5d*2024Bit*20192*20FLAC*20Beolab1700/1-Sunrise.flac Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:43,886 [Thread-3] DEBUG [org.rpi.providers.PrvPlayList] GetNextId: 1 2016-03-18 17:18:43,894 [Thread-3] DEBUG [org.rpi.channel.ChannelPlayList] Sunrise/dc:title object.item.audioItem.musicTrack/upnp:class http://192.168.2.104:9790/minimserver/*/Musik/Norah*20Jones*20-*20Feels*20Like*20Home*20(2012)*20*5bHDtracks*5d*2024Bit*20192*20FLAC*20Beolab1700/1-Sunrise.flac/$!picture-371-201674.jpg?connection=close/upnp:albumArtURI Feels Like Home/upnp:album Norah Jones/upnp:artist Norah Jones/upnp:artist Norah Jones, Lee Alexander/upnp:artist 2012-01-01/dc:date Singer/Songwriter/upnp:genre http://192.168.2.104:9790/minimserver/*/Musik/Norah*20Jones*20-*20Feels*20Like*20Home*20(2012)*20*5bHDtracks*5d*2024Bit*20192*20FLAC*20Beolab1700/1-Sunrise.flac

2016-03-18 17:18:43,911 [Thread-3] DEBUG [org.rpi.providers.PrvPlayList] Insert Track Return: 1 2016-03-18 17:18:43,931 [Thread-4] DEBUG [org.rpi.providers.PrvProduct] SetSourceIndex: 3 Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:43,933 [Thread-4] DEBUG [org.rpi.providers.PrvProduct] Source Selected: PlayList 2016-03-18 17:18:43,934 [Thread-6] DEBUG [org.rpi.providers.PrvPlayList] SeekId: 1 Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:43,935 [Thread-6] DEBUG [org.rpi.player.PlayManager] GetTrakcFromId: 1 2016-03-18 17:18:43,935 [Thread-5] DEBUG [org.rpi.providers.PrvPlayList] ReadList: 1 Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:43,936 [Thread-6] DEBUG [org.rpi.player.PlayManager] We are playing a Channel, take out of Standby 2016-03-18 17:18:43,936 [Thread-5] DEBUG [org.rpi.providers.PrvPlayList] ReadList Contains : 1 1http://192.168.2.104:9790/minimserver/*/Musik/Norah*20Jones*20-*20Feels*20Like*20Home*20(2012)*20*5bHDtracks*5d*2024Bit*20192*20FLAC*20Beolab1700/1-Sunrise.flac<DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"> <item id="d5112915403826242371-co533" parentID="co533" restricted="0"> <dc:title xmlns:dc="http://purl.org/dc/elements/1.1/"&#62;Sunrise&#60;/dc:title&#62;&#13;&#10; <upnp:class xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">object.item.audioItem.musicTrack</upnp:class> <upnp:albumArtURI xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">http://192.168.2.104:9790/minimserver/*/Musik/Norah*20Jones*20-*20Feels*20Like*20Home*20(2012)*20*5bHDtracks*5d*2024Bit*20192*20FLAC*20Beolab1700/1-Sunrise.flac/$!picture-371-201674.jpg?connection=close&#60;/upnp:albumArtURI&#62;&#13;&#10; <upnp:album xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Feels Like Home</upnp:album> <upnp:artist xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Norah Jones</upnp:artist> <upnp:artist xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" role="AlbumArtist">Norah Jones</upnp:artist> <upnp:artist xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" role="Composer">Norah Jones, Lee Alexander</upnp:artist> <dc:date xmlns:dc="http://purl.org/dc/elements/1.1/"&#62;2012-01-01&#60;/dc:date&#62;&#13;&#10; <upnp:genre xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Singer/Songwriter</upnp:genre> <res sampleFrequency="192000" bitsPerSample="24" bitrate="1152000" protocolInfo="http-get::audio/x-flac:">http://192.168.2.104:9790/minimserver/*/Musik/Norah*20Jones*20-*20Feels*20Like*20Home*20(2012)*20*5bHDtracks*5d*2024Bit*20192*20FLAC*20Beolab1700/1-Sunrise.flac&#60;/res&#62;&#13;&#10; </item> </DIDL-Lite> 2016-03-18 17:18:43,940 [Thread-6] DEBUG [org.rpi.player.PlayManager] EventStatusChanged: Buffering 2016-03-18 17:18:43,941 [Thread-6] DEBUG [org.rpi.player.PlayManager] SetStatus: Buffering 2016-03-18 17:18:43,946 [Thread-6] DEBUG [org.rpi.providers.PrvAVTransport] Status: Buffering 2016-03-18 17:18:43,951 [Thread-6] DEBUG [org.rpi.radio.parsers.FileParser] FLAC File: http://192.168.2.104:9790/minimserver/*/Musik/Norah*20Jones*20-*20Feels*20Like*20Home*20(2012)*20*5bHDtracks*5d*2024Bit*20192*20FLAC*20Beolab1700/1-Sunrise.flac 2016-03-18 17:18:43,954 [Thread-6] DEBUG [org.rpi.mpdplayer.MPDPlayer] ADD TO PLAYLIST{Id=1} 2016-03-18 17:18:43,993 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:44,062 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:44,090 [Thread-0] DEBUG [org.rpi.mpdplayer.StatusMonitor] Song Changed From : To: 1 2016-03-18 17:18:44,093 [Thread-0] DEBUG [org.rpi.mpdplayer.StatusMonitor] Status Changed From : Stopped To: Playing 2016-03-18 17:18:44,094 [Thread-0] DEBUG [org.rpi.mpdplayer.MPDPlayer] Status Changed: Playing 2016-03-18 17:18:44,094 [Thread-0] DEBUG [org.rpi.player.PlayManager] EventStatusChanged: Playing 2016-03-18 17:18:44,094 [Thread-0] DEBUG [org.rpi.player.PlayManager] SetStatus: Playing 2016-03-18 17:18:44,102 [Thread-0] DEBUG [org.rpi.providers.PrvAVTransport] Status: Playing 2016-03-18 17:18:44,105 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] Artist and/or Title was empty:
2016-03-18 17:18:44,230 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] TrackChanged: '' '' 2016-03-18 17:18:44,757 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:44,790 [Thread-6] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:45,210 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:47,005 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:47,018 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:47,031 [Thread-6] DEBUG [org.rpi.providers.PrvAVTransport] GetMediaInfo Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:47,734 [Thread-7] INFO [org.rpi.airplay.BonjourEmitter] Registered for Service: [ServiceInfoImpl@4311026 name: '801F02F2DB8C@PiBerry._raop._tcp.local.' address: 'null:5004 fe80:0:0:0:2f5d:ba48:cc03:a2c%2/fe80:0:0:0:2f5d:ba48:cc03:a2c%2:5004 ' status: 'DNS: PiBerry-jmdns state: announced task: Renewer(PiBerry-jmdns) state: announced', has data ch: 2 sr: 44100 txtvers: 1 vn: 3 ek: 1 sm: false md: 0,2 cn: 0,1 tp: UDP et: 0,1 pw: false sv: false ss: 16 ] 2016-03-18 17:18:47,734 [Thread-7] DEBUG [org.rpi.airplay.AirPlayThread] Registered AirTunes service 'PiBerry' on fe80:0:0:0:2f5d:ba48:cc03:a2c%2/fe80:0:0:0:2f5d:ba48:cc03:a2c%2 2016-03-18 17:18:47,735 [Thread-7] DEBUG [org.rpi.airplay.BonjourEmitter] Starting Bonjour Service: 801F02F2DB8C@PiBerry@/192.168.2.121 2016-03-18 17:18:49,831 [PlayListWriter] DEBUG [org.rpi.playlist.PlayListWriter] PlayList Contains : 1 2016-03-18 17:18:49,832 [PlayListWriter] DEBUG [org.rpi.playlist.PlayListWriter] Saving PlayList: 2016-03-18 17:18:49,834 [PlayListWriter] DEBUG [org.rpi.playlist.PlayListWriter] Saved PlayList 2016-03-18 17:18:49,977 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:50,018 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:53,033 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:55,317 [Thread-7] INFO [org.rpi.airplay.BonjourEmitter] Registered for Service: [ServiceInfoImpl@4682289 name: '801F02F2DB8C@PiBerry._raop._tcp.local.' address: '192.168.2.121/192.168.2.121:5004 null:5004 ' status: 'DNS: PiBerry-jmdns state: announced task: Renewer(PiBerry-jmdns) state: announced', has data ch: 2 sr: 44100 txtvers: 1 vn: 3 ek: 1 sm: false md: 0,2 cn: 0,1 tp: UDP et: 0,1 pw: false sv: false ss: 16 ] 2016-03-18 17:18:55,318 [Thread-7] DEBUG [org.rpi.airplay.AirPlayThread] Registered AirTunes service 'PiBerry' on 192.168.2.121/192.168.2.121 2016-03-18 17:18:55,319 [Thread-7] DEBUG [org.rpi.airplay.AirPlayThread] Finished Registering AirTunes Services 2016-03-18 17:18:55,967 [Thread-6] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:56,045 [Thread-6] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:18:59,058 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:00,609 [Thread-3] DEBUG [org.rpi.providers.PrvPlayList] Pause Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:00,691 [Thread-3] DEBUG [org.rpi.player.PlayManager] SetStatus: Paused 2016-03-18 17:19:01,233 [Thread-0] DEBUG [org.rpi.mpdplayer.StatusMonitor] Status Changed From : Playing To: Paused 2016-03-18 17:19:01,234 [Thread-0] DEBUG [org.rpi.mpdplayer.MPDPlayer] Status Changed: Paused 2016-03-18 17:19:01,234 [Thread-0] DEBUG [org.rpi.player.PlayManager] EventStatusChanged: Paused 2016-03-18 17:19:01,234 [Thread-0] DEBUG [org.rpi.player.PlayManager] SetStatus: Paused 2016-03-18 17:19:01,235 [Thread-0] DEBUG [org.rpi.providers.PrvAVTransport] Status: Paused 2016-03-18 17:19:01,958 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:02,070 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:05,085 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:06,777 [Thread-12] DEBUG [org.rpi.main.SimpleDevice] Shutdown Hook, Start of Shutdown 2016-03-18 17:19:06,778 [Thread-12] DEBUG [org.rpi.airplay.AirPlayThread] AirplayThread Shutdown... 2016-03-18 17:19:06,779 [Thread-12] DEBUG [org.rpi.airplay.BonjourEmitter] Stop BonjourEmitter: 801F02F2DB8C@PiBerry@/2003:71:e1d:4748:8e2:31a3:a347:bfce%2 2016-03-18 17:19:07,949 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:08,098 [Thread-6] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:11,111 [Thread-6] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:13,794 [Thread-12] DEBUG [org.rpi.airplay.BonjourEmitter] Stop BonjourEmitter: 801F02F2DB8C@PiBerry@/fe80:0:0:0:2f5d:ba48:cc03:a2c%2 2016-03-18 17:19:13,939 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:14,124 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:17,137 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:19,930 [Thread-6] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:20,151 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:21,805 [Thread-12] DEBUG [org.rpi.airplay.BonjourEmitter] Stop BonjourEmitter: 801F02F2DB8C@PiBerry@/192.168.2.121 2016-03-18 17:19:23,165 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:25,922 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:26,177 [Thread-6] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:28,810 [Thread-12] INFO [org.rpi.airplay.AirPlayThread] Bonjur Service stopped. 2016-03-18 17:19:28,811 [Thread-12] DEBUG [org.rpi.airplay.AirPlayThread] Close RTSP Server 2016-03-18 17:19:29,190 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-18 17:19:31,036 [Thread-12] INFO [org.rpi.web.longpolling.WorkqeueEvents] Clearing Work Queue. Number of Items: 0 2016-03-18 17:19:31,037 [Thread-12] INFO [org.rpi.web.longpolling.WorkqeueEvents] WorkQueue Cleared 2016-03-18 17:19:31,219 [Thread-12] INFO [org.rpi.main.SimpleDevice] Destroying IPlayer 2016-03-18 17:19:31,219 [Thread-12] DEBUG [org.rpi.player.PlayManager] Start of destroy 2016-03-18 17:19:31,219 [Thread-12] DEBUG [org.rpi.player.PlayManager] Attempt to Destroy MPlayer 2016-03-18 17:19:31,220 [Thread-12] INFO [org.rpi.main.SimpleDevice] Destroyed IPlayer 2016-03-18 17:19:31,220 [Thread-12] INFO [org.rpi.main.SimpleDevice] Destroying device 2016-03-18 17:19:31,314 [Thread-0] ERROR [org.rpi.mpdplayer.TCPConnector] java.net.SocketException: Socket is closed 2016-03-18 17:19:31,318 [Thread-0] INFO [org.rpi.mpdplayer.TCPConnector] MPD Version: MPD 0.19.0 2016-03-18 17:19:31,361 [Thread-20] INFO [org.rpi.main.OpenHomeLogger] OpenHome/Network.cpp:306: 2016-03-18 17:19:31,361 [Thread-20] INFO [org.rpi.main.OpenHomeLogger] Socket::ReceiveFrom H = 1618696312, RETURN VALUE = -1

2016-03-18 17:19:31,362 [Thread-20] INFO [org.rpi.main.OpenHomeLogger] SSDP Multicast ReaderError

2016-03-18 17:19:31,363 [Thread-12] INFO [org.rpi.main.SimpleDevice] Destroyed device 2016-03-18 17:19:31,364 [Thread-12] INFO [org.rpi.main.SimpleDevice] Dispose ConnectionManager 2016-03-18 17:19:31,365 [Thread-12] INFO [org.rpi.main.SimpleDevice] Disposed ConnectionManager 2016-03-18 17:19:31,365 [Thread-12] INFO [org.rpi.main.SimpleDevice] Dispose PlayList 2016-03-18 17:19:31,365 [Thread-12] INFO [org.rpi.main.SimpleDevice] Disposed PlayList 2016-03-18 17:19:31,365 [Thread-12] INFO [org.rpi.main.SimpleDevice] Dispose Volume 2016-03-18 17:19:31,366 [Thread-12] INFO [org.rpi.main.SimpleDevice] Disposed Volume 2016-03-18 17:19:31,366 [Thread-12] INFO [org.rpi.main.SimpleDevice] Dispose Product 2016-03-18 17:19:31,367 [Thread-12] INFO [org.rpi.main.SimpleDevice] Disposed Product 2016-03-18 17:19:31,367 [Thread-12] INFO [org.rpi.main.SimpleDevice] Dispose Info 2016-03-18 17:19:31,367 [Thread-12] INFO [org.rpi.main.SimpleDevice] Disposed Info 2016-03-18 17:19:31,367 [Thread-12] INFO [org.rpi.main.SimpleDevice] Dispose Time 2016-03-18 17:19:31,367 [Thread-12] INFO [org.rpi.main.SimpleDevice] Disposed Time 2016-03-18 17:19:31,368 [Thread-12] INFO [org.rpi.main.SimpleDevice] Dispose Radio 2016-03-18 17:19:31,368 [Thread-12] INFO [org.rpi.main.SimpleDevice] Disposed Radio 2016-03-18 17:19:31,368 [Thread-12] INFO [org.rpi.main.SimpleDevice] Dispose Receiver 2016-03-18 17:19:31,368 [Thread-12] INFO [org.rpi.main.SimpleDevice] Disposed Receiver 2016-03-18 17:19:31,369 [Thread-12] INFO [org.rpi.main.SimpleDevice] Dispose AVTransport 2016-03-18 17:19:31,369 [Thread-12] INFO [org.rpi.main.SimpleDevice] Disposed AVTransport 2016-03-18 17:19:31,369 [Thread-12] INFO [org.rpi.main.SimpleDevice] Dispose RenderingControl 2016-03-18 17:19:31,370 [Thread-12] INFO [org.rpi.main.SimpleDevice] Disposed RenderingControl 2016-03-18 17:19:31,370 [Thread-12] INFO [org.rpi.main.SimpleDevice] Dispose Songcast 2016-03-18 17:19:31,370 [Thread-12] INFO [org.rpi.main.SimpleDevice] Disposed Songcast 2016-03-18 17:19:31,370 [Thread-12] INFO [org.rpi.main.SimpleDevice] Attempting to Close DeviceStack 2016-03-18 17:19:31,371 [Thread-3] INFO [org.rpi.main.OpenHomeLogger] OpenHome/Network.cpp:353: 2016-03-18 17:19:31,371 [Thread-3] INFO [org.rpi.main.OpenHomeLogger] Socket::Accept H = 0

2016-03-18 17:19:31,372 [Thread-3] INFO [org.rpi.main.OpenHomeLogger] OpenHome/Network.cpp:599: 2016-03-18 17:19:31,372 [Thread-3] INFO [org.rpi.main.OpenHomeLogger] -SocketTcpSession::Start() Network Accept Exception

2016-03-18 17:19:31,373 [Thread-5] INFO [org.rpi.main.OpenHomeLogger] OpenHome/Network.cpp:599: 2016-03-18 17:19:31,373 [Thread-5] INFO [org.rpi.main.OpenHomeLogger] -SocketTcpSession::Start() Network Accept Exception

2016-03-18 17:19:31,374 [Thread-6] INFO [org.rpi.main.OpenHomeLogger] OpenHome/Network.cpp:599: 2016-03-18 17:19:31,374 [Thread-6] INFO [org.rpi.main.OpenHomeLogger] -SocketTcpSession::Start() Network Accept Exception

2016-03-18 17:19:31,375 [Thread-4] INFO [org.rpi.main.OpenHomeLogger] OpenHome/Network.cpp:599: 2016-03-18 17:19:31,375 [Thread-4] INFO [org.rpi.main.OpenHomeLogger] -SocketTcpSession::Start() Network Accept Exception

2016-03-18 17:19:31,378 [Thread-12] INFO [org.rpi.main.SimpleDevice] Closed DeviceStack

PeteManchester commented 8 years ago

It's getting the TrackChanged Event but for some reason it's not getting either the title or artist info

2016-03-18 17:18:44,105 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] Artist and/or Title was empty:

2016-03-18 17:18:44,230 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] TrackChanged: '' ''

Can you send me a log of your successful attempt using Kinsky and the UPNP source please.

fupps commented 8 years ago

Thanks a lot for your time an help. Here is the log:

2016-03-19 10:54:25,166 [main] DEBUG [org.rpi.providers.PrvProduct] Creating CustomProduct 2016-03-19 10:54:25,225 [main] DEBUG [org.rpi.providers.PrvVolume] Creating CustomVolume 2016-03-19 10:54:25,276 [main] DEBUG [org.rpi.providers.PrvPlayList] Creating CustomPlayList 2016-03-19 10:54:25,594 [main] DEBUG [org.rpi.channel.ChannelPlayList] Hello/dc:titlePop/upnp:genre2015-01-01/dc:date25/upnp:albumAdele/upnp:artistAdele/dc:creator1/upnp:originalTrackNumberhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/cover.jpg/upnp:albumArtURIhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/01*20Hello.flacobject.item.audioItem.musicTrack/upnp:class 2016-03-19 10:54:25,662 [main] DEBUG [org.rpi.playlist.PlayListReader] Adding Track Id: 1 URL: http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/01*20Hello.flac Hello - Adele - 25 2016-03-19 10:54:25,667 [main] DEBUG [org.rpi.channel.ChannelPlayList] Hello/dc:titlePop/upnp:genre2015-01-01/dc:date25/upnp:albumAdele/upnp:artistAdele/dc:creator1/upnp:originalTrackNumberhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/cover.jpg/upnp:albumArtURIhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/01*20Hello.flacobject.item.audioItem.musicTrack/upnp:class 2016-03-19 10:54:25,682 [main] DEBUG [org.rpi.playlist.PlayListReader] Adding Track Id: 2 URL: http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/01*20Hello.flac Hello - Adele - 25 2016-03-19 10:54:25,702 [main] DEBUG [org.rpi.providers.PrvInfo] Creating Info 2016-03-19 10:54:25,722 [main] DEBUG [org.rpi.providers.PrvTime] Creating CustomTime 2016-03-19 10:54:25,742 [main] DEBUG [org.rpi.providers.PrvRadio] Creating CustomRadio 2016-03-19 10:54:25,798 [main] DEBUG [org.rpi.providers.PrvReceiver] Creating PrvReceiver 2016-03-19 10:54:25,832 [main] DEBUG [org.rpi.providers.PrvAVTransport] Creating AvTransport 2016-03-19 10:54:25,897 [main] DEBUG [org.rpi.providers.PrvRenderingControl] Creating CustomRenderingControl 2016-03-19 10:54:26,060 [main] WARN [org.rpi.sources.SourceReader] ListSources.xml Not Found 2016-03-19 10:54:26,066 [main] DEBUG [org.rpi.main.SimpleDevice] Adding Source: Name: Radio 2016-03-19 10:54:26,069 [main] DEBUG [org.rpi.providers.PrvProduct] SourceXML

RadioRadiotrue

2016-03-19 10:54:26,069 [main] DEBUG [org.rpi.main.SimpleDevice] Adding Source: Name: Receiver 2016-03-19 10:54:26,070 [main] DEBUG [org.rpi.providers.PrvProduct] SourceXML

RadioRadiotrueReceiverReceivertrue

2016-03-19 10:54:26,070 [main] DEBUG [org.rpi.main.SimpleDevice] Adding Source: Name: PiBerry 2016-03-19 10:54:26,070 [main] DEBUG [org.rpi.providers.PrvProduct] SourceXML

RadioRadiotrueReceiverReceivertruePiBerryUpnpAvfalse

2016-03-19 10:54:26,071 [main] DEBUG [org.rpi.main.SimpleDevice] Adding Source: Name: PlayList 2016-03-19 10:54:26,071 [main] DEBUG [org.rpi.providers.PrvProduct] SourceXML

RadioRadiotrueReceiverReceivertruePiBerryUpnpAvfalsePlayListPlaylisttrue

2016-03-19 10:54:26,071 [main] DEBUG [org.rpi.main.SimpleDevice] Adding Source: Name: AirPlay 2016-03-19 10:54:26,072 [main] DEBUG [org.rpi.providers.PrvProduct] SourceXML

RadioRadiotrueReceiverReceivertruePiBerryUpnpAvfalsePlayListPlaylisttrueAirPlayNetAuxfalse

2016-03-19 10:54:26,073 [main] DEBUG [org.rpi.providers.PrvProduct] Source Selected: Radio 2016-03-19 10:54:26,087 [main] DEBUG [org.rpi.main.SimpleDevice] Device Enabled UDN: device-PiBerry-raspberrypi-MediaRenderer 2016-03-19 10:54:26,088 [main] DEBUG [org.rpi.providers.PrvProduct] Source Selected: PlayList 2016-03-19 10:54:26,171 [main] INFO [org.rpi.http.HttpServerGrizzly] HTTP Server URI: http://0.0.0.0:80/myapp/ 2016-03-19 10:54:26,171 [main] INFO [org.rpi.http.HttpServerGrizzly] Starting HTTP Server: http://0.0.0.0:80/myapp/ 2016-03-19 10:54:26,270 [RadioGetter] DEBUG [org.rpi.radio.ChannelReaderJSON] Get Stations Children 2016-03-19 10:54:26,376 [Thread-3] DEBUG [org.rpi.providers.PrvConnectionManager] GetProtocolInfo: Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:54:26,376 [Thread-3] DEBUG [org.rpi.providers.PrvConnectionManager] GetProtolInfo Source= Sink=http-get::audio/x-flac:,http-get::audio/wav:,http-get::audio/wave:,http-get::audio/x-wav:,http-get::audio/mpeg:,http-get::audio/x-mpeg:,http-get::audio/mp1:,http-get::audio/aiff:,http-get::audio/x-aiff:,http-get::audio/x-m4a:,http-get::audio/x-ms-wma:,rtsp-rtp-udp::audio/x-ms-wma:,http-get::audio/x-scpls:,http-get::audio/x-mpegurl:,http-get::audio/x-ms-asf:,http-get::audio/x-ms-wax:,http-get::audio/x-ms-wvx:,http-get::text/xml:,http-get::audio/aac:,http-get::audio/aacp:,http-get::audio/mp4:,http-get::audio/ogg:,http-get::audio/x-ogg:,http-get::application/ogg:,http-get::video/mpeg:,http-get::video/mp4:,http-get::video/quicktime:,http-get::video/webm:,http-get::video/x-ms-wmv:,http-get::video/x-ms-asf:,http-get::video/x-msvideo:,http-get::video/x-ms-wax:,http-get::video/x-ms-wvx:,http-get::video/x-m4v:,http-get::video/x-matroska:,http-get::application/octet-stream: 2016-03-19 10:54:26,547 [RadioGetter] DEBUG [org.rpi.radio.ChannelReaderJSON] Could Not Parse ChannelID: 2016-03-19 10:54:26,550 [RadioGetter] DEBUG [org.rpi.channel.ChannelRadio] <?xml version="1.0" encoding="UTF-8" standalone="no"?>96.2 | The Revolution (Rock)/dc:title96.2 | The Revolution (Rock)/upnp:albumobject.item.audioItem/upnp:classhttp://opml.radiotime.com/Tune.ashx?id=s45508http://d1i6vahw24eb07.cloudfront.net/s45508q.png/upnp:albumArtURI 2016-03-19 10:54:26,564 [RadioGetter] INFO [org.rpi.radio.ChannelReaderJSON] Channel Name (For AlarmClock Config: '96.2 | The Revolution (Rock)' 2016-03-19 10:54:26,565 [RadioGetter] DEBUG [org.rpi.radio.ChannelReaderJSON] Added Channel: 1 - http://opml.radiotime.com/Tune.ashx?id=s45508 96.2 | The Revolution (Rock) - 96.2 | The Revolution (Rock) 2016-03-19 10:54:26,566 [RadioGetter] DEBUG [org.rpi.radio.ChannelReaderJSON] TuneIn PartnerId not configured, do not attempt to load TuneIn stations 2016-03-19 10:54:26,566 [RadioGetter] DEBUG [org.rpi.providers.PrvRadio] Start of AddRadioChannels 2016-03-19 10:54:26,567 [RadioGetter] DEBUG [org.rpi.providers.PrvRadio] Start of UpdateIdArray Radio 2016-03-19 10:54:26,568 [RadioGetter] DEBUG [org.rpi.providers.PrvRadio] End of UpdateIdArray Radio 2016-03-19 10:54:26,568 [RadioGetter] DEBUG [org.rpi.providers.PrvRadio] Added Radio Channels: 1 2016-03-19 10:54:27,934 [Thread-4] DEBUG [org.rpi.providers.PrvRenderingControl] getVolumeDBRange Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:54:27,949 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:54:27,960 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] GetMediaInfo Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:54:28,501 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:54:31,648 [main] INFO [org.rpi.http.HttpServerGrizzly] BaseDir: sun.misc.Launcher$AppClassLoader@4adb34 2016-03-19 10:54:31,649 [main] INFO [org.rpi.http.HttpServerGrizzly] Jersey app started: http://0.0.0.0:80/myapp/ 2016-03-19 10:54:32,497 [main] DEBUG [org.rpi.player.PlayManager] Set Volume: 100 2016-03-19 10:54:32,810 [main] INFO [org.quartz.impl.StdSchedulerFactory] Using default implementation for ThreadExecutor 2016-03-19 10:54:32,828 [main] INFO [org.quartz.simpl.SimpleThreadPool] Job execution threads will use class loader of thread: main 2016-03-19 10:54:32,933 [main] INFO [org.quartz.core.SchedulerSignalerImpl] Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 2016-03-19 10:54:32,939 [main] INFO [org.quartz.core.QuartzScheduler] Quartz Scheduler v.2.2.1 created. 2016-03-19 10:54:32,944 [main] INFO [org.quartz.simpl.RAMJobStore] RAMJobStore initialized. 2016-03-19 10:54:32,949 [main] INFO [org.quartz.core.QuartzScheduler] Scheduler meta-data: Quartz Scheduler (v2.2.1) 'DefaultQuartzScheduler' with instanceId 'NON_CLUSTERED' Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally. NOT STARTED. Currently in standby mode. Number of jobs executed: 0 Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 10 threads. Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.

2016-03-19 10:54:32,949 [main] INFO [org.quartz.impl.StdSchedulerFactory] Quartz scheduler 'DefaultQuartzScheduler' initialized from default resource file in Quartz package: 'quartz.properties' 2016-03-19 10:54:32,950 [main] INFO [org.quartz.impl.StdSchedulerFactory] Quartz scheduler version: 2.2.1 2016-03-19 10:54:32,951 [main] INFO [org.quartz.core.QuartzScheduler] Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started. 2016-03-19 10:54:32,963 [main] DEBUG [org.rpi.alarm.Alarm] Refreshing Alarms 2016-03-19 10:54:32,964 [main] DEBUG [org.rpi.alarm.Alarm] Clearing Schedules 2016-03-19 10:54:32,970 [main] INFO [org.rpi.os.OSManager] Start of LoadPlugins 2016-03-19 10:54:33,167 [main] INFO [org.rpi.os.OSManager] End of LoadPlugnis 2016-03-19 10:54:33,168 [main] DEBUG [org.rpi.main.SimpleDevice] Shut Down Hook Attached. 2016-03-19 10:54:34,492 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:54:40,483 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:54:46,474 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:54:52,463 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:54:58,454 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:00,808 [Thread-4] DEBUG [org.rpi.providers.PrvProduct] SetStandby: false Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:00,811 [Thread-5] DEBUG [org.rpi.providers.PrvProduct] SetStandby: false Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:00,814 [Thread-11] DEBUG [org.rpi.providers.PrvProduct] SetStandby: false Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:00,817 [Thread-4] DEBUG [org.rpi.providers.PrvProduct] Source Selected: PlayList 2016-03-19 10:55:00,818 [Thread-3] DEBUG [org.rpi.providers.PrvProduct] SetStandby: false Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:00,819 [Thread-4] DEBUG [org.rpi.providers.PrvProduct] SetStandby: false Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:00,820 [Thread-11] DEBUG [org.rpi.providers.PrvProduct] Source Selected: PlayList 2016-03-19 10:55:00,820 [Thread-5] DEBUG [org.rpi.providers.PrvProduct] Source Selected: PlayList 2016-03-19 10:55:00,822 [Thread-4] DEBUG [org.rpi.providers.PrvProduct] Source Selected: PlayList 2016-03-19 10:55:00,822 [Thread-3] DEBUG [org.rpi.providers.PrvProduct] Source Selected: PlayList 2016-03-19 10:55:00,850 [Thread-11] DEBUG [org.rpi.providers.PrvPlayList] ReadList: 1 2 Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:00,851 [Thread-11] DEBUG [org.rpi.providers.PrvPlayList] ReadList Contains : 2 1http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/01*20Hello.flac<DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="0$folders$f1$f2$_i151" parentID="0$folders$f1$f2" restricted="1"><dc:title xmlns:dc="http://purl.org/dc/elements/1.1/"&#62;Hello&#60;/dc:title&#62;&#60;upnp:genre xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Pop</upnp:genre><dc:date xmlns:dc="http://purl.org/dc/elements/1.1/"&#62;2015-01-01&#60;/dc:date&#62;&#60;upnp:album xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">25</upnp:album><upnp:artist xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Adele</upnp:artist><dc:creator xmlns:dc="http://purl.org/dc/elements/1.1/"&#62;Adele&#60;/dc:creator&#62;&#60;upnp:originalTrackNumber xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">1</upnp:originalTrackNumber><upnp:albumArtURI dlna:profileID="JPEGLRG" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">http://192.168.2.104:9790/minimserver//Musik/!Schimmanski/Adele_20-_2025_20(Target_20Deluxe_20Edition)_20(2015)_20_5bCD-FLAC_5d_20_5bsn3h1t87_5d_20_5bGloDLS5d/cover.jpg</upnp:albumArtURI><res duration="0:04:55.493" size="29144030" bitsPerSample="16" bitrate="176400" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get::audio/x-flac:DLNA.ORG_OP=01;DLNA.ORG_FLAGS=01700000000000000000000000000000">http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/01*20Hello.flac&#60;/res&#62;&#60;upnp:class xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>2http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/01*20Hello.flac<DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="0$folders$f1$f2$_i151" parentID="0$folders$f1$f2" restricted="1"><dc:title xmlns:dc="http://purl.org/dc/elements/1.1/"&#62;Hello&#60;/dc:title&#62;&#60;upnp:genre xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Pop</upnp:genre><dc:date xmlns:dc="http://purl.org/dc/elements/1.1/"&#62;2015-01-01&#60;/dc:date&#62;&#60;upnp:album xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">25</upnp:album><upnp:artist xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Adele</upnp:artist><dc:creator xmlns:dc="http://purl.org/dc/elements/1.1/"&#62;Adele&#60;/dc:creator&#62;&#60;upnp:originalTrackNumber xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">1</upnp:originalTrackNumber><upnp:albumArtURI dlna:profileID="JPEGLRG" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">http://192.168.2.104:9790/minimserver//Musik/!Schimmanski/Adele_20-_2025_20(Target_20Deluxe_20Edition)_20(2015)_20_5bCD-FLAC_5d_20_5bsn3h1t87_5d_20_5bGloDLS5d/cover.jpg</upnp:albumArtURI><res duration="0:04:55.493" size="29144030" bitsPerSample="16" bitrate="176400" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get::audio/x-flac:DLNA.ORG_OP=01;DLNA.ORG_FLAGS=01700000000000000000000000000000">http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/01*20Hello.flac&#60;/res&#62;&#60;upnp:class xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite> 2016-03-19 10:55:04,445 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:07,116 [Thread-5] DEBUG [org.rpi.providers.PrvPlayList] Insert After: 2 URI: http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/01*20Hello.flac MetaDate:

Hello/dc:titlePop/upnp:genre2015-01-01/dc:date25/upnp:albumAdele/upnp:artistAdele/dc:creator1/upnp:originalTrackNumberhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/cover.jpg/upnp:albumArtURIhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/01*20Hello.flacobject.item.audioItem.musicTrack/upnp:class Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:07,118 [Thread-5] DEBUG [org.rpi.providers.PrvPlayList] GetNextId: 3 2016-03-19 10:55:07,120 [Thread-5] DEBUG [org.rpi.channel.ChannelPlayList] Hello/dc:titlePop/upnp:genre2015-01-01/dc:date25/upnp:albumAdele/upnp:artistAdele/dc:creator1/upnp:originalTrackNumberhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/cover.jpg/upnp:albumArtURIhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/01*20Hello.flacobject.item.audioItem.musicTrack/upnp:class

2016-03-19 10:55:07,137 [Thread-5] DEBUG [org.rpi.providers.PrvPlayList] Insert Track Return: 3 2016-03-19 10:55:07,159 [Thread-4] DEBUG [org.rpi.providers.PrvPlayList] SeekIndex: 2 Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:07,161 [Thread-4] DEBUG [org.rpi.player.PlayManager] GetTrackFromIndex: 2 2016-03-19 10:55:07,161 [Thread-4] DEBUG [org.rpi.player.PlayManager] EventStatusChanged: Buffering 2016-03-19 10:55:07,162 [Thread-4] DEBUG [org.rpi.player.PlayManager] SetStatus: Buffering 2016-03-19 10:55:07,169 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Status: Buffering 2016-03-19 10:55:07,180 [Thread-4] DEBUG [org.rpi.radio.parsers.FileParser] FLAC File: http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Adele*20-*2025*20(Target*20Deluxe*20Edition)*20(2015)*20*5bCD-FLAC*5d*20*5bsn3h1t87*5d*20*5bGloDLS*5d/01*20Hello.flac 2016-03-19 10:55:07,183 [Thread-4] DEBUG [org.rpi.mpdplayer.MPDPlayer] ADD TO PLAYLIST{Id=3} 2016-03-19 10:55:07,194 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:08,177 [Thread-0] DEBUG [org.rpi.mpdplayer.StatusMonitor] Song Changed From : 2 To: 3 2016-03-19 10:55:08,181 [Thread-0] DEBUG [org.rpi.mpdplayer.StatusMonitor] Status Changed From : Stopped To: Playing 2016-03-19 10:55:08,182 [Thread-0] DEBUG [org.rpi.mpdplayer.MPDPlayer] Status Changed: Playing 2016-03-19 10:55:08,182 [Thread-0] DEBUG [org.rpi.player.PlayManager] EventStatusChanged: Playing 2016-03-19 10:55:08,182 [Thread-0] DEBUG [org.rpi.player.PlayManager] SetStatus: Playing 2016-03-19 10:55:08,193 [Thread-0] DEBUG [org.rpi.providers.PrvAVTransport] Status: Playing 2016-03-19 10:55:08,196 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] http://lyrics.wikia.com/Adele:Hello 2016-03-19 10:55:08,213 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:08,228 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:08,272 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:09,367 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] ### Found Lyrics. URL: http://lyrics.wikia.com/Adele:Hello 2016-03-19 10:55:10,212 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:10,218 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:10,241 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] TrackChanged: 'Adele' 'Hello' 2016-03-19 10:55:10,435 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:12,286 [PlayListWriter] DEBUG [org.rpi.playlist.PlayListWriter] PlayList Contains : 3 2016-03-19 10:55:12,287 [PlayListWriter] DEBUG [org.rpi.playlist.PlayListWriter] Saving PlayList: 2016-03-19 10:55:12,290 [PlayListWriter] DEBUG [org.rpi.playlist.PlayListWriter] Saved PlayList 2016-03-19 10:55:13,219 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:16,232 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:16,425 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:19,246 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:22,259 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:22,416 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:25,272 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:28,286 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:28,407 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:31,299 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:34,312 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:34,397 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:37,325 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:40,339 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:40,382 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:43,352 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:45,578 [Thread-5] DEBUG [org.rpi.providers.PrvPlayList] Insert After: 3 URI: http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/ACDC*20-*202014*20-*20Rock*20Or*20Bust*20*5bFLAC*5d*20*5bHawzerstar*5d/01*20Rock*20Or*20Bust.flac MetaDate:

Rock or Bust/dc:titleRock/upnp:genre2014-01-01/dc:dateRock Or Bust/upnp:albumAngus Young / Malcolm Young/upnp:artistAC/DC/upnp:artistAC/DC/dc:creator1/upnp:originalTrackNumberhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/ACDC*20-*202014*20-*20Rock*20Or*20Bust*20*5bFLAC*5d*20*5bHawzerstar*5d/01*20Rock*20Or*20Bust.flac/$!picture-88-3072882.jpg/upnp:albumArtURIhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/ACDC*20-*202014*20-*20Rock*20Or*20Bust*20*5bFLAC*5d*20*5bHawzerstar*5d/01*20Rock*20Or*20Bust.flacobject.item.audioItem.musicTrack/upnp:class Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:45,586 [Thread-5] DEBUG [org.rpi.providers.PrvPlayList] GetNextId: 4 2016-03-19 10:55:45,589 [Thread-5] DEBUG [org.rpi.channel.ChannelPlayList] Rock or Bust/dc:titleRock/upnp:genre2014-01-01/dc:dateRock Or Bust/upnp:albumAngus Young / Malcolm Young/upnp:artistAC/DC/upnp:artistAC/DC/dc:creator1/upnp:originalTrackNumberhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/ACDC*20-*202014*20-*20Rock*20Or*20Bust*20*5bFLAC*5d*20*5bHawzerstar*5d/01*20Rock*20Or*20Bust.flac/$!picture-88-3072882.jpg/upnp:albumArtURIhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/ACDC*20-*202014*20-*20Rock*20Or*20Bust*20*5bFLAC*5d*20*5bHawzerstar*5d/01*20Rock*20Or*20Bust.flacobject.item.audioItem.musicTrack/upnp:class

2016-03-19 10:55:45,602 [Thread-5] DEBUG [org.rpi.providers.PrvPlayList] Insert Track Return: 4 2016-03-19 10:55:45,622 [Thread-3] DEBUG [org.rpi.providers.PrvPlayList] ReadList: 4 Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:45,624 [Thread-3] DEBUG [org.rpi.providers.PrvPlayList] ReadList Contains : 1 4http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/ACDC*20-*202014*20-*20Rock*20Or*20Bust*20*5bFLAC*5d*20*5bHawzerstar*5d/01*20Rock*20Or*20Bust.flac<DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="0$folders$f1$f14$_i298" parentID="0$folders$f1$f14" restricted="1"><dc:title xmlns:dc="http://purl.org/dc/elements/1.1/"&#62;Rock or Bust</dc:title><upnp:genre xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Rock</upnp:genre><dc:date xmlns:dc="http://purl.org/dc/elements/1.1/"&#62;2014-01-01&#60;/dc:date&#62;&#60;upnp:album xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Rock Or Bust</upnp:album><upnp:artist role="Composer" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Angus Young / Malcolm Young</upnp:artist><upnp:artist xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">AC/DC</upnp:artist><dc:creator xmlns:dc="http://purl.org/dc/elements/1.1/"&#62;AC/DC&#60;/dc:creator&#62;&#60;upnp:originalTrackNumber xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">1</upnp:originalTrackNumber><upnp:albumArtURI dlna:profileID="JPEGLRG" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">http://192.168.2.104:9790/minimserver//Musik/!Schimmanski/ACDC_20-_202014_20-_20Rock_20Or_20Bust_20_5bFLAC_5d_20_5bHawzerstar_5d/01_20Rock_20Or20Bust.flac/$!picture-88-3072882.jpg</upnp:albumArtURI><res duration="0:03:03.248" size="30170203" bitsPerSample="16" bitrate="176400" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get::audio/x-flac:DLNA.ORG_OP=01;DLNA.ORG_FLAGS=01700000000000000000000000000000">http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/ACDC*20-*202014*20-*20Rock*20Or*20Bust*20*5bFLAC*5d*20*5bHawzerstar*5d/01*20Rock*20Or*20Bust.flac&#60;/res&#62;&#60;upnp:class xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite> 2016-03-19 10:55:45,623 [Thread-4] DEBUG [org.rpi.providers.PrvPlayList] SeekIndex: 3 Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:45,626 [Thread-4] DEBUG [org.rpi.player.PlayManager] GetTrackFromIndex: 3 2016-03-19 10:55:45,627 [Thread-4] DEBUG [org.rpi.player.PlayManager] EventStatusChanged: Buffering 2016-03-19 10:55:45,627 [Thread-4] DEBUG [org.rpi.player.PlayManager] SetStatus: Buffering 2016-03-19 10:55:45,628 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Status: Buffering 2016-03-19 10:55:45,632 [Thread-4] DEBUG [org.rpi.radio.parsers.FileParser] FLAC File: http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/ACDC*20-*202014*20-*20Rock*20Or*20Bust*20*5bFLAC*5d*20*5bHawzerstar*5d/01*20Rock*20Or*20Bust.flac 2016-03-19 10:55:45,654 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:45,657 [Thread-4] DEBUG [org.rpi.mpdplayer.MPDPlayer] ADD TO PLAYLIST{Id=4} 2016-03-19 10:55:46,396 [Thread-0] DEBUG [org.rpi.mpdplayer.StatusMonitor] Song Changed From : 3 To: 4 2016-03-19 10:55:46,397 [Thread-0] DEBUG [org.rpi.mpdplayer.MPDPlayer] Status Changed: Playing 2016-03-19 10:55:46,397 [Thread-0] DEBUG [org.rpi.player.PlayManager] EventStatusChanged: Playing 2016-03-19 10:55:46,397 [Thread-0] DEBUG [org.rpi.player.PlayManager] SetStatus: Playing 2016-03-19 10:55:46,401 [Thread-0] DEBUG [org.rpi.providers.PrvAVTransport] Status: Playing 2016-03-19 10:55:46,405 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] http://lyrics.wikia.com/AC/DC:Rock_or_Bust 2016-03-19 10:55:46,568 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:46,568 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:46,569 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:46,579 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:46,581 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:46,594 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:47,317 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] ### Found Lyrics. URL: http://lyrics.wikia.com/AC/DC:Rock_or_Bust 2016-03-19 10:55:48,021 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] TrackChanged: 'AC/DC' 'Rock or Bust' 2016-03-19 10:55:49,580 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:49,593 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:51,296 [PlayListWriter] DEBUG [org.rpi.playlist.PlayListWriter] PlayList Contains : 4 2016-03-19 10:55:51,297 [PlayListWriter] DEBUG [org.rpi.playlist.PlayListWriter] Saving PlayList: 2016-03-19 10:55:51,299 [PlayListWriter] DEBUG [org.rpi.playlist.PlayListWriter] Saved PlayList 2016-03-19 10:55:52,369 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:52,594 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:55,607 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:58,360 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:55:58,621 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:56:01,634 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:56:02,965 [Thread-3] DEBUG [org.rpi.providers.PrvPlayList] Insert After: 4 URI: http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Bob*20Marley*20-*20Legend*20Remastered*20(2012)*20*5b24-192*20HD*20FLAC*5d/2-No*20Woman*20No*20Cry*201975*20Live*20At.flac MetaDate:

No Woman, No Cry (1975 - Live At The Lyceum, London)/dc:titleReggae/upnp:genre2012-01-01/dc:dateLegend (Remastered)/upnp:albumVincent Ford/upnp:artistBob Marley & The Wailers/upnp:artistBob Marley & The Wailers/upnp:artistBob Marley & The Wailers/dc:creator2/upnp:originalTrackNumberhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Bob*20Marley*20-*20Legend*20Remastered*20(2012)*20*5b24-192*20HD*20FLAC*5d/2-No*20Woman*20No*20Cry*201975*20Live*20At.flac/$!picture-598-448097.jpg/upnp:albumArtURIhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Bob*20Marley*20-*20Legend*20Remastered*20(2012)*20*5b24-192*20HD*20FLAC*5d/2-No*20Woman*20No*20Cry*201975*20Live*20At.flacobject.item.audioItem.musicTrack/upnp:class Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:56:02,966 [Thread-3] DEBUG [org.rpi.providers.PrvPlayList] GetNextId: 5 2016-03-19 10:56:02,967 [Thread-3] DEBUG [org.rpi.channel.ChannelPlayList] No Woman, No Cry (1975 - Live At The Lyceum, London)/dc:titleReggae/upnp:genre2012-01-01/dc:dateLegend (Remastered)/upnp:albumVincent Ford/upnp:artistBob Marley & The Wailers/upnp:artistBob Marley & The Wailers/upnp:artistBob Marley & The Wailers/dc:creator2/upnp:originalTrackNumberhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Bob*20Marley*20-*20Legend*20Remastered*20(2012)*20*5b24-192*20HD*20FLAC*5d/2-No*20Woman*20No*20Cry*201975*20Live*20At.flac/$!picture-598-448097.jpg/upnp:albumArtURIhttp://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Bob*20Marley*20-*20Legend*20Remastered*20(2012)*20*5b24-192*20HD*20FLAC*5d/2-No*20Woman*20No*20Cry*201975*20Live*20At.flacobject.item.audioItem.musicTrack/upnp:class

2016-03-19 10:56:02,982 [Thread-3] DEBUG [org.rpi.providers.PrvPlayList] Insert Track Return: 5 2016-03-19 10:56:03,003 [Thread-4] DEBUG [org.rpi.providers.PrvPlayList] ReadList: 5 Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:56:03,005 [Thread-4] DEBUG [org.rpi.providers.PrvPlayList] ReadList Contains : 1 5http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Bob*20Marley*20-*20Legend*20Remastered*20(2012)*20*5b24-192*20HD*20FLAC*5d/2-No*20Woman*20No*20Cry*201975*20Live*20At.flac<DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"><item id="0$folders$f1$f6$_i255" parentID="0$folders$f1$f6" restricted="1"><dc:title xmlns:dc="http://purl.org/dc/elements/1.1/"&#62;No Woman, No Cry (1975 - Live At The Lyceum, London)</dc:title><upnp:genre xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Reggae</upnp:genre><dc:date xmlns:dc="http://purl.org/dc/elements/1.1/"&#62;2012-01-01&#60;/dc:date&#62;&#60;upnp:album xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Legend (Remastered)</upnp:album><upnp:artist role="Composer" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Vincent Ford</upnp:artist><upnp:artist role="AlbumArtist" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Bob Marley &amp; The Wailers</upnp:artist><upnp:artist xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">Bob Marley &amp; The Wailers</upnp:artist><dc:creator xmlns:dc="http://purl.org/dc/elements/1.1/"&#62;Bob Marley &amp; The Wailers</dc:creator><upnp:originalTrackNumber xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">2</upnp:originalTrackNumber><upnp:albumArtURI dlna:profileID="JPEGMED" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">http://192.168.2.104:9790/minimserver//Musik/!Schimmanski/Bob_20Marley_20-_20Legend_20Remastered_20(2012)_20_5b24-192_20HD_20FLAC_5d/2-No_20Woman_20No_20Cry_201975_20Live20At.flac/$!picture-598-448097.jpg</upnp:albumArtURI><res duration="0:07:09.556" size="284857354" bitsPerSample="24" bitrate="1152000" sampleFrequency="192000" nrAudioChannels="2" protocolInfo="http-get::audio/x-flac:DLNA.ORG_OP=01;DLNA.ORGFLAGS=01700000000000000000000000000000">http://192.168.2.104:9790/minimserver//Musik/!Schimmanski/Bob_20Marley_20-_20Legend_20Remastered_20(2012)_20_5b24-192_20HD_20FLAC_5d/2-No_20Woman_20No_20Cry_201975_20Live_20At.flac</res><upnp:class xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/">object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite> 2016-03-19 10:56:03,007 [Thread-5] DEBUG [org.rpi.providers.PrvPlayList] SeekIndex: 4 Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:56:03,008 [Thread-5] DEBUG [org.rpi.player.PlayManager] GetTrackFromIndex: 4 2016-03-19 10:56:03,008 [Thread-5] DEBUG [org.rpi.player.PlayManager] EventStatusChanged: Buffering 2016-03-19 10:56:03,008 [Thread-5] DEBUG [org.rpi.player.PlayManager] SetStatus: Buffering 2016-03-19 10:56:03,009 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] Status: Buffering 2016-03-19 10:56:03,009 [Thread-5] DEBUG [org.rpi.radio.parsers.FileParser] FLAC File: http://192.168.2.104:9790/minimserver/*/Musik/!Schimmanski/Bob*20Marley*20-*20Legend*20Remastered*20(2012)*20*5b24-192*20HD*20FLAC*5d/2-No*20Woman*20No*20Cry*201975*20Live*20At.flac 2016-03-19 10:56:03,035 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:56:03,083 [Thread-5] DEBUG [org.rpi.mpdplayer.MPDPlayer] ADD TO PLAYLIST{Id=5} 2016-03-19 10:56:03,530 [Thread-0] DEBUG [org.rpi.mpdplayer.StatusMonitor] Song Changed From : 4 To: 5 2016-03-19 10:56:03,530 [Thread-0] DEBUG [org.rpi.mpdplayer.MPDPlayer] Status Changed: Playing 2016-03-19 10:56:03,531 [Thread-0] DEBUG [org.rpi.player.PlayManager] EventStatusChanged: Playing 2016-03-19 10:56:03,531 [Thread-0] DEBUG [org.rpi.player.PlayManager] SetStatus: Playing 2016-03-19 10:56:03,532 [Thread-0] DEBUG [org.rpi.providers.PrvAVTransport] Status: Playing 2016-03-19 10:56:03,540 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] http://lyrics.wikia.com/Bob_Marley_&_The_Wailers:No_Woman,_No_Cry_(1975_-_Live_At_The_Lyceum,_London) 2016-03-19 10:56:03,592 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:56:03,602 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:56:04,351 [Thread-11] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:56:04,553 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] ### Found Lyrics. URL: http://lyrics.wikia.com/Bob_Marley_&_The_Wailers:No_Woman,_No_Cry_(1975_-_Live_At_The_Lyceum,_London) 2016-03-19 10:56:04,648 [Thread-5] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:56:04,662 [Thread-3] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:56:04,964 [EventHandler] DEBUG [org.rpi.web.longpolling.WorkqeueEvents] TrackChanged: 'Bob Marley & The Wailers' 'No Woman, No Cry (1975 - Live At The Lyceum, London)' 2016-03-19 10:56:07,660 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] Get Position Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1 2016-03-19 10:56:08,302 [PlayListWriter] DEBUG [org.rpi.playlist.PlayListWriter] PlayList Contains : 5 2016-03-19 10:56:08,303 [PlayListWriter] DEBUG [org.rpi.playlist.PlayListWriter] Saving PlayList: 2016-03-19 10:56:08,306 [PlayListWriter] DEBUG [org.rpi.playlist.PlayListWriter] Saved PlayList 2016-03-19 10:56:10,341 [Thread-4] DEBUG [org.rpi.providers.PrvAVTransport] GetTransport Info Adapter: 192.168.2.121 uriPrefix: http://192.168.2.121:52821/device-PiBerry-raspberrypi-MediaRenderer/Upnp/resource/ Version:1

PeteManchester commented 8 years ago

Test to see if didl lite is encoded when pasted to GitHub.

Start

2016-03-21 09:54:51,778 [Thread-10] DEBUG [org.rpi.providers.PrvPlayList] GetIdArray Adapter: 192.168.1.58 uriPrefix: http://192.168.1.58:52821/device-TestPlayer-7JF3N12-MediaRenderer/Upnp/resource/ Version:1 2016-03-21 09:54:51,791 [Thread-11] DEBUG [org.rpi.providers.PrvPlayList] Insert After: 18 URI: http://192.168.1.205:9790/minimserver/*/Music/Joy*20Division/Closer/1-06-Heart*20and*20Soul.flac MetaDate:

Heart and Soul/dc:titleobject.item.audioItem.musicTrack/upnp:classhttp://192.168.1.205:9790/minimserver/*/Music/Joy*20Division/Closer/1-06-Heart*20and*20Soul.flac/$!picture-904-138547.jpg?connection=close/upnp:albumArtURICloser/upnp:albumJoy Division/upnp:artistJoy Division/upnp:artistBernard Albrecht, Ian Curtis, Peter Hook, Stephen Morris, Bernard Sumner/upnp:artist1980-07-18/dc:datePop/Rock/upnp:genrehttp://192.168.1.205:9790/minimserver/*/Music/Joy*20Division/Closer/1-06-Heart*20and*20Soul.flac Adapter: 192.168.1.58 uriPrefix: http://192.168.1.58:52821/device-TestPlayer-7JF3N12-MediaRenderer/Upnp/resource/ Version:1 2016-03-21 09:54:51,791 [Thread-11] DEBUG [org.rpi.providers.PrvPlayList] GetNextId: 19 2016-03-21 09:54:51,791 [Thread-11] DEBUG [org.rpi.channel.ChannelPlayList] Heart and Soul/dc:titleobject.item.audioItem.musicTrack/upnp:classhttp://192.168.1.205:9790/minimserver/*/Music/Joy*20Division/Closer/1-06-Heart*20and*20Soul.flac/$!picture-904-138547.jpg?connection=close/upnp:albumArtURICloser/upnp:albumJoy Division/upnp:artistJoy Division/upnp:artistBernard Albrecht, Ian Curtis, Peter Hook, Stephen Morris, Bernard Sumner/upnp:artist1980-07-18/dc:datePop/Rock/upnp:genrehttp://192.168.1.205:9790/minimserver/*/Music/Joy*20Division/Closer/1-06-Heart*20and*20Soul.flac

2016-03-21 09:54:51,793 [Thread-11] DEBUG [org.rpi.providers.PrvPlayList] Insert Track Return: 19 2016-03-21 09:54:51,801 [Thread-7] DEBUG [org.rpi.providers.PrvProduct] SetSourceIndex: 5 Adapter: 192.168.1.58 uriPrefix: http://192.168.1.58:52821/device-TestPlayer-7JF3N12-MediaRenderer/Upnp/resource/ Version:1 2016-03-21 09:54:51,801 [Thread-7] DEBUG [org.rpi.providers.PrvProduct] Source Selected: PlayList 2016-03-21 09:54:51,802 [Thread-7] DEBUG [org.rpi.providers.PrvPlayList] SeekId: 19 Adapter: 192.168.1.58 uriPrefix: http://192.168.1.58:52821/device-TestPlayer-7JF3N12-MediaRenderer/Upnp/resource/ Version:1 2016-03-21 09:54:51,802 [Thread-7] DEBUG [org.rpi.player.PlayManager] GetTrakcFromId: 19 2016-03-21 09:54:51,802 [Thread-7] DEBUG [org.rpi.providers.PrvProduct] Source Selected: PlayList

End

PeteManchester commented 8 years ago

Hi, I'm still scratching my head with this one, I can't recreate it in my environment..

If possible can you do the test again one time using Kazoo and adding a track to the playlist and playing it, then using Kinsky to play the same track as the previous test in UPNP.

If you could email me the two log files to pete.hoyle1@gmail.com please, I think there is some encoding happening when posting to GitHub..

Thanks,

Pete.

ghost commented 6 years ago

I'm seeing this problem as well. There is nothing particularly special about the files I am playing - they are simply mp3s and are being served up by minimserver. Can you help?

PeteManchester commented 6 years ago

Try this latest version:

https://github.com/PeteManchester/MediaPlayer/releases/tag/v20180320

And let me know how it goes..

ghost commented 6 years ago

Thanks Pete - it is working as expected in that new version. My mistake for not testing the latest version.