-- Logs begin at Wed 2024-07-03 18:07:24 CEST, end at Sun 2024-10-27 19:55:30 CET. -- Oct 27 19:54:00 volumio volumio[845]: ..++++ Oct 27 19:54:01 volumio volumio[648]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 27 19:54:01 volumio volumio[648]: info: MYVOLUMIO: Adding device Oct 27 19:54:01 volumio volumio[648]: info: MYVOLUMIO: Evaluating Server Oct 27 19:54:02 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 19:54:02 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 19:54:02 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 27 19:54:02 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 27 19:54:02 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 27 19:54:02 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 27 19:54:02 volumio volumio[648]: info: CoreCommandRouter::volumioGetBrowseSources Oct 27 19:54:02 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 19:54:06 volumio volumio[648]: info: MyVolumio status changed Oct 27 19:54:06 volumio volumio[648]: info: Streaming services startup Oct 27 19:54:06 volumio volumio[648]: info: Starting Streaming Daemon Oct 27 19:54:07 volumio volumio[648]: info: Removing browser output: myVolumio user plan is not superstar Oct 27 19:54:07 volumio volumio[648]: info: Removing audio output: Oct 27 19:54:07 volumio volumio[648]: info: Stoppping Tunnel 1 Oct 27 19:54:07 volumio sudo[966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 27 19:54:07 volumio sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 19:54:08 volumio volumio[648]: info: CoreCommandRouter::volumioGetState Oct 27 19:54:08 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:08 volumio sudo[966]: pam_unix(sudo:session): session closed for user root Oct 27 19:54:08 volumio sudo[968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 27 19:54:08 volumio sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 19:54:08 volumio sudo[968]: pam_unix(sudo:session): session closed for user root Oct 27 19:54:09 volumio volumio[648]: info: Setting Geolocation for MyVolumio to eu4 Oct 27 19:54:09 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 19:54:09 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 19:54:09 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 19:54:10 volumio volumio[648]: error: Cannot start Volumio Streaming Daemon Oct 27 19:54:10 volumio volumio[648]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 27 19:54:10 volumio volumio[648]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 27 19:54:10 volumio volumio[648]: info: Remote SSH Stopped Oct 27 19:54:10 volumio volumio[648]: info: Updating MyVolumio device info Oct 27 19:54:10 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 19:54:10 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 19:54:10 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 19:54:11 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 19:54:11 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 19:54:11 volumio volumio[648]: info: Discovery: Getting this device information Oct 27 19:54:11 volumio volumio[648]: info: CoreCommandRouter::volumioGetState Oct 27 19:54:11 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:11 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 19:54:12 volumio volumio[648]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 27 19:54:12 volumio volumio[648]: info: Completed starting MyVolumio Plugin Oct 27 19:54:12 volumio volumio[648]: info: BOOT COMPLETED Oct 27 19:54:12 volumio volumio[648]: [Metrics] CommandRouter: 117s 730.51ms Oct 27 19:54:12 volumio volumio[648]: info: CoreCommandRouter::volumiosetStartupVolume Oct 27 19:54:12 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 19:54:12 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 19:54:13 volumio volumio[648]: info: CoreCommandRouter::Close All Modals sent Oct 27 19:54:13 volumio volumio[648]: info: CoreCommandRouter::Close All Modals sent Oct 27 19:54:14 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 27 19:54:14 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 27 19:54:14 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 27 19:54:15 volumio volumio[648]: error: Failed to add MyVolumio device: {"message":"Unauthenticated"} Oct 27 19:54:15 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 19:54:15 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 19:54:15 volumio volumio[648]: info: Discovery: Getting this device information Oct 27 19:54:15 volumio volumio[648]: info: CoreCommandRouter::volumioGetState Oct 27 19:54:15 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:15 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 19:54:15 volumio volumio[648]: error: Failed to update MyVolumio device: {"message":"Unauthenticated"} Oct 27 19:54:16 volumio volumio[648]: verbose: New Socket.io Connection to 192.168.1.66:3000 from 192.168.1.69 UA: Dart/3.4 (dart:io) Total Clients: 4 Oct 27 19:54:17 volumio volumio[648]: info: CoreCommandRouter::volumioGetState Oct 27 19:54:17 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:17 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 27 19:54:17 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 27 19:54:18 volumio volumio[648]: STREAMING PROXY: Starting server on port 3245 Oct 27 19:54:18 volumio volumio[648]: Node JS runtime: 14 Oct 27 19:54:27 volumio sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 19:54:27 volumio sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 19:54:27 volumio sudo[978]: pam_unix(sudo:session): session closed for user root Oct 27 19:54:27 volumio sudo[980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 19:54:27 volumio sudo[980]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 19:54:27 volumio sudo[980]: pam_unix(sudo:session): session closed for user root Oct 27 19:54:27 volumio volumio[648]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.69 UA: Mozilla/5.0 (Linux; Android 14; 2109119DG Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.102 Mobile Safari/537.36 Total Clients: 5 Oct 27 19:54:29 volumio sudo[984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 19:54:29 volumio sudo[984]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 19:54:29 volumio sudo[984]: pam_unix(sudo:session): session closed for user root Oct 27 19:54:29 volumio sudo[986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 19:54:29 volumio sudo[986]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 19:54:29 volumio sudo[986]: pam_unix(sudo:session): session closed for user root Oct 27 19:54:29 volumio volumio[648]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.69 UA: Mozilla/5.0 (Linux; Android 14; 2109119DG Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.102 Mobile Safari/537.36 Total Clients: 6 Oct 27 19:54:30 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 27 19:54:30 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 27 19:54:30 volumio volumio[648]: info: CoreCommandRouter::volumioGetVisibleSources Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::volumioGetState Oct 27 19:54:31 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::volumioGetQueue Oct 27 19:54:31 volumio volumio[648]: info: CoreStateMachine::getQueue Oct 27 19:54:31 volumio volumio[648]: info: CorePlayQueue::getQueue Oct 27 19:54:31 volumio volumio[648]: info: Listing playlists Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 27 19:54:31 volumio volumio[648]: info: Received Get System Info Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 19:54:31 volumio volumio[648]: info: Discovery: Getting this device information Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::volumioGetState Oct 27 19:54:31 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::volumioGetState Oct 27 19:54:31 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 19:54:31 volumio volumio[648]: info: Received Get System Info Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 19:54:31 volumio volumio[648]: info: Discovery: Getting this device information Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::volumioGetState Oct 27 19:54:31 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:31 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 19:54:33 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 19:54:33 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 27 19:54:33 volumio volumio[648]: info: Received Get System Info Oct 27 19:54:33 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 19:54:33 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 19:54:33 volumio volumio[648]: info: Discovery: Getting this device information Oct 27 19:54:33 volumio volumio[648]: info: CoreCommandRouter::volumioGetState Oct 27 19:54:33 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:33 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 19:54:34 volumio volumio[648]: info: Preload queue cleared Oct 27 19:54:34 volumio volumio[648]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 27 19:54:34 volumio volumio[648]: info: CoreStateMachine::ClearQueue Oct 27 19:54:34 volumio volumio[648]: info: CoreStateMachine::stop Oct 27 19:54:34 volumio volumio[648]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 19:54:34 volumio volumio[648]: info: CorePlayQueue::clearPlayQueue Oct 27 19:54:34 volumio volumio[648]: info: CorePlayQueue::saveQueue Oct 27 19:54:34 volumio volumio[648]: info: CoreCommandRouter::volumioPushQueue Oct 27 19:54:34 volumio volumio[648]: info: CoreStateMachine::addQueueItems Oct 27 19:54:34 volumio volumio[648]: info: CorePlayQueue::addQueueItems Oct 27 19:54:34 volumio volumio[648]: info: Preload queue cleared Oct 27 19:54:34 volumio volumio[648]: info: Adding Item to queue: http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ Oct 27 19:54:34 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 27 19:54:34 volumio volumio[648]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=191920 Oct 27 19:54:34 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 27 19:54:34 volumio volumio[648]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=578543 Oct 27 19:54:34 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 27 19:54:34 volumio volumio[648]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=672282 Oct 27 19:54:34 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 27 19:54:34 volumio volumio[648]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1786948 Oct 27 19:54:34 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 27 19:54:34 volumio volumio[648]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1299670 Oct 27 19:54:34 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 27 19:54:34 volumio volumio[648]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99440417 Oct 27 19:54:34 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 27 19:54:34 volumio volumio[648]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=808453 Oct 27 19:54:34 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 27 19:54:34 volumio volumio[648]: info: CoreCommandRouter::volumioPushQueue Oct 27 19:54:34 volumio volumio[648]: info: CorePlayQueue::saveQueue Oct 27 19:54:34 volumio volumio[648]: info: CoreStateMachine::updateTrackBlock Oct 27 19:54:34 volumio volumio[648]: info: CorePlayQueue::getTrackBlock Oct 27 19:54:34 volumio volumio[648]: info: CoreCommandRouter::volumioPlay Oct 27 19:54:34 volumio volumio[648]: info: CoreStateMachine::play index 0 Oct 27 19:54:34 volumio volumio[648]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 19:54:34 volumio volumio[648]: info: CoreStateMachine::stop Oct 27 19:54:34 volumio volumio[648]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 19:54:34 volumio volumio[648]: info: CoreStateMachine::play index undefined Oct 27 19:54:34 volumio volumio[648]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 19:54:34 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:34 volumio volumio[648]: info: CoreStateMachine::startPlaybackTimer Oct 27 19:54:34 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:34 volumio volumio[648]: info: [1730055274620] ControllerWebradio::clearAddPlayTrack Oct 27 19:54:34 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 19:54:34 volumio volumio[648]: info: sendMpdCommand stop took 103 milliseconds Oct 27 19:54:34 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 19:54:34 volumio volumio[648]: info: Oct 27 19:54:34 volumio volumio[648]: ---------------------------- MPD announces system playlist update Oct 27 19:54:34 volumio volumio[648]: info: Ignoring MPD Status Update Oct 27 19:54:34 volumio volumio[648]: info: sendMpdCommand clear took 108 milliseconds Oct 27 19:54:34 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand load "http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ" Oct 27 19:54:34 volumio volumio[648]: error: updateQueue error: null Oct 27 19:54:34 volumio volumio[648]: info: ------------------------------ 111ms Oct 27 19:54:35 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 19:54:35 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 19:54:35 volumio volumio[648]: info: Discovery: Getting this device information Oct 27 19:54:35 volumio volumio[648]: info: CoreCommandRouter::volumioGetState Oct 27 19:54:35 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:35 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 19:54:35 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand add "http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ" Oct 27 19:54:35 volumio volumio[648]: info: Oct 27 19:54:35 volumio volumio[648]: ---------------------------- MPD announces system playlist update Oct 27 19:54:35 volumio volumio[648]: info: Ignoring MPD Status Update Oct 27 19:54:35 volumio volumio[648]: info: sendMpdCommand add "http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ" took 19 milliseconds Oct 27 19:54:35 volumio volumio[648]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 19:54:35 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand play Oct 27 19:54:35 volumio volumio[648]: info: ------------------------------ 83ms Oct 27 19:54:35 volumio volumio[648]: info: sendMpdCommand play took 55 milliseconds Oct 27 19:54:35 volumio volumio[648]: info: Oct 27 19:54:35 volumio volumio[648]: ---------------------------- MPD announces state update: player Oct 27 19:54:35 volumio volumio[648]: info: ControllerMpd::getState Oct 27 19:54:35 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand status Oct 27 19:54:35 volumio kernel: bcm2835-i2s 20203000.i2s: I2S SYNC error! Oct 27 19:54:35 volumio volumio[648]: info: sendMpdCommand status took 66 milliseconds Oct 27 19:54:35 volumio volumio[648]: verbose: ControllerMpd::parseState Oct 27 19:54:35 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 19:54:35 volumio volumio[648]: info: Oct 27 19:54:35 volumio volumio[648]: ---------------------------- MPD announces state update: player Oct 27 19:54:35 volumio volumio[648]: info: ControllerMpd::getState Oct 27 19:54:35 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand status Oct 27 19:54:35 volumio volumio[648]: info: sendMpdCommand playlistinfo took 65 milliseconds Oct 27 19:54:35 volumio volumio[648]: verbose: ControllerMpd::parseTrackInfo Oct 27 19:54:35 volumio volumio[648]: info: ControllerMpd::pushState Oct 27 19:54:35 volumio volumio[648]: info: CoreCommandRouter::servicePushState Oct 27 19:54:35 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:35 volumio volumio[648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","artist":null,"album":null,"uri":"http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","trackType":""} Oct 27 19:54:35 volumio volumio[648]: verbose: CURRENT POSITION 0 Oct 27 19:54:35 volumio volumio[648]: info: CoreStateMachine::syncState stateService play Oct 27 19:54:35 volumio volumio[648]: info: CoreStateMachine::syncState currentStatus stop Oct 27 19:54:35 volumio volumio[648]: info: ------------------------------ 565ms Oct 27 19:54:36 volumio volumio[648]: info: Oct 27 19:54:36 volumio volumio[648]: ---------------------------- MPD announces system playlist update Oct 27 19:54:36 volumio volumio[648]: info: Ignoring MPD Status Update Oct 27 19:54:36 volumio volumio[648]: info: Oct 27 19:54:36 volumio volumio[648]: ---------------------------- MPD announces state update: player Oct 27 19:54:36 volumio volumio[648]: info: sendMpdCommand status took 414 milliseconds Oct 27 19:54:36 volumio volumio[648]: info: ControllerMpd::getState Oct 27 19:54:36 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand status Oct 27 19:54:36 volumio volumio[648]: verbose: ControllerMpd::parseState Oct 27 19:54:36 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 19:54:36 volumio volumio[648]: info: Oct 27 19:54:36 volumio volumio[648]: ---------------------------- MPD announces system playlist update Oct 27 19:54:36 volumio volumio[648]: info: Ignoring MPD Status Update Oct 27 19:54:36 volumio volumio[648]: info: Oct 27 19:54:36 volumio volumio[648]: ---------------------------- MPD announces state update: player Oct 27 19:54:36 volumio volumio[648]: info: ControllerMpd::getState Oct 27 19:54:36 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand status Oct 27 19:54:36 volumio volumio[648]: info: ------------------------------ 216ms Oct 27 19:54:36 volumio volumio[648]: info: sendMpdCommand status took 139 milliseconds Oct 27 19:54:36 volumio volumio[648]: info: sendMpdCommand playlistinfo took 131 milliseconds Oct 27 19:54:36 volumio volumio[648]: verbose: ControllerMpd::parseState Oct 27 19:54:36 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 19:54:36 volumio volumio[648]: verbose: ControllerMpd::parseTrackInfo Oct 27 19:54:36 volumio volumio[648]: info: ControllerMpd::pushState Oct 27 19:54:36 volumio volumio[648]: info: CoreCommandRouter::servicePushState Oct 27 19:54:36 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:36 volumio volumio[648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","artist":"mpeg","album":null,"uri":"http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","trackType":""} Oct 27 19:54:36 volumio volumio[648]: verbose: CURRENT POSITION 0 Oct 27 19:54:36 volumio volumio[648]: info: CoreStateMachine::syncState stateService play Oct 27 19:54:36 volumio volumio[648]: info: CoreStateMachine::syncState currentStatus play Oct 27 19:54:36 volumio volumio[648]: info: Received an update from plugin. extracting info from payload Oct 27 19:54:36 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:54:36 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:36 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 19:54:36 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:54:36 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:54:36 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:54:36 volumio volumio[648]: info: ------------------------------ 1256ms Oct 27 19:54:36 volumio volumio[648]: info: ------------------------------ 721ms Oct 27 19:54:36 volumio volumio[648]: info: sendMpdCommand status took 669 milliseconds Oct 27 19:54:36 volumio volumio[648]: info: sendMpdCommand playlistinfo took 598 milliseconds Oct 27 19:54:36 volumio volumio[648]: verbose: ControllerMpd::parseState Oct 27 19:54:36 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 19:54:36 volumio volumio[648]: verbose: ControllerMpd::parseTrackInfo Oct 27 19:54:36 volumio volumio[648]: info: ControllerMpd::pushState Oct 27 19:54:36 volumio volumio[648]: info: CoreCommandRouter::servicePushState Oct 27 19:54:36 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:36 volumio volumio[648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1087,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","artist":"mpeg","album":null,"uri":"http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","trackType":""} Oct 27 19:54:36 volumio volumio[648]: verbose: CURRENT POSITION 0 Oct 27 19:54:36 volumio volumio[648]: info: CoreStateMachine::syncState stateService play Oct 27 19:54:36 volumio volumio[648]: info: CoreStateMachine::syncState currentStatus play Oct 27 19:54:36 volumio volumio[648]: info: Received an update from plugin. extracting info from payload Oct 27 19:54:36 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:54:36 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:36 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 19:54:36 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:54:37 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:54:37 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:54:37 volumio volumio[648]: info: ------------------------------ 1263ms Oct 27 19:54:37 volumio volumio[648]: info: sendMpdCommand playlistinfo took 607 milliseconds Oct 27 19:54:37 volumio volumio[648]: verbose: ControllerMpd::parseTrackInfo Oct 27 19:54:37 volumio volumio[648]: info: ControllerMpd::pushState Oct 27 19:54:37 volumio volumio[648]: info: CoreCommandRouter::servicePushState Oct 27 19:54:37 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:37 volumio volumio[648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1281,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","artist":"mpeg","album":null,"uri":"http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","trackType":""} Oct 27 19:54:37 volumio volumio[648]: verbose: CURRENT POSITION 0 Oct 27 19:54:37 volumio volumio[648]: info: CoreStateMachine::syncState stateService play Oct 27 19:54:37 volumio volumio[648]: info: CoreStateMachine::syncState currentStatus play Oct 27 19:54:37 volumio volumio[648]: info: Received an update from plugin. extracting info from payload Oct 27 19:54:37 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:54:37 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:37 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 19:54:37 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:54:37 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:54:37 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:54:37 volumio volumio[648]: info: ------------------------------ 1684ms Oct 27 19:54:40 volumio volumio[648]: info: Oct 27 19:54:40 volumio volumio[648]: ---------------------------- MPD announces system playlist update Oct 27 19:54:40 volumio volumio[648]: info: Ignoring MPD Status Update Oct 27 19:54:40 volumio volumio[648]: info: Oct 27 19:54:40 volumio volumio[648]: ---------------------------- MPD announces state update: player Oct 27 19:54:40 volumio volumio[648]: info: ControllerMpd::getState Oct 27 19:54:40 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand status Oct 27 19:54:40 volumio volumio[648]: info: ------------------------------ 58ms Oct 27 19:54:40 volumio volumio[648]: info: sendMpdCommand status took 33 milliseconds Oct 27 19:54:40 volumio volumio[648]: verbose: ControllerMpd::parseState Oct 27 19:54:40 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 19:54:40 volumio volumio[648]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 27 19:54:40 volumio volumio[648]: verbose: ControllerMpd::parseTrackInfo Oct 27 19:54:40 volumio volumio[648]: info: ControllerMpd::pushState Oct 27 19:54:40 volumio volumio[648]: info: CoreCommandRouter::servicePushState Oct 27 19:54:40 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:40 volumio volumio[648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5281,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","artist":"mpeg","album":null,"uri":"http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","trackType":""} Oct 27 19:54:40 volumio volumio[648]: verbose: CURRENT POSITION 0 Oct 27 19:54:40 volumio volumio[648]: info: CoreStateMachine::syncState stateService play Oct 27 19:54:40 volumio volumio[648]: info: CoreStateMachine::syncState currentStatus play Oct 27 19:54:40 volumio volumio[648]: info: Received an update from plugin. extracting info from payload Oct 27 19:54:40 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:54:40 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:40 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 19:54:40 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:54:40 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:54:40 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:54:40 volumio volumio[648]: info: ------------------------------ 475ms Oct 27 19:54:46 volumio volumio[648]: info: Oct 27 19:54:46 volumio volumio[648]: ---------------------------- MPD announces system playlist update Oct 27 19:54:46 volumio volumio[648]: info: Ignoring MPD Status Update Oct 27 19:54:46 volumio volumio[648]: info: Oct 27 19:54:46 volumio volumio[648]: ---------------------------- MPD announces state update: player Oct 27 19:54:46 volumio volumio[648]: info: ControllerMpd::getState Oct 27 19:54:46 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand status Oct 27 19:54:46 volumio volumio[648]: info: ------------------------------ 43ms Oct 27 19:54:46 volumio volumio[648]: info: sendMpdCommand status took 47 milliseconds Oct 27 19:54:46 volumio volumio[648]: verbose: ControllerMpd::parseState Oct 27 19:54:46 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 19:54:46 volumio volumio[648]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 27 19:54:46 volumio volumio[648]: verbose: ControllerMpd::parseTrackInfo Oct 27 19:54:46 volumio volumio[648]: info: ControllerMpd::pushState Oct 27 19:54:46 volumio volumio[648]: info: CoreCommandRouter::servicePushState Oct 27 19:54:46 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:46 volumio volumio[648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11186,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","artist":"mpeg","album":null,"uri":"http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","trackType":""} Oct 27 19:54:46 volumio volumio[648]: verbose: CURRENT POSITION 0 Oct 27 19:54:46 volumio volumio[648]: info: CoreStateMachine::syncState stateService play Oct 27 19:54:46 volumio volumio[648]: info: CoreStateMachine::syncState currentStatus play Oct 27 19:54:46 volumio volumio[648]: info: Received an update from plugin. extracting info from payload Oct 27 19:54:46 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:54:46 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:46 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 19:54:46 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:54:46 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:54:46 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:54:46 volumio volumio[648]: info: ------------------------------ 547ms Oct 27 19:54:46 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 19:54:46 volumio volumio[648]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 27 19:54:46 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 27 19:54:47 volumio volumio[648]: info: Received Get System Version Oct 27 19:54:47 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 19:54:47 volumio volumio[648]: info: Received Get System Info Oct 27 19:54:47 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 19:54:47 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 19:54:47 volumio volumio[648]: info: Discovery: Getting this device information Oct 27 19:54:47 volumio volumio[648]: info: CoreCommandRouter::volumioGetState Oct 27 19:54:47 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 19:54:52 volumio volumio[648]: info: Oct 27 19:54:52 volumio volumio[648]: ---------------------------- MPD announces system playlist update Oct 27 19:54:52 volumio volumio[648]: info: Ignoring MPD Status Update Oct 27 19:54:52 volumio volumio[648]: info: Oct 27 19:54:52 volumio volumio[648]: ---------------------------- MPD announces state update: player Oct 27 19:54:52 volumio volumio[648]: info: ControllerMpd::getState Oct 27 19:54:52 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand status Oct 27 19:54:52 volumio volumio[648]: info: ------------------------------ 50ms Oct 27 19:54:52 volumio volumio[648]: info: sendMpdCommand status took 25 milliseconds Oct 27 19:54:52 volumio volumio[648]: verbose: ControllerMpd::parseState Oct 27 19:54:52 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 19:54:52 volumio volumio[648]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 27 19:54:52 volumio volumio[648]: verbose: ControllerMpd::parseTrackInfo Oct 27 19:54:52 volumio volumio[648]: info: ControllerMpd::pushState Oct 27 19:54:52 volumio volumio[648]: info: CoreCommandRouter::servicePushState Oct 27 19:54:52 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:52 volumio volumio[648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","artist":"mpeg","album":null,"uri":"http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","trackType":""} Oct 27 19:54:52 volumio volumio[648]: verbose: CURRENT POSITION 0 Oct 27 19:54:52 volumio volumio[648]: info: CoreStateMachine::syncState stateService play Oct 27 19:54:52 volumio volumio[648]: info: CoreStateMachine::syncState currentStatus play Oct 27 19:54:52 volumio volumio[648]: info: Received an update from plugin. extracting info from payload Oct 27 19:54:52 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:54:52 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:52 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 19:54:52 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:54:52 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:54:52 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:54:52 volumio volumio[648]: info: ------------------------------ 420ms Oct 27 19:54:58 volumio volumio[648]: info: Oct 27 19:54:58 volumio volumio[648]: ---------------------------- MPD announces system playlist update Oct 27 19:54:58 volumio volumio[648]: info: Ignoring MPD Status Update Oct 27 19:54:58 volumio volumio[648]: info: Oct 27 19:54:58 volumio volumio[648]: ---------------------------- MPD announces state update: player Oct 27 19:54:58 volumio volumio[648]: info: ControllerMpd::getState Oct 27 19:54:58 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand status Oct 27 19:54:58 volumio volumio[648]: info: ------------------------------ 58ms Oct 27 19:54:58 volumio volumio[648]: info: sendMpdCommand status took 26 milliseconds Oct 27 19:54:58 volumio volumio[648]: verbose: ControllerMpd::parseState Oct 27 19:54:58 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 19:54:58 volumio volumio[648]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 27 19:54:58 volumio volumio[648]: verbose: ControllerMpd::parseTrackInfo Oct 27 19:54:58 volumio volumio[648]: info: ControllerMpd::pushState Oct 27 19:54:58 volumio volumio[648]: info: CoreCommandRouter::servicePushState Oct 27 19:54:58 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:58 volumio volumio[648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23777,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","artist":"mpeg","album":null,"uri":"http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","trackType":""} Oct 27 19:54:58 volumio volumio[648]: verbose: CURRENT POSITION 0 Oct 27 19:54:58 volumio volumio[648]: info: CoreStateMachine::syncState stateService play Oct 27 19:54:58 volumio volumio[648]: info: CoreStateMachine::syncState currentStatus play Oct 27 19:54:58 volumio volumio[648]: info: Received an update from plugin. extracting info from payload Oct 27 19:54:58 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:54:58 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:54:58 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 19:54:58 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:54:59 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:54:59 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:54:59 volumio volumio[648]: info: ------------------------------ 424ms Oct 27 19:55:04 volumio volumio[648]: info: Oct 27 19:55:04 volumio volumio[648]: ---------------------------- MPD announces system playlist update Oct 27 19:55:04 volumio volumio[648]: info: Ignoring MPD Status Update Oct 27 19:55:04 volumio volumio[648]: info: Oct 27 19:55:04 volumio volumio[648]: ---------------------------- MPD announces state update: player Oct 27 19:55:04 volumio volumio[648]: info: ControllerMpd::getState Oct 27 19:55:04 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand status Oct 27 19:55:04 volumio volumio[648]: info: ------------------------------ 51ms Oct 27 19:55:04 volumio volumio[648]: info: sendMpdCommand status took 25 milliseconds Oct 27 19:55:04 volumio volumio[648]: verbose: ControllerMpd::parseState Oct 27 19:55:04 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 19:55:04 volumio volumio[648]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 27 19:55:04 volumio volumio[648]: verbose: ControllerMpd::parseTrackInfo Oct 27 19:55:04 volumio volumio[648]: info: ControllerMpd::pushState Oct 27 19:55:04 volumio volumio[648]: info: CoreCommandRouter::servicePushState Oct 27 19:55:04 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:55:04 volumio volumio[648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29681,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","artist":"mpeg","album":null,"uri":"http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","trackType":""} Oct 27 19:55:04 volumio volumio[648]: verbose: CURRENT POSITION 0 Oct 27 19:55:04 volumio volumio[648]: info: CoreStateMachine::syncState stateService play Oct 27 19:55:04 volumio volumio[648]: info: CoreStateMachine::syncState currentStatus play Oct 27 19:55:04 volumio volumio[648]: info: Received an update from plugin. extracting info from payload Oct 27 19:55:04 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:55:04 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:55:04 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 19:55:04 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:55:04 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:55:04 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:55:05 volumio volumio[648]: info: ------------------------------ 426ms Oct 27 19:55:05 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 19:55:05 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 19:55:05 volumio volumio[648]: info: Discovery: Getting this device information Oct 27 19:55:05 volumio volumio[648]: info: CoreCommandRouter::volumioGetState Oct 27 19:55:05 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 19:55:09 volumio volumio[648]: info: Oct 27 19:55:09 volumio volumio[648]: ---------------------------- MPD announces system playlist update Oct 27 19:55:10 volumio volumio[648]: info: Ignoring MPD Status Update Oct 27 19:55:10 volumio volumio[648]: info: Oct 27 19:55:10 volumio volumio[648]: ---------------------------- MPD announces state update: player Oct 27 19:55:10 volumio volumio[648]: info: ControllerMpd::getState Oct 27 19:55:10 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand status Oct 27 19:55:10 volumio volumio[648]: info: ------------------------------ 59ms Oct 27 19:55:10 volumio volumio[648]: info: sendMpdCommand status took 48 milliseconds Oct 27 19:55:10 volumio volumio[648]: verbose: ControllerMpd::parseState Oct 27 19:55:10 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 19:55:10 volumio volumio[648]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 27 19:55:10 volumio volumio[648]: verbose: ControllerMpd::parseTrackInfo Oct 27 19:55:10 volumio volumio[648]: info: ControllerMpd::pushState Oct 27 19:55:10 volumio volumio[648]: info: CoreCommandRouter::servicePushState Oct 27 19:55:10 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:55:10 volumio volumio[648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","artist":"mpeg","album":null,"uri":"http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","trackType":""} Oct 27 19:55:10 volumio volumio[648]: verbose: CURRENT POSITION 0 Oct 27 19:55:10 volumio volumio[648]: info: CoreStateMachine::syncState stateService play Oct 27 19:55:10 volumio volumio[648]: info: CoreStateMachine::syncState currentStatus play Oct 27 19:55:10 volumio volumio[648]: info: Received an update from plugin. extracting info from payload Oct 27 19:55:10 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:55:10 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:55:10 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 19:55:10 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:55:10 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:55:10 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:55:10 volumio volumio[648]: info: ------------------------------ 442ms Oct 27 19:55:11 volumio volumio[648]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 19:55:11 volumio volumio[648]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 19:55:11 volumio volumio[648]: info: Retrieving Cloud Streaming UI Oct 27 19:55:11 volumio volumio[648]: info: Getting Tidal Cloud Configuration Oct 27 19:55:11 volumio volumio[648]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 19:55:11 volumio volumio[648]: info: Getting Qobuz Cloud Configuration Oct 27 19:55:11 volumio volumio[648]: info: Asking plugin for UI Config Oct 27 19:55:11 volumio volumio[648]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 19:55:11 volumio volumio[648]: info: Getting Spotify Cloud Configuration Oct 27 19:55:12 volumio volumio[648]: info: Asking plugin for UI Config Oct 27 19:55:12 volumio volumio[648]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 19:55:12 volumio volumio[648]: info: Saving Spotify Acccount Oct 27 19:55:12 volumio volumio[648]: info: Got Tidal Cloud Configuration Oct 27 19:55:12 volumio volumio[648]: info: Got it Oct 27 19:55:12 volumio volumio[648]: info: Got it Oct 27 19:55:12 volumio volumio[648]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 27 19:55:12 volumio volumio[648]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 19:55:12 volumio volumio[648]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 27 19:55:12 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 27 19:55:12 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 27 19:55:12 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 27 19:55:12 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 27 19:55:12 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 19:55:12 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 19:55:12 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 19:55:12 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 19:55:12 volumio volumio[648]: info: CoreCommandRouter::volumioGetBrowseSources Oct 27 19:55:12 volumio volumio[648]: info: CoreCommandRouter::volumioGetBrowseSources Oct 27 19:55:12 volumio volumio[648]: info: CoreCommandRouter::volumioGetBrowseSources Oct 27 19:55:12 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 27 19:55:13 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 27 19:55:15 volumio volumio[648]: info: Oct 27 19:55:15 volumio volumio[648]: ---------------------------- MPD announces system playlist update Oct 27 19:55:15 volumio volumio[648]: info: Ignoring MPD Status Update Oct 27 19:55:15 volumio volumio[648]: info: Oct 27 19:55:15 volumio volumio[648]: ---------------------------- MPD announces state update: player Oct 27 19:55:15 volumio volumio[648]: info: ControllerMpd::getState Oct 27 19:55:15 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand status Oct 27 19:55:15 volumio volumio[648]: info: ------------------------------ 48ms Oct 27 19:55:15 volumio volumio[648]: info: sendMpdCommand status took 31 milliseconds Oct 27 19:55:15 volumio volumio[648]: verbose: ControllerMpd::parseState Oct 27 19:55:15 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 19:55:15 volumio volumio[648]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 27 19:55:15 volumio volumio[648]: verbose: ControllerMpd::parseTrackInfo Oct 27 19:55:15 volumio volumio[648]: info: ControllerMpd::pushState Oct 27 19:55:15 volumio volumio[648]: info: CoreCommandRouter::servicePushState Oct 27 19:55:15 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:55:15 volumio volumio[648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","artist":"mpeg","album":null,"uri":"http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","trackType":""} Oct 27 19:55:15 volumio volumio[648]: verbose: CURRENT POSITION 0 Oct 27 19:55:15 volumio volumio[648]: info: CoreStateMachine::syncState stateService play Oct 27 19:55:15 volumio volumio[648]: info: CoreStateMachine::syncState currentStatus play Oct 27 19:55:15 volumio volumio[648]: info: Received an update from plugin. extracting info from payload Oct 27 19:55:15 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:55:16 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:55:16 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 19:55:16 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:55:16 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:55:16 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:55:16 volumio volumio[648]: info: ------------------------------ 512ms Oct 27 19:55:16 volumio volumio[648]: info: Disabling MyMusic plugin upnp Oct 27 19:55:16 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 27 19:55:17 volumio sudo[1076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 27 19:55:17 volumio sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 19:55:17 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 27 19:55:20 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 27 19:55:22 volumio volumio[648]: info: Oct 27 19:55:22 volumio volumio[648]: ---------------------------- MPD announces system playlist update Oct 27 19:55:22 volumio volumio[648]: info: Ignoring MPD Status Update Oct 27 19:55:22 volumio volumio[648]: info: Oct 27 19:55:22 volumio volumio[648]: ---------------------------- MPD announces state update: player Oct 27 19:55:22 volumio volumio[648]: info: ------------------------------ 23ms Oct 27 19:55:22 volumio volumio[648]: info: ControllerMpd::getState Oct 27 19:55:22 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand status Oct 27 19:55:22 volumio volumio[648]: info: sendMpdCommand status took 7 milliseconds Oct 27 19:55:22 volumio volumio[648]: verbose: ControllerMpd::parseState Oct 27 19:55:22 volumio volumio[648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 19:55:22 volumio volumio[648]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 27 19:55:22 volumio volumio[648]: verbose: ControllerMpd::parseTrackInfo Oct 27 19:55:22 volumio volumio[648]: info: ControllerMpd::pushState Oct 27 19:55:22 volumio volumio[648]: info: CoreCommandRouter::servicePushState Oct 27 19:55:22 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:55:22 volumio volumio[648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","artist":"mpeg","album":null,"uri":"http://powersmoothjazz.listenpowerapp.com/powersmoothjazz/mpeg/icecast.audio?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY0MjQ0MjcxNiwiaXNzIjoidGlzcnYifQ.He_RHQxv-RitdVBXxmHYHh8Lb6B9VHFr56GjGEDnFoQ","trackType":""} Oct 27 19:55:22 volumio volumio[648]: verbose: CURRENT POSITION 0 Oct 27 19:55:22 volumio volumio[648]: info: CoreStateMachine::syncState stateService play Oct 27 19:55:22 volumio volumio[648]: info: CoreStateMachine::syncState currentStatus play Oct 27 19:55:22 volumio volumio[648]: info: Received an update from plugin. extracting info from payload Oct 27 19:55:22 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:55:22 volumio volumio[648]: info: CorePlayQueue::getTrack 0 Oct 27 19:55:22 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 19:55:22 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:55:22 volumio volumio[648]: info: CoreStateMachine::pushState Oct 27 19:55:22 volumio volumio[648]: info: CoreCommandRouter::volumioPushState Oct 27 19:55:22 volumio volumio[648]: info: ------------------------------ 231ms Oct 27 19:55:24 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 27 19:55:26 volumio volumio[648]: info: Enabling MyMusic plugin upnp Oct 27 19:55:26 volumio volumio[648]: info: Enabling plugin upnp Oct 27 19:55:26 volumio volumio[648]: info: Loading plugin "upnp"... Oct 27 19:55:26 volumio volumio[648]: info: [1730055326248] Starting Upmpd Daemon Oct 27 19:55:26 volumio volumio[648]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 27 19:55:26 volumio volumio[648]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 19:55:26 volumio volumio[648]: Error: listen EADDRINUSE: address already in use :::6599 Oct 27 19:55:26 volumio volumio[648]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Oct 27 19:55:26 volumio volumio[648]: at listenInCluster (net.js:1366:12) Oct 27 19:55:26 volumio volumio[648]: at Server.listen (net.js:1452:7) Oct 27 19:55:26 volumio volumio[648]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 27 19:55:26 volumio volumio[648]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 27 19:55:26 volumio volumio[648]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 27 19:55:26 volumio volumio[648]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 27 19:55:26 volumio volumio[648]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 27 19:55:26 volumio volumio[648]: code: 'EADDRINUSE', Oct 27 19:55:26 volumio volumio[648]: errno: -98, Oct 27 19:55:26 volumio volumio[648]: syscall: 'listen', Oct 27 19:55:26 volumio volumio[648]: address: '::', Oct 27 19:55:26 volumio volumio[648]: port: 6599 Oct 27 19:55:26 volumio volumio[648]: } Oct 27 19:55:26 volumio volumio[648]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 19:55:30 volumio sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-27 19:54 Oct 27 19:55:30 volumio sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cd8cf34b47ce852e70b71e6f7b6e0265f7fdb695" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="8f88153f9a18f312d56050e6613b45a852ac819a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 27 Sep 2023 11:35:49 AM CEST" VOLUMIO_VERSION="3.546" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6823c0d83061ff736b7e0dd4481bc496"