-- Logs begin at Thu 2019-02-14 08:12:00 -02, end at Sun 2025-11-02 11:02:31 -03. --
Nov 02 11:01:00 volumio volumio[749]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Nov 02 11:01:00 volumio volumio[749]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Nov 02 11:01:00 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:00 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:00 volumio volumio[749]: info: Starting MyVolumio Remote Streaming Endpoints
Nov 02 11:01:00 volumio volumio[749]: info: MyVolumio login type: Token
Nov 02 11:01:00 volumio volumio[749]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Nov 02 11:01:00 volumio volumio[749]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Nov 02 11:01:04 volumio ntpd[726]: receive: Unexpected origin timestamp 0xecb1e29e.a9552047 does not match aorg 0000000000.00000000 from server@200.189.40.8 xmt 0xecb1e2a0.2823dd1e
Nov 02 11:01:04 volumio volumio[1128]: .........................................................++++
Nov 02 11:01:05 volumio volumio[749]: info: Starting Streaming Service Transparent Proxy
Nov 02 11:01:05 volumio volumio[749]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Nov 02 11:01:05 volumio volumio[749]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Nov 02 11:01:05 volumio volumio[749]: info: Streaming services startup
Nov 02 11:01:05 volumio volumio[749]: info: Starting Streaming Daemon
Nov 02 11:01:05 volumio sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Nov 02 11:01:05 volumio sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 02 11:01:05 volumio sudo[1350]: pam_unix(sudo:session): session closed for user root
Nov 02 11:01:05 volumio volumio[749]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Nov 02 11:01:05 volumio volumio[749]: SPOTIFY: SETTING SPOTIFY VOLUME 100
Nov 02 11:01:05 volumio volumio[749]: info: Sending Spotify command with payload to local API: /player/volume
Nov 02 11:01:06 volumio volumio[749]: error: Cannot start Volumio Streaming Daemon
Nov 02 11:01:06 volumio volumio[749]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Nov 02 11:01:06 volumio volumio[749]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Nov 02 11:01:06 volumio volumio[749]: error: MyVolumio Custom Token format not valid, refreshing it
Nov 02 11:01:07 volumio volumio[749]: info: MyVolumio login type: Token
Nov 02 11:01:07 volumio volumio[749]: info: CoreCommandRouter::getUIConfigOnPlugin
Nov 02 11:01:07 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Nov 02 11:01:08 volumio volumio[749]: STREAMING PROXY: Starting server on port 3245
Nov 02 11:01:08 volumio volumio[749]: Node JS runtime: 14
Nov 02 11:01:08 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Nov 02 11:01:08 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Nov 02 11:01:08 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs
Nov 02 11:01:08 volumio volumio[749]: info: Received Get System Version
Nov 02 11:01:08 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Nov 02 11:01:08 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds
Nov 02 11:01:09 volumio volumio[749]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Nov 02 11:01:11 volumio volumio[749]: info: MyVolumio token set successfully
Nov 02 11:01:11 volumio volumio[749]: info: MYVOLUMIO: Adding device
Nov 02 11:01:11 volumio volumio[749]: info: MYVOLUMIO: Evaluating Server
Nov 02 11:01:12 volumio volumio[749]: info: MyVolumio status changed
Nov 02 11:01:12 volumio volumio[749]: info: Streaming services startup
Nov 02 11:01:12 volumio volumio[749]: info: Starting Streaming Daemon
Nov 02 11:01:12 volumio volumio[749]: info: Removing browser output: myVolumio user plan is not superstar
Nov 02 11:01:12 volumio volumio[749]: info: Removing audio output:
Nov 02 11:01:12 volumio volumio[749]: info: Stoppping Tunnel 1
Nov 02 11:01:12 volumio sudo[1389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Nov 02 11:01:12 volumio sudo[1389]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 02 11:01:12 volumio sudo[1389]: pam_unix(sudo:session): session closed for user root
Nov 02 11:01:12 volumio volumio[749]: error: Cannot start Volumio Streaming Daemon
Nov 02 11:01:12 volumio sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Nov 02 11:01:12 volumio sudo[1392]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 02 11:01:12 volumio volumio[749]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Nov 02 11:01:12 volumio volumio[749]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Nov 02 11:01:12 volumio sudo[1392]: pam_unix(sudo:session): session closed for user root
Nov 02 11:01:12 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Nov 02 11:01:12 volumio volumio[749]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Nov 02 11:01:12 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Nov 02 11:01:12 volumio volumio[749]: info: Received Get System Version
Nov 02 11:01:12 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Nov 02 11:01:12 volumio volumio[749]: info: Received Get System Info
Nov 02 11:01:12 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Nov 02 11:01:12 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Nov 02 11:01:12 volumio volumio[749]: info: Discovery: Getting this device information
Nov 02 11:01:12 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Nov 02 11:01:12 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:12 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Nov 02 11:01:12 volumio volumio[749]: info: Remote SSH Stopped
Nov 02 11:01:12 volumio volumio[749]: info: Setting Geolocation for MyVolumio to us1
Nov 02 11:01:12 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:12 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:12 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:13 volumio volumio[749]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Nov 02 11:01:13 volumio volumio[749]: info: Updating MyVolumio device info
Nov 02 11:01:13 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:13 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:13 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:14 volumio volumio[749]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Nov 02 11:01:15 volumio volumio[1128]: .............................................++++
Nov 02 11:01:15 volumio volumio[1128]: e is 65537 (0x010001)
Nov 02 11:01:15 volumio volumio[1128]: writing RSA key
Nov 02 11:01:16 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Nov 02 11:01:16 volumio volumio[749]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Nov 02 11:01:16 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Nov 02 11:01:16 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:16 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Nov 02 11:01:16 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:16 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Nov 02 11:01:16 volumio volumio-remote-updater[444]: No test mode
Nov 02 11:01:16 volumio volumio-remote-updater[444]: No alpha test mode
Nov 02 11:01:16 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Nov 02 11:01:17 volumio volumio[749]: info: MYVOLUMIO: Adding device
Nov 02 11:01:17 volumio volumio[749]: info: MYVOLUMIO: Evaluating Server
Nov 02 11:01:18 volumio volumio[749]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix plugin installations due to apt repository
\n- Fix chromecast playback
\n
\nNEW ADDITIONS
\n\n- Add link for downloading CORRD in the side-menu
\n
\n","title":"Update v3.832","updateavailable":true}
Nov 02 11:01:18 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Nov 02 11:01:18 volumio volumio[749]: info: Setting Geolocation for MyVolumio to us1
Nov 02 11:01:18 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:18 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:18 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:19 volumio volumio[749]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Nov 02 11:01:19 volumio volumio[749]: info: Updating MyVolumio device info
Nov 02 11:01:19 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:19 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:19 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:20 volumio volumio[749]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Nov 02 11:01:21 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 02 11:01:21 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 02 11:01:21 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Nov 02 11:01:21 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Nov 02 11:01:21 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Nov 02 11:01:21 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Nov 02 11:01:21 volumio volumio[749]: info: CoreCommandRouter::volumioGetBrowseSources
Nov 02 11:01:21 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Nov 02 11:01:25 volumio volumio[749]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Nov 02 11:01:25 volumio volumio[749]: info: Completed starting MyVolumio Plugin
Nov 02 11:01:25 volumio volumio[749]: [Metrics] CommandRouter: 89s 898.42ms
Nov 02 11:01:25 volumio volumio[749]: info: CoreCommandRouter::volumiosetStartupVolume
Nov 02 11:01:25 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 02 11:01:25 volumio volumio[749]: info: VolumeController:: Setting startup Volume 30
Nov 02 11:01:25 volumio volumio[749]: info: VolumeController::SetAlsaVolume30
Nov 02 11:01:25 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 02 11:01:25 volumio volumio[749]: info: CoreCommandRouter::Close All Modals sent
Nov 02 11:01:25 volumio volumio[749]: info: CoreCommandRouter::Close All Modals sent
Nov 02 11:01:25 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Nov 02 11:01:25 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:25 volumio volumio[749]: [ytcr.VolumeControl] Setting volume to 30
Nov 02 11:01:25 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:25 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:25 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:01:25 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:26 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:26 volumio volumio[749]: SPOTIFY: SPOTIFY VOLUME 100
Nov 02 11:01:26 volumio volumio[749]: SPOTIFY: VOLUMIO VOLUME 30
Nov 02 11:01:26 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Nov 02 11:01:26 volumio volumio[749]: SPOTIFY: DELTA VOLUME ENOUGH: true
Nov 02 11:01:26 volumio volumio[749]: info: Setting Spotify Volume from Volumio: 30
Nov 02 11:01:26 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Nov 02 11:01:26 volumio volumio[749]: info: CoreCommandRouter::volumioGetQueue
Nov 02 11:01:26 volumio volumio[749]: info: CoreStateMachine::getQueue
Nov 02 11:01:26 volumio volumio[749]: info: CorePlayQueue::getQueue
Nov 02 11:01:26 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:01:26 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Nov 02 11:01:26 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Nov 02 11:01:26 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Nov 02 11:01:27 volumio volumio[749]: SPOTIFY: SETTING SPOTIFY VOLUME 30
Nov 02 11:01:27 volumio volumio[749]: info: Sending Spotify command with payload to local API: /player/volume
Nov 02 11:01:28 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Nov 02 11:01:28 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:01:30 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:01:30 volumio volumio[749]: info: CoreCommandRouter::volumioReplaceandPlayItems
Nov 02 11:01:30 volumio volumio[749]: info: CoreStateMachine::ClearQueue
Nov 02 11:01:30 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:01:30 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:01:30 volumio volumio[749]: info: CorePlayQueue::clearPlayQueue
Nov 02 11:01:30 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:01:30 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:01:30 volumio volumio[749]: info: CoreStateMachine::addQueueItems
Nov 02 11:01:30 volumio volumio[749]: info: CorePlayQueue::addQueueItems
Nov 02 11:01:30 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:01:30 volumio volumio[749]: info: Adding Item to queue: http://streams.calmradio.com/api/169/128/stream
Nov 02 11:01:30 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Nov 02 11:01:30 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:01:30 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:01:30 volumio volumio[749]: info: CoreStateMachine::updateTrackBlock
Nov 02 11:01:30 volumio volumio[749]: info: CorePlayQueue::getTrackBlock
Nov 02 11:01:30 volumio volumio[749]: info: CoreCommandRouter::volumioPlay
Nov 02 11:01:30 volumio volumio[749]: info: CoreStateMachine::play index 0
Nov 02 11:01:30 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:01:30 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:01:30 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:01:30 volumio volumio[749]: info: CoreStateMachine::play index undefined
Nov 02 11:01:30 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:01:30 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:30 volumio volumio[749]: info: CoreStateMachine::startPlaybackTimer
Nov 02 11:01:30 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:30 volumio volumio[749]: info: [1762092090831] ControllerWebradio::clearAddPlayTrack
Nov 02 11:01:30 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand stop
Nov 02 11:01:30 volumio volumio[749]: info: sendMpdCommand stop took 18 milliseconds
Nov 02 11:01:30 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand clear
Nov 02 11:01:30 volumio volumio[749]: info:
Nov 02 11:01:30 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:30 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:30 volumio volumio[749]: info: sendMpdCommand clear took 9 milliseconds
Nov 02 11:01:30 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand load "http://streams.calmradio.com/api/169/128/stream"
Nov 02 11:01:30 volumio volumio[749]: info:
Nov 02 11:01:30 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:30 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:30 volumio volumio[749]: info:
Nov 02 11:01:30 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:30 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:30 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:01:30 volumio volumio[749]: info: ------------------------------ 26ms
Nov 02 11:01:30 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:01:30 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:01:30 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:01:32 volumio volumio[749]: info: BOOT COMPLETED
Nov 02 11:01:33 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand add "http://streams.calmradio.com/api/169/128/stream"
Nov 02 11:01:33 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:01:33 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:01:33 volumio volumio[749]: info:
Nov 02 11:01:33 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:33 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:33 volumio volumio[749]: info: ------------------------------ 2325ms
Nov 02 11:01:33 volumio volumio[749]: info: ------------------------------ 2321ms
Nov 02 11:01:33 volumio volumio[749]: info: sendMpdCommand add "http://streams.calmradio.com/api/169/128/stream" took 9 milliseconds
Nov 02 11:01:33 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService mpd
Nov 02 11:01:33 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand play
Nov 02 11:01:33 volumio volumio[749]: Upnp client error: Error: This socket has been ended by the other party
Nov 02 11:01:33 volumio volumio[749]: info:
Nov 02 11:01:33 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:33 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:33 volumio volumio[749]: info:
Nov 02 11:01:33 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:33 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:33 volumio volumio[749]: info: ------------------------------ 26ms
Nov 02 11:01:33 volumio volumio[749]: info: sendMpdCommand play took 20 milliseconds
Nov 02 11:01:33 volumio volumio[749]: info: ------------------------------ 16ms
Nov 02 11:01:33 volumio volumio[749]: info: ------------------------------ 12ms
Nov 02 11:01:33 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:01:33 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:01:33 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:01:33 volumio volumio[749]: info:
Nov 02 11:01:33 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:33 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:33 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:33 volumio volumio[749]: info:
Nov 02 11:01:33 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:33 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:33 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Nov 02 11:01:33 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:33 volumio volumio[749]: info:
Nov 02 11:01:33 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:33 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:33 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:34 volumio volumio[749]: info:
Nov 02 11:01:34 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand status took 37 milliseconds
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand status took 36 milliseconds
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand status took 32 milliseconds
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:01:34 volumio volumio[749]: info:
Nov 02 11:01:34 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:34 volumio volumio[749]: info:
Nov 02 11:01:34 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand status took 30 milliseconds
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand playlistinfo took 25 milliseconds
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand playlistinfo took 22 milliseconds
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand playlistinfo took 21 milliseconds
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand status took 16 milliseconds
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand status took 11 milliseconds
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:01:34 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:34 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:01:34 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus stop
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:01:34 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:34 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:01:34 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:01:34 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:01:34 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:34 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:01:34 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:01:34 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: ------------------------------ 165ms
Nov 02 11:01:34 volumio volumio[749]: info: ------------------------------ 210ms
Nov 02 11:01:34 volumio volumio[749]: info: ------------------------------ 208ms
Nov 02 11:01:34 volumio volumio[749]: info:
Nov 02 11:01:34 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:34 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:34 volumio volumio[749]: info:
Nov 02 11:01:34 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand playlistinfo took 145 milliseconds
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand playlistinfo took 135 milliseconds
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand playlistinfo took 134 milliseconds
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:01:34 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:34 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:01:34 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:01:34 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:01:34 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:34 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:01:34 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:01:34 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:01:34 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:34 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:01:34 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:01:34 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: ------------------------------ 334ms
Nov 02 11:01:34 volumio volumio[749]: info: ------------------------------ 314ms
Nov 02 11:01:34 volumio volumio[749]: info: ------------------------------ 311ms
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: info:
Nov 02 11:01:34 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:34 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:34 volumio volumio[749]: info:
Nov 02 11:01:34 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:34 volumio volumio[749]: info:
Nov 02 11:01:34 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:34 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:34 volumio volumio[749]: info:
Nov 02 11:01:34 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:34 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:01:34 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:01:34 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:01:34 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:01:34 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:01:34 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:01:34 volumio volumio[749]: info: ------------------------------ 221ms
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand status took 213 milliseconds
Nov 02 11:01:34 volumio volumio[749]: info: ------------------------------ 23ms
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand status took 20 milliseconds
Nov 02 11:01:34 volumio volumio[749]: info: ------------------------------ 19ms
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand status took 15 milliseconds
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand playlistinfo took 11 milliseconds
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand playlistinfo took 10 milliseconds
Nov 02 11:01:34 volumio volumio[749]: info: sendMpdCommand playlistinfo took 10 milliseconds
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:01:34 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:01:34 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:34 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stanton Lanier - A Thousand Years - Be Still My Soul (feat. Jill Haley)","artist":"CALMRADIO.COM - Christian Instrumentals","album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:01:34 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:01:34 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:01:34 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:34 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stanton Lanier - A Thousand Years - Be Still My Soul (feat. Jill Haley)","artist":"CALMRADIO.COM - Christian Instrumentals","album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:01:34 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:01:34 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:01:34 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:34 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stanton Lanier - A Thousand Years - Be Still My Soul (feat. Jill Haley)","artist":"CALMRADIO.COM - Christian Instrumentals","album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:01:34 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:01:34 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:34 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:34 volumio volumio[749]: info: ------------------------------ 371ms
Nov 02 11:01:34 volumio volumio[749]: info: ------------------------------ 174ms
Nov 02 11:01:34 volumio volumio[749]: info: ------------------------------ 170ms
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:34 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Nov 02 11:01:43 volumio volumio[749]: info: VolumeController::SetAlsaVolume95
Nov 02 11:01:43 volumio volumio[749]: info: CoreCommandRouter::volumioGetState
Nov 02 11:01:43 volumio volumio[749]: [ytcr.VolumeControl] Setting volume to 95
Nov 02 11:01:43 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:43 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:01:43 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:43 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:01:43 volumio volumio[749]: SPOTIFY: SPOTIFY VOLUME 30
Nov 02 11:01:43 volumio volumio[749]: SPOTIFY: VOLUMIO VOLUME 95
Nov 02 11:01:43 volumio volumio[749]: SPOTIFY: DELTA VOLUME ENOUGH: true
Nov 02 11:01:43 volumio volumio[749]: info: Setting Spotify Volume from Volumio: 95
Nov 02 11:01:45 volumio volumio[749]: SPOTIFY: SETTING SPOTIFY VOLUME 95
Nov 02 11:01:45 volumio volumio[749]: info: Sending Spotify command with payload to local API: /player/volume
Nov 02 11:01:52 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:01:52 volumio volumio[749]: info: CoreCommandRouter::volumioReplaceandPlayItems
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::ClearQueue
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::stPlaybackTimer
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::updateTrackBlock
Nov 02 11:01:52 volumio volumio[749]: info: CorePlayQueue::getTrackBlock
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:52 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:52 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:01:52 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::serviceStop
Nov 02 11:01:52 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:52 volumio volumio[749]: info: CoreCommandRouter::serviceStop
Nov 02 11:01:52 volumio volumio[749]: info: [1762092112594] ControllerWebradio::stop
Nov 02 11:01:52 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand stop
Nov 02 11:01:52 volumio volumio[749]: info: CorePlayQueue::clearPlayQueue
Nov 02 11:01:52 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:01:52 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::addQueueItems
Nov 02 11:01:52 volumio volumio[749]: info: CorePlayQueue::addQueueItems
Nov 02 11:01:52 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:01:52 volumio volumio[749]: info: Adding Item to queue: http://wanderingsheep.net/christianjazz/stream.mp3
Nov 02 11:01:52 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Nov 02 11:01:52 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:01:52 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::updateTrackBlock
Nov 02 11:01:52 volumio volumio[749]: info: CorePlayQueue::getTrackBlock
Nov 02 11:01:52 volumio volumio[749]: info: CoreCommandRouter::volumioPlay
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::play index 0
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::play index undefined
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:01:52 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::startPlaybackTimer
Nov 02 11:01:52 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:52 volumio volumio[749]: info: [1762092112670] ControllerWebradio::clearAddPlayTrack
Nov 02 11:01:52 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand stop
Nov 02 11:01:52 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:01:52 volumio volumio[749]: info:
Nov 02 11:01:52 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:52 volumio volumio[749]: info: sendMpdCommand stop took 94 milliseconds
Nov 02 11:01:52 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:52 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:52 volumio volumio[749]: info: sendMpdCommand stop took 20 milliseconds
Nov 02 11:01:52 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand clear
Nov 02 11:01:52 volumio volumio[749]: info:
Nov 02 11:01:52 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:52 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:52 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:52 volumio volumio[749]: info:
Nov 02 11:01:52 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:52 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:52 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:52 volumio volumio[749]: info:
Nov 02 11:01:52 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:52 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:52 volumio volumio[749]: info: sendMpdCommand status took 23 milliseconds
Nov 02 11:01:52 volumio volumio[749]: info: sendMpdCommand clear took 21 milliseconds
Nov 02 11:01:52 volumio volumio[749]: info: sendMpdCommand status took 17 milliseconds
Nov 02 11:01:52 volumio volumio[749]: info: sendMpdCommand status took 13 milliseconds
Nov 02 11:01:52 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:52 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:01:52 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand load "http://wanderingsheep.net/christianjazz/stream.mp3"
Nov 02 11:01:52 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:52 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:52 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:01:52 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:52 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:52 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:01:52 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:52 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:52 volumio volumio[749]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Nov 02 11:01:52 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:01:52 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:01:52 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:52 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:52 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:52 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:52 volumio volumio[749]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Nov 02 11:01:52 volumio volumio[749]: info: ------------------------------ 80ms
Nov 02 11:01:52 volumio volumio[749]: info: ------------------------------ 77ms
Nov 02 11:01:52 volumio volumio[749]: info:
Nov 02 11:01:52 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:52 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:52 volumio volumio[749]: info:
Nov 02 11:01:52 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:52 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:52 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:01:52 volumio volumio[749]: info: ------------------------------ 81ms
Nov 02 11:01:52 volumio volumio[749]: info: sendMpdCommand playlistinfo took 74 milliseconds
Nov 02 11:01:52 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:01:52 volumio volumio[749]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Nov 02 11:01:52 volumio volumio[749]: info: ------------------------------ 110ms
Nov 02 11:01:52 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:01:52 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:01:52 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:01:52 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:01:52 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:01:52 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:01:52 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:01:52 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:01:54 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand add "http://wanderingsheep.net/christianjazz/stream.mp3"
Nov 02 11:01:54 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:01:54 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:01:54 volumio volumio[749]: info:
Nov 02 11:01:54 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:54 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:54 volumio volumio[749]: info: ------------------------------ 1554ms
Nov 02 11:01:54 volumio volumio[749]: info: ------------------------------ 1552ms
Nov 02 11:01:54 volumio volumio[749]: info: sendMpdCommand add "http://wanderingsheep.net/christianjazz/stream.mp3" took 6 milliseconds
Nov 02 11:01:54 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService mpd
Nov 02 11:01:54 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand play
Nov 02 11:01:54 volumio volumio[749]: info:
Nov 02 11:01:54 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:54 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:54 volumio volumio[749]: info:
Nov 02 11:01:54 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:54 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:54 volumio volumio[749]: info: ------------------------------ 19ms
Nov 02 11:01:54 volumio volumio[749]: info: sendMpdCommand play took 15 milliseconds
Nov 02 11:01:54 volumio volumio[749]: info: ------------------------------ 13ms
Nov 02 11:01:54 volumio volumio[749]: info: ------------------------------ 10ms
Nov 02 11:01:54 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:01:54 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:01:54 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:01:55 volumio volumio[749]: info:
Nov 02 11:01:55 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:55 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:55 volumio volumio[749]: info:
Nov 02 11:01:55 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:55 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:55 volumio volumio[749]: info:
Nov 02 11:01:55 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:01:55 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand clearerror
Nov 02 11:01:55 volumio volumio[749]: info: sendMpdCommand status took 25 milliseconds
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand clearerror
Nov 02 11:01:55 volumio volumio[749]: info: sendMpdCommand status took 24 milliseconds
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand clearerror
Nov 02 11:01:55 volumio volumio[749]: info: sendMpdCommand status took 23 milliseconds
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:01:55 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:01:55 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:01:55 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:01:55 volumio volumio[749]: info: sendMpdCommand clearerror took 38 milliseconds
Nov 02 11:01:55 volumio volumio[749]: info: sendMpdCommand clearerror took 36 milliseconds
Nov 02 11:01:55 volumio volumio[749]: info: sendMpdCommand clearerror took 33 milliseconds
Nov 02 11:01:55 volumio volumio[749]: info: sendMpdCommand playlistinfo took 12 milliseconds
Nov 02 11:01:55 volumio volumio[749]: info: sendMpdCommand playlistinfo took 11 milliseconds
Nov 02 11:01:55 volumio volumio[749]: info: sendMpdCommand playlistinfo took 9 milliseconds
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:01:55 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:01:55 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:01:55 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:55 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:55 volumio volumio[749]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":null,"album":null,"uri":"http://wanderingsheep.net/christianjazz/stream.mp3","trackType":"mp3"}
Nov 02 11:01:55 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::syncState stateService stop
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus stop
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:55 volumio volumio[749]: info: No code
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:55 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:55 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:55 volumio volumio[749]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":null,"album":null,"uri":"http://wanderingsheep.net/christianjazz/stream.mp3","trackType":"mp3"}
Nov 02 11:01:55 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::syncState stateService stop
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus stop
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:55 volumio volumio[749]: info: No code
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:55 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:55 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:55 volumio volumio[749]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":null,"album":null,"uri":"http://wanderingsheep.net/christianjazz/stream.mp3","trackType":"mp3"}
Nov 02 11:01:55 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::syncState stateService stop
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus stop
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:55 volumio volumio[749]: info: No code
Nov 02 11:01:55 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:01:55 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:01:55 volumio volumio[749]: info: ------------------------------ 251ms
Nov 02 11:01:55 volumio volumio[749]: info: ------------------------------ 250ms
Nov 02 11:01:55 volumio volumio[749]: info: ------------------------------ 250ms
Nov 02 11:01:55 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:01:55 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:01:55 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:01:55 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:01:55 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:01:55 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:01:55 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:01:55 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:01:55 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:01:58 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:01:58 volumio volumio[749]: info: CoreCommandRouter::volumioReplaceandPlayItems
Nov 02 11:01:58 volumio volumio[749]: info: CoreStateMachine::ClearQueue
Nov 02 11:01:58 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:01:58 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:01:58 volumio volumio[749]: info: CorePlayQueue::clearPlayQueue
Nov 02 11:01:58 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:01:58 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:01:58 volumio volumio[749]: info: CoreStateMachine::addQueueItems
Nov 02 11:01:58 volumio volumio[749]: info: CorePlayQueue::addQueueItems
Nov 02 11:01:58 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:01:58 volumio volumio[749]: info: Adding Item to queue: https://hopefm.org.uk/playhd/live-stream128
Nov 02 11:01:58 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Nov 02 11:01:58 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:01:58 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:01:58 volumio volumio[749]: info: CoreStateMachine::updateTrackBlock
Nov 02 11:01:58 volumio volumio[749]: info: CorePlayQueue::getTrackBlock
Nov 02 11:01:58 volumio volumio[749]: info: CoreCommandRouter::volumioPlay
Nov 02 11:01:58 volumio volumio[749]: info: CoreStateMachine::play index 0
Nov 02 11:01:58 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:01:58 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:01:58 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:01:58 volumio volumio[749]: info: CoreStateMachine::play index undefined
Nov 02 11:01:58 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:01:58 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:58 volumio volumio[749]: info: CoreStateMachine::startPlaybackTimer
Nov 02 11:01:58 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:01:58 volumio volumio[749]: info: [1762092118869] ControllerWebradio::clearAddPlayTrack
Nov 02 11:01:58 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand stop
Nov 02 11:01:58 volumio volumio[749]: info: sendMpdCommand stop took 32 milliseconds
Nov 02 11:01:58 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand clear
Nov 02 11:01:58 volumio volumio[749]: info:
Nov 02 11:01:58 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:58 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:58 volumio volumio[749]: info: sendMpdCommand clear took 8 milliseconds
Nov 02 11:01:58 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand load "https://hopefm.org.uk/playhd/live-stream128"
Nov 02 11:01:58 volumio volumio[749]: info:
Nov 02 11:01:58 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:58 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:58 volumio volumio[749]: info:
Nov 02 11:01:58 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:01:58 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:01:58 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:01:58 volumio volumio[749]: info: ------------------------------ 20ms
Nov 02 11:01:58 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:01:58 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:01:58 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:01 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand add "https://hopefm.org.uk/playhd/live-stream128"
Nov 02 11:02:01 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:01 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:01 volumio volumio[749]: info:
Nov 02 11:02:01 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:01 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:01 volumio volumio[749]: info: ------------------------------ 2576ms
Nov 02 11:02:01 volumio volumio[749]: info: ------------------------------ 2572ms
Nov 02 11:02:01 volumio volumio[749]: info: sendMpdCommand add "https://hopefm.org.uk/playhd/live-stream128" took 7 milliseconds
Nov 02 11:02:01 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService mpd
Nov 02 11:02:01 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand play
Nov 02 11:02:01 volumio volumio[749]: Upnp client error: Error: This socket has been ended by the other party
Nov 02 11:02:01 volumio volumio[749]: info:
Nov 02 11:02:01 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:01 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:01 volumio volumio[749]: info:
Nov 02 11:02:01 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:01 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:01 volumio volumio[749]: info: ------------------------------ 17ms
Nov 02 11:02:01 volumio volumio[749]: info: sendMpdCommand play took 13 milliseconds
Nov 02 11:02:01 volumio volumio[749]: info: ------------------------------ 10ms
Nov 02 11:02:01 volumio volumio[749]: info: ------------------------------ 8ms
Nov 02 11:02:01 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:01 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:01 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:02 volumio volumio[749]: info:
Nov 02 11:02:02 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:02 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:02 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:02 volumio volumio[749]: info:
Nov 02 11:02:02 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:02 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:02 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:02 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand clearerror
Nov 02 11:02:02 volumio volumio[749]: info: sendMpdCommand status took 18 milliseconds
Nov 02 11:02:02 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand clearerror
Nov 02 11:02:02 volumio volumio[749]: info: sendMpdCommand status took 17 milliseconds
Nov 02 11:02:02 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:02 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:02 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:02 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:02 volumio volumio[749]: info:
Nov 02 11:02:02 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:02 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:02 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:02 volumio volumio[749]: info: sendMpdCommand clearerror took 22 milliseconds
Nov 02 11:02:02 volumio volumio[749]: info: sendMpdCommand clearerror took 20 milliseconds
Nov 02 11:02:02 volumio volumio[749]: info: sendMpdCommand playlistinfo took 11 milliseconds
Nov 02 11:02:02 volumio volumio[749]: info: sendMpdCommand playlistinfo took 9 milliseconds
Nov 02 11:02:02 volumio volumio[749]: info: sendMpdCommand status took 6 milliseconds
Nov 02 11:02:02 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:02 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:02 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:02 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:02 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:02 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:02 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:02 volumio volumio[749]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live-stream128","artist":null,"album":null,"uri":"https://hopefm.org.uk/playhd/live-stream128","trackType":""}
Nov 02 11:02:02 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::syncState stateService stop
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus stop
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:02 volumio volumio[749]: info: No code
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:02 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:02 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:02 volumio volumio[749]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live-stream128","artist":null,"album":null,"uri":"https://hopefm.org.uk/playhd/live-stream128","trackType":""}
Nov 02 11:02:02 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::syncState stateService stop
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus stop
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:02 volumio volumio[749]: info: No code
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:02 volumio volumio[749]: info: ------------------------------ 179ms
Nov 02 11:02:02 volumio volumio[749]: info: ------------------------------ 177ms
Nov 02 11:02:02 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:02 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:02 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:02 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:02 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:02 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:02 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:02 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:02 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:02 volumio volumio[749]: info: sendMpdCommand playlistinfo took 167 milliseconds
Nov 02 11:02:02 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:02 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:02 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:02 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:02 volumio volumio[749]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live-stream128","artist":null,"album":null,"uri":"https://hopefm.org.uk/playhd/live-stream128","trackType":""}
Nov 02 11:02:02 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::syncState stateService stop
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus stop
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:02 volumio volumio[749]: info: No code
Nov 02 11:02:02 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:02 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:02 volumio volumio[749]: info: ------------------------------ 240ms
Nov 02 11:02:02 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:02 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:02 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:03 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:02:03 volumio volumio[749]: info: CoreCommandRouter::volumioReplaceandPlayItems
Nov 02 11:02:03 volumio volumio[749]: info: CoreStateMachine::ClearQueue
Nov 02 11:02:03 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:02:03 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:03 volumio volumio[749]: info: CorePlayQueue::clearPlayQueue
Nov 02 11:02:03 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:02:03 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:02:03 volumio volumio[749]: info: CoreStateMachine::addQueueItems
Nov 02 11:02:03 volumio volumio[749]: info: CorePlayQueue::addQueueItems
Nov 02 11:02:03 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:02:03 volumio volumio[749]: info: Adding Item to queue: http://ice64.securenetsystems.net/CLR1MP3
Nov 02 11:02:03 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Nov 02 11:02:03 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:02:03 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:02:03 volumio volumio[749]: info: CoreStateMachine::updateTrackBlock
Nov 02 11:02:03 volumio volumio[749]: info: CorePlayQueue::getTrackBlock
Nov 02 11:02:03 volumio volumio[749]: info: CoreCommandRouter::volumioPlay
Nov 02 11:02:03 volumio volumio[749]: info: CoreStateMachine::play index 0
Nov 02 11:02:03 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:03 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:02:03 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:03 volumio volumio[749]: info: CoreStateMachine::play index undefined
Nov 02 11:02:03 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:03 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:03 volumio volumio[749]: info: CoreStateMachine::startPlaybackTimer
Nov 02 11:02:03 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:03 volumio volumio[749]: info: [1762092123948] ControllerWebradio::clearAddPlayTrack
Nov 02 11:02:03 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand stop
Nov 02 11:02:03 volumio volumio[749]: info: sendMpdCommand stop took 6 milliseconds
Nov 02 11:02:03 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand clear
Nov 02 11:02:03 volumio volumio[749]: info:
Nov 02 11:02:03 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:03 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:03 volumio volumio[749]: info: sendMpdCommand clear took 4 milliseconds
Nov 02 11:02:03 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand load "http://ice64.securenetsystems.net/CLR1MP3"
Nov 02 11:02:03 volumio volumio[749]: info:
Nov 02 11:02:03 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:03 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:03 volumio volumio[749]: info:
Nov 02 11:02:03 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:03 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:03 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:03 volumio volumio[749]: info: ------------------------------ 13ms
Nov 02 11:02:03 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:03 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:03 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand add "http://ice64.securenetsystems.net/CLR1MP3"
Nov 02 11:02:05 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:05 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 1047ms
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 1044ms
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:05 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand add "http://ice64.securenetsystems.net/CLR1MP3" took 9 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService mpd
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand play
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:05 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:05 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 13ms
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand play took 11 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 10ms
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 7ms
Nov 02 11:02:05 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:05 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:05 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:05 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand status took 19 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand status took 21 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand status took 18 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand status took 6 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand playlistinfo took 6 milliseconds
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: 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":"CLR1MP3","artist":null,"album":null,"uri":"http://ice64.securenetsystems.net/CLR1MP3","trackType":""}
Nov 02 11:02:05 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus stop
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 42ms
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand playlistinfo took 20 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand playlistinfo took 19 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand playlistinfo took 18 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand status took 9 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand status took 6 milliseconds
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: 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":"CLR1MP3","artist":null,"album":null,"uri":"http://ice64.securenetsystems.net/CLR1MP3","trackType":""}
Nov 02 11:02:05 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:05 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: 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":"CLR1MP3","artist":null,"album":null,"uri":"http://ice64.securenetsystems.net/CLR1MP3","trackType":""}
Nov 02 11:02:05 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:05 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: 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":"CLR1MP3","artist":null,"album":null,"uri":"http://ice64.securenetsystems.net/CLR1MP3","trackType":""}
Nov 02 11:02:05 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:05 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 203ms
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 200ms
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 190ms
Nov 02 11:02:05 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:05 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:05 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:05 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand playlistinfo took 159 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand playlistinfo took 159 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: 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":"CLR1MP3","artist":null,"album":null,"uri":"http://ice64.securenetsystems.net/CLR1MP3","trackType":""}
Nov 02 11:02:05 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:05 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: 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":"CLR1MP3","artist":null,"album":null,"uri":"http://ice64.securenetsystems.net/CLR1MP3","trackType":""}
Nov 02 11:02:05 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:05 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 276ms
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 274ms
Nov 02 11:02:05 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:05 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:05 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:05 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:05 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:05 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:05 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:05 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:05 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:05 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:05 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:05 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:05 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 157ms
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand status took 158 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 24ms
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand status took 22 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 21ms
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand status took 19 milliseconds
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:05 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:05 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:05 volumio volumio[749]: info:
Nov 02 11:02:05 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 40ms
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand status took 36 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand playlistinfo took 24 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand playlistinfo took 23 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand playlistinfo took 22 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 20ms
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand status took 16 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 15ms
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand status took 11 milliseconds
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Praise Band - Rock Of Ages The Praise Band - ","artist":"Christian Life Radio","album":null,"uri":"http://ice64.securenetsystems.net/CLR1MP3","trackType":""}
Nov 02 11:02:05 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:05 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1084,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Praise Band - Rock Of Ages The Praise Band - ","artist":"Christian Life Radio","album":null,"uri":"http://ice64.securenetsystems.net/CLR1MP3","trackType":""}
Nov 02 11:02:05 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:05 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1084,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Praise Band - Rock Of Ages The Praise Band - ","artist":"Christian Life Radio","album":null,"uri":"http://ice64.securenetsystems.net/CLR1MP3","trackType":""}
Nov 02 11:02:05 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:05 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 337ms
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 199ms
Nov 02 11:02:05 volumio volumio[749]: info: ------------------------------ 196ms
Nov 02 11:02:05 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:05 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:05 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:05 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:05 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:05 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand playlistinfo took 150 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand playlistinfo took 147 milliseconds
Nov 02 11:02:05 volumio volumio[749]: info: sendMpdCommand playlistinfo took 146 milliseconds
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:05 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1084,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Praise Band - Rock Of Ages The Praise Band - ","artist":"Christian Life Radio","album":null,"uri":"http://ice64.securenetsystems.net/CLR1MP3","trackType":""}
Nov 02 11:02:05 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:05 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1084,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Praise Band - Rock Of Ages The Praise Band - ","artist":"Christian Life Radio","album":null,"uri":"http://ice64.securenetsystems.net/CLR1MP3","trackType":""}
Nov 02 11:02:05 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:05 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:05 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:05 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1084,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Praise Band - Rock Of Ages The Praise Band - ","artist":"Christian Life Radio","album":null,"uri":"http://ice64.securenetsystems.net/CLR1MP3","trackType":""}
Nov 02 11:02:05 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:05 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:05 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:06 volumio volumio[749]: info: ------------------------------ 322ms
Nov 02 11:02:06 volumio volumio[749]: info: ------------------------------ 301ms
Nov 02 11:02:06 volumio volumio[749]: info: ------------------------------ 296ms
Nov 02 11:02:06 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:06 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:06 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:06 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:06 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:06 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:06 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:06 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:06 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:06 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:06 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:06 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:09 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:02:09 volumio volumio[749]: info: CoreCommandRouter::volumioReplaceandPlayItems
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::ClearQueue
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::stPlaybackTimer
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::updateTrackBlock
Nov 02 11:02:09 volumio volumio[749]: info: CorePlayQueue::getTrackBlock
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:09 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:09 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:09 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::serviceStop
Nov 02 11:02:09 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:09 volumio volumio[749]: info: CoreCommandRouter::serviceStop
Nov 02 11:02:09 volumio volumio[749]: info: [1762092129304] ControllerWebradio::stop
Nov 02 11:02:09 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand stop
Nov 02 11:02:09 volumio volumio[749]: info: CorePlayQueue::clearPlayQueue
Nov 02 11:02:09 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:02:09 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::addQueueItems
Nov 02 11:02:09 volumio volumio[749]: info: CorePlayQueue::addQueueItems
Nov 02 11:02:09 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:02:09 volumio volumio[749]: info: Adding Item to queue: https://ice64.securenetsystems.net/CCR1MP3
Nov 02 11:02:09 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Nov 02 11:02:09 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:02:09 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::updateTrackBlock
Nov 02 11:02:09 volumio volumio[749]: info: CorePlayQueue::getTrackBlock
Nov 02 11:02:09 volumio volumio[749]: info: CoreCommandRouter::volumioPlay
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::play index 0
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::play index undefined
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:09 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::startPlaybackTimer
Nov 02 11:02:09 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:09 volumio volumio[749]: info: [1762092129349] ControllerWebradio::clearAddPlayTrack
Nov 02 11:02:09 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand stop
Nov 02 11:02:09 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:09 volumio volumio[749]: info:
Nov 02 11:02:09 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:09 volumio volumio[749]: info: sendMpdCommand stop took 61 milliseconds
Nov 02 11:02:09 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:09 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:09 volumio volumio[749]: info: sendMpdCommand stop took 18 milliseconds
Nov 02 11:02:09 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand clear
Nov 02 11:02:09 volumio volumio[749]: info:
Nov 02 11:02:09 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:09 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:09 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:09 volumio volumio[749]: info:
Nov 02 11:02:09 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:09 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:09 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:09 volumio volumio[749]: info:
Nov 02 11:02:09 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:09 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:09 volumio volumio[749]: info: sendMpdCommand status took 15 milliseconds
Nov 02 11:02:09 volumio volumio[749]: info: sendMpdCommand clear took 14 milliseconds
Nov 02 11:02:09 volumio volumio[749]: info: sendMpdCommand status took 12 milliseconds
Nov 02 11:02:09 volumio volumio[749]: info: sendMpdCommand status took 9 milliseconds
Nov 02 11:02:09 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:09 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:09 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand load "https://ice64.securenetsystems.net/CCR1MP3"
Nov 02 11:02:09 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:09 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:09 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:09 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:09 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:09 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:09 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:09 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:09 volumio volumio[749]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Nov 02 11:02:09 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:09 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:09 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:09 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:09 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:09 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:09 volumio volumio[749]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Nov 02 11:02:09 volumio volumio[749]: info: ------------------------------ 64ms
Nov 02 11:02:09 volumio volumio[749]: info: ------------------------------ 63ms
Nov 02 11:02:09 volumio volumio[749]: info:
Nov 02 11:02:09 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:09 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:09 volumio volumio[749]: info:
Nov 02 11:02:09 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:09 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:09 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:09 volumio volumio[749]: info: ------------------------------ 67ms
Nov 02 11:02:09 volumio volumio[749]: info: sendMpdCommand playlistinfo took 62 milliseconds
Nov 02 11:02:09 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:09 volumio volumio[749]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Nov 02 11:02:09 volumio volumio[749]: info: ------------------------------ 87ms
Nov 02 11:02:09 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:09 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:09 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:09 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:09 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:09 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:09 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:09 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:11 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand add "https://ice64.securenetsystems.net/CCR1MP3"
Nov 02 11:02:11 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:11 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:11 volumio volumio[749]: info:
Nov 02 11:02:11 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:11 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:11 volumio volumio[749]: info: ------------------------------ 2537ms
Nov 02 11:02:11 volumio volumio[749]: info: ------------------------------ 2535ms
Nov 02 11:02:11 volumio volumio[749]: info: sendMpdCommand add "https://ice64.securenetsystems.net/CCR1MP3" took 6 milliseconds
Nov 02 11:02:11 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService mpd
Nov 02 11:02:11 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand play
Nov 02 11:02:11 volumio volumio[749]: Upnp client error: Error: This socket has been ended by the other party
Nov 02 11:02:11 volumio volumio[749]: info:
Nov 02 11:02:11 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:11 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:11 volumio volumio[749]: info:
Nov 02 11:02:11 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:11 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:11 volumio volumio[749]: info: ------------------------------ 19ms
Nov 02 11:02:11 volumio volumio[749]: info: sendMpdCommand play took 16 milliseconds
Nov 02 11:02:11 volumio volumio[749]: info: ------------------------------ 12ms
Nov 02 11:02:11 volumio volumio[749]: info: ------------------------------ 8ms
Nov 02 11:02:12 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:12 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:12 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:14 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand status took 16 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:14 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:14 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:14 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand status took 37 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand status took 34 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand status took 26 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand playlistinfo took 25 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand status took 11 milliseconds
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: 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":"CCR1MP3","artist":null,"album":null,"uri":"https://ice64.securenetsystems.net/CCR1MP3","trackType":""}
Nov 02 11:02:14 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus stop
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 74ms
Nov 02 11:02:14 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:14 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:14 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand status took 38 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand playlistinfo took 29 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand playlistinfo took 29 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand playlistinfo took 16 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand playlistinfo took 15 milliseconds
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: 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":"CCR1MP3","artist":null,"album":null,"uri":"https://ice64.securenetsystems.net/CCR1MP3","trackType":""}
Nov 02 11:02:14 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:14 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: 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":"CCR1MP3","artist":null,"album":null,"uri":"https://ice64.securenetsystems.net/CCR1MP3","trackType":""}
Nov 02 11:02:14 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:14 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: 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":"CCR1MP3","artist":null,"album":null,"uri":"https://ice64.securenetsystems.net/CCR1MP3","trackType":""}
Nov 02 11:02:14 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:14 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: 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":"CCR1MP3","artist":null,"album":null,"uri":"https://ice64.securenetsystems.net/CCR1MP3","trackType":""}
Nov 02 11:02:14 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:14 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 269ms
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 267ms
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 260ms
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 247ms
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:14 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand playlistinfo took 204 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: 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":"CCR1MP3","artist":null,"album":null,"uri":"https://ice64.securenetsystems.net/CCR1MP3","trackType":""}
Nov 02 11:02:14 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:14 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 300ms
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:14 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:14 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:14 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 106ms
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand status took 102 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 17ms
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand status took 15 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 13ms
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand status took 12 milliseconds
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:14 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:14 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:14 volumio volumio[749]: info:
Nov 02 11:02:14 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 27ms
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand status took 23 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand playlistinfo took 19 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand playlistinfo took 19 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand playlistinfo took 18 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 17ms
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand status took 14 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 13ms
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand status took 10 milliseconds
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ellie Holcomb - Marvelous Light Ellie Holcomb - ","artist":"Cape Christian Radio","album":null,"uri":"https://ice64.securenetsystems.net/CCR1MP3","trackType":""}
Nov 02 11:02:14 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:14 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1082,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ellie Holcomb - Marvelous Light Ellie Holcomb - ","artist":"Cape Christian Radio","album":null,"uri":"https://ice64.securenetsystems.net/CCR1MP3","trackType":""}
Nov 02 11:02:14 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:14 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1082,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ellie Holcomb - Marvelous Light Ellie Holcomb - ","artist":"Cape Christian Radio","album":null,"uri":"https://ice64.securenetsystems.net/CCR1MP3","trackType":""}
Nov 02 11:02:14 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:14 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 259ms
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 171ms
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 168ms
Nov 02 11:02:14 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:14 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:14 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:14 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:14 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:14 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand playlistinfo took 136 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand playlistinfo took 135 milliseconds
Nov 02 11:02:14 volumio volumio[749]: info: sendMpdCommand playlistinfo took 135 milliseconds
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:14 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1082,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ellie Holcomb - Marvelous Light Ellie Holcomb - ","artist":"Cape Christian Radio","album":null,"uri":"https://ice64.securenetsystems.net/CCR1MP3","trackType":""}
Nov 02 11:02:14 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:14 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1082,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ellie Holcomb - Marvelous Light Ellie Holcomb - ","artist":"Cape Christian Radio","album":null,"uri":"https://ice64.securenetsystems.net/CCR1MP3","trackType":""}
Nov 02 11:02:14 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:14 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:14 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:14 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1082,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ellie Holcomb - Marvelous Light Ellie Holcomb - ","artist":"Cape Christian Radio","album":null,"uri":"https://ice64.securenetsystems.net/CCR1MP3","trackType":""}
Nov 02 11:02:14 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:14 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:14 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 313ms
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 302ms
Nov 02 11:02:14 volumio volumio[749]: info: ------------------------------ 300ms
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:14 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:15 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:02:15 volumio volumio[749]: info: CoreCommandRouter::volumioReplaceandPlayItems
Nov 02 11:02:15 volumio volumio[749]: info: CoreStateMachine::ClearQueue
Nov 02 11:02:15 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:02:15 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:15 volumio volumio[749]: info: CoreStateMachine::stPlaybackTimer
Nov 02 11:02:15 volumio volumio[749]: info: CoreStateMachine::updateTrackBlock
Nov 02 11:02:15 volumio volumio[749]: info: CorePlayQueue::getTrackBlock
Nov 02 11:02:15 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:15 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:15 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:15 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:15 volumio volumio[749]: info: CoreStateMachine::serviceStop
Nov 02 11:02:15 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:15 volumio volumio[749]: info: CoreCommandRouter::serviceStop
Nov 02 11:02:15 volumio volumio[749]: info: [1762092135998] ControllerWebradio::stop
Nov 02 11:02:15 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand stop
Nov 02 11:02:15 volumio volumio[749]: info: CorePlayQueue::clearPlayQueue
Nov 02 11:02:15 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:02:16 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:02:16 volumio volumio[749]: info: CoreStateMachine::addQueueItems
Nov 02 11:02:16 volumio volumio[749]: info: CorePlayQueue::addQueueItems
Nov 02 11:02:16 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:02:16 volumio volumio[749]: info: Adding Item to queue: http://streams.calmradio.com/api/169/128/stream
Nov 02 11:02:16 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Nov 02 11:02:16 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:02:16 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:02:16 volumio volumio[749]: info: CoreStateMachine::updateTrackBlock
Nov 02 11:02:16 volumio volumio[749]: info: CorePlayQueue::getTrackBlock
Nov 02 11:02:16 volumio volumio[749]: info: CoreCommandRouter::volumioPlay
Nov 02 11:02:16 volumio volumio[749]: info: CoreStateMachine::play index 0
Nov 02 11:02:16 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:16 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:02:16 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:16 volumio volumio[749]: info: CoreStateMachine::play index undefined
Nov 02 11:02:16 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:16 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:16 volumio volumio[749]: info: CoreStateMachine::startPlaybackTimer
Nov 02 11:02:16 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:16 volumio volumio[749]: info: [1762092136069] ControllerWebradio::clearAddPlayTrack
Nov 02 11:02:16 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand stop
Nov 02 11:02:16 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:16 volumio volumio[749]: info:
Nov 02 11:02:16 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:16 volumio volumio[749]: info: sendMpdCommand stop took 85 milliseconds
Nov 02 11:02:16 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:16 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:16 volumio volumio[749]: info: sendMpdCommand stop took 16 milliseconds
Nov 02 11:02:16 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand clear
Nov 02 11:02:16 volumio volumio[749]: info:
Nov 02 11:02:16 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:16 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:16 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:16 volumio volumio[749]: info:
Nov 02 11:02:16 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:16 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:16 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:16 volumio volumio[749]: info:
Nov 02 11:02:16 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:16 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:16 volumio volumio[749]: info: sendMpdCommand status took 13 milliseconds
Nov 02 11:02:16 volumio volumio[749]: info: sendMpdCommand clear took 12 milliseconds
Nov 02 11:02:16 volumio volumio[749]: info: sendMpdCommand status took 10 milliseconds
Nov 02 11:02:16 volumio volumio[749]: info: sendMpdCommand status took 8 milliseconds
Nov 02 11:02:16 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:16 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:16 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand load "http://streams.calmradio.com/api/169/128/stream"
Nov 02 11:02:16 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:16 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:16 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:16 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:16 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:16 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:16 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:16 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:16 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:16 volumio volumio[749]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Nov 02 11:02:16 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:16 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:16 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:16 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:16 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:16 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:16 volumio volumio[749]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Nov 02 11:02:16 volumio volumio[749]: info: ------------------------------ 59ms
Nov 02 11:02:16 volumio volumio[749]: info: ------------------------------ 58ms
Nov 02 11:02:16 volumio volumio[749]: info:
Nov 02 11:02:16 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:16 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:16 volumio volumio[749]: info:
Nov 02 11:02:16 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:16 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:16 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:16 volumio volumio[749]: info: ------------------------------ 62ms
Nov 02 11:02:16 volumio volumio[749]: info: sendMpdCommand playlistinfo took 58 milliseconds
Nov 02 11:02:16 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:16 volumio volumio[749]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Nov 02 11:02:16 volumio volumio[749]: info: ------------------------------ 79ms
Nov 02 11:02:16 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:16 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:16 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:16 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:16 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:16 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:16 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:16 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:16 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand add "http://streams.calmradio.com/api/169/128/stream"
Nov 02 11:02:16 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:16 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:16 volumio volumio[749]: info:
Nov 02 11:02:16 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:16 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:16 volumio volumio[749]: info: ------------------------------ 763ms
Nov 02 11:02:16 volumio volumio[749]: info: ------------------------------ 762ms
Nov 02 11:02:16 volumio volumio[749]: info: sendMpdCommand add "http://streams.calmradio.com/api/169/128/stream" took 5 milliseconds
Nov 02 11:02:16 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService mpd
Nov 02 11:02:16 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand play
Nov 02 11:02:16 volumio volumio[749]: info:
Nov 02 11:02:16 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:16 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:16 volumio volumio[749]: info:
Nov 02 11:02:16 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:16 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:16 volumio volumio[749]: info: ------------------------------ 16ms
Nov 02 11:02:16 volumio volumio[749]: info: sendMpdCommand play took 13 milliseconds
Nov 02 11:02:16 volumio volumio[749]: info: ------------------------------ 10ms
Nov 02 11:02:16 volumio volumio[749]: info: ------------------------------ 8ms
Nov 02 11:02:16 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:16 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:16 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:17 volumio volumio[749]: info:
Nov 02 11:02:17 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:17 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Nov 02 11:02:17 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:17 volumio volumio[749]: info:
Nov 02 11:02:17 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:17 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:17 volumio volumio[749]: info:
Nov 02 11:02:17 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:17 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:17 volumio volumio[749]: info:
Nov 02 11:02:17 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:17 volumio volumio[749]: info: sendMpdCommand status took 16 milliseconds
Nov 02 11:02:17 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:17 volumio volumio[749]: info: sendMpdCommand status took 17 milliseconds
Nov 02 11:02:17 volumio volumio[749]: info: sendMpdCommand status took 14 milliseconds
Nov 02 11:02:17 volumio volumio[749]: info: sendMpdCommand status took 5 milliseconds
Nov 02 11:02:17 volumio volumio[749]: info: sendMpdCommand playlistinfo took 5 milliseconds
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:17 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:17 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:17 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:02:17 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus stop
Nov 02 11:02:17 volumio volumio[749]: info: ------------------------------ 34ms
Nov 02 11:02:17 volumio volumio[749]: info:
Nov 02 11:02:17 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:17 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:17 volumio volumio[749]: info:
Nov 02 11:02:17 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:17 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:17 volumio volumio[749]: info: sendMpdCommand playlistinfo took 16 milliseconds
Nov 02 11:02:17 volumio volumio[749]: info: sendMpdCommand playlistinfo took 16 milliseconds
Nov 02 11:02:17 volumio volumio[749]: info: sendMpdCommand playlistinfo took 16 milliseconds
Nov 02 11:02:17 volumio volumio[749]: info: sendMpdCommand status took 8 milliseconds
Nov 02 11:02:17 volumio volumio[749]: info: sendMpdCommand status took 5 milliseconds
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:17 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:17 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:17 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:02:17 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:17 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:17 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:17 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:17 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:02:17 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:17 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:17 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:17 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:17 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:02:17 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:17 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:17 volumio volumio[749]: info: ------------------------------ 174ms
Nov 02 11:02:17 volumio volumio[749]: info: ------------------------------ 172ms
Nov 02 11:02:17 volumio volumio[749]: info: ------------------------------ 165ms
Nov 02 11:02:17 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:17 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:17 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:17 volumio volumio[749]: info: sendMpdCommand playlistinfo took 138 milliseconds
Nov 02 11:02:17 volumio volumio[749]: info: sendMpdCommand playlistinfo took 138 milliseconds
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:17 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:17 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:17 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:02:17 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:17 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:17 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:17 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:17 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:02:17 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:17 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:17 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:17 volumio volumio[749]: info: ------------------------------ 241ms
Nov 02 11:02:17 volumio volumio[749]: info: ------------------------------ 239ms
Nov 02 11:02:17 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:17 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:17 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:17 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:17 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:17 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:17 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:17 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:17 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:17 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:17 volumio volumio[749]: info:
Nov 02 11:02:17 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:17 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:17 volumio volumio[749]: info:
Nov 02 11:02:17 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:17 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:17 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:17 volumio volumio[749]: info:
Nov 02 11:02:17 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:17 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:17 volumio volumio[749]: info:
Nov 02 11:02:17 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:18 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:18 volumio volumio[749]: info:
Nov 02 11:02:18 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:18 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:18 volumio volumio[749]: info:
Nov 02 11:02:18 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:18 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:18 volumio volumio[749]: info:
Nov 02 11:02:18 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:18 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:18 volumio volumio[749]: info:
Nov 02 11:02:18 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:18 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:18 volumio volumio[749]: info: ------------------------------ 24ms
Nov 02 11:02:18 volumio volumio[749]: info: sendMpdCommand status took 22 milliseconds
Nov 02 11:02:18 volumio volumio[749]: info: ------------------------------ 21ms
Nov 02 11:02:18 volumio volumio[749]: info: sendMpdCommand status took 19 milliseconds
Nov 02 11:02:18 volumio volumio[749]: info: ------------------------------ 17ms
Nov 02 11:02:18 volumio volumio[749]: info: sendMpdCommand status took 13 milliseconds
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:18 volumio volumio[749]: info:
Nov 02 11:02:18 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:18 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:18 volumio volumio[749]: info:
Nov 02 11:02:18 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:18 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:18 volumio volumio[749]: info:
Nov 02 11:02:18 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:18 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:18 volumio volumio[749]: info:
Nov 02 11:02:18 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:18 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:18 volumio volumio[749]: info: ------------------------------ 29ms
Nov 02 11:02:18 volumio volumio[749]: info: sendMpdCommand status took 26 milliseconds
Nov 02 11:02:18 volumio volumio[749]: info: sendMpdCommand playlistinfo took 22 milliseconds
Nov 02 11:02:18 volumio volumio[749]: info: sendMpdCommand playlistinfo took 22 milliseconds
Nov 02 11:02:18 volumio volumio[749]: info: sendMpdCommand playlistinfo took 22 milliseconds
Nov 02 11:02:18 volumio volumio[749]: info: ------------------------------ 21ms
Nov 02 11:02:18 volumio volumio[749]: info: sendMpdCommand status took 20 milliseconds
Nov 02 11:02:18 volumio volumio[749]: info: ------------------------------ 18ms
Nov 02 11:02:18 volumio volumio[749]: info: sendMpdCommand status took 16 milliseconds
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:18 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:18 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:18 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stanton Lanier - A Thousand Years - Be Still My Soul (feat. Jill Haley)","artist":"CALMRADIO.COM - Christian Instrumentals","album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:02:18 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:18 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:18 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:18 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:18 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stanton Lanier - A Thousand Years - Be Still My Soul (feat. Jill Haley)","artist":"CALMRADIO.COM - Christian Instrumentals","album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:02:18 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:18 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:18 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:18 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:18 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stanton Lanier - A Thousand Years - Be Still My Soul (feat. Jill Haley)","artist":"CALMRADIO.COM - Christian Instrumentals","album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:02:18 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:18 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:18 volumio volumio[749]: info: ------------------------------ 216ms
Nov 02 11:02:18 volumio volumio[749]: info: ------------------------------ 213ms
Nov 02 11:02:18 volumio volumio[749]: info: ------------------------------ 208ms
Nov 02 11:02:18 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:18 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:18 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:18 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:18 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:18 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:18 volumio volumio[749]: info: sendMpdCommand playlistinfo took 166 milliseconds
Nov 02 11:02:18 volumio volumio[749]: info: sendMpdCommand playlistinfo took 164 milliseconds
Nov 02 11:02:18 volumio volumio[749]: info: sendMpdCommand playlistinfo took 163 milliseconds
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:18 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:18 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:18 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:18 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stanton Lanier - A Thousand Years - Be Still My Soul (feat. Jill Haley)","artist":"CALMRADIO.COM - Christian Instrumentals","album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:02:18 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:18 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:18 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:18 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:18 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stanton Lanier - A Thousand Years - Be Still My Soul (feat. Jill Haley)","artist":"CALMRADIO.COM - Christian Instrumentals","album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:02:18 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:18 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:18 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:18 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:18 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stanton Lanier - A Thousand Years - Be Still My Soul (feat. Jill Haley)","artist":"CALMRADIO.COM - Christian Instrumentals","album":null,"uri":"http://streams.calmradio.com/api/169/128/stream","trackType":""}
Nov 02 11:02:18 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:18 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:18 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:18 volumio volumio[749]: info: ------------------------------ 324ms
Nov 02 11:02:18 volumio volumio[749]: info: ------------------------------ 313ms
Nov 02 11:02:18 volumio volumio[749]: info: ------------------------------ 310ms
Nov 02 11:02:18 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:18 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:18 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:18 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:18 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:18 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:18 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:18 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:18 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:18 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:18 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:18 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:23 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:02:23 volumio volumio[749]: info: CoreCommandRouter::volumioReplaceandPlayItems
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::ClearQueue
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::stPlaybackTimer
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::updateTrackBlock
Nov 02 11:02:23 volumio volumio[749]: info: CorePlayQueue::getTrackBlock
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:23 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:23 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:23 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::serviceStop
Nov 02 11:02:23 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:23 volumio volumio[749]: info: CoreCommandRouter::serviceStop
Nov 02 11:02:23 volumio volumio[749]: info: [1762092143911] ControllerWebradio::stop
Nov 02 11:02:23 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand stop
Nov 02 11:02:23 volumio volumio[749]: info: CorePlayQueue::clearPlayQueue
Nov 02 11:02:23 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:02:23 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::addQueueItems
Nov 02 11:02:23 volumio volumio[749]: info: CorePlayQueue::addQueueItems
Nov 02 11:02:23 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:02:23 volumio volumio[749]: info: Adding Item to queue: http://88.208.244.107:8002/stream
Nov 02 11:02:23 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Nov 02 11:02:23 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:02:23 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::updateTrackBlock
Nov 02 11:02:23 volumio volumio[749]: info: CorePlayQueue::getTrackBlock
Nov 02 11:02:23 volumio volumio[749]: info: CoreCommandRouter::volumioPlay
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::play index 0
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::play index undefined
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:23 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:23 volumio volumio[749]: info: CoreStateMachine::startPlaybackTimer
Nov 02 11:02:23 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:23 volumio volumio[749]: info: [1762092143970] ControllerWebradio::clearAddPlayTrack
Nov 02 11:02:23 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand stop
Nov 02 11:02:23 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:23 volumio volumio[749]: info:
Nov 02 11:02:23 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:23 volumio volumio[749]: info: sendMpdCommand stop took 73 milliseconds
Nov 02 11:02:23 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:23 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:23 volumio volumio[749]: info: sendMpdCommand stop took 16 milliseconds
Nov 02 11:02:23 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand clear
Nov 02 11:02:23 volumio volumio[749]: info:
Nov 02 11:02:23 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:23 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:23 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:23 volumio volumio[749]: info:
Nov 02 11:02:23 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:23 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:23 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:24 volumio volumio[749]: info:
Nov 02 11:02:24 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:24 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:24 volumio volumio[749]: info: sendMpdCommand status took 16 milliseconds
Nov 02 11:02:24 volumio volumio[749]: info: sendMpdCommand clear took 14 milliseconds
Nov 02 11:02:24 volumio volumio[749]: info: sendMpdCommand status took 12 milliseconds
Nov 02 11:02:24 volumio volumio[749]: info: sendMpdCommand status took 9 milliseconds
Nov 02 11:02:24 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:24 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:24 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand load "http://88.208.244.107:8002/stream"
Nov 02 11:02:24 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:24 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:24 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:24 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:24 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:24 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:24 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:24 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:24 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:24 volumio volumio[749]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Nov 02 11:02:24 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:24 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:24 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:24 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:24 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:24 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:24 volumio volumio[749]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Nov 02 11:02:24 volumio volumio[749]: info: ------------------------------ 66ms
Nov 02 11:02:24 volumio volumio[749]: info: ------------------------------ 64ms
Nov 02 11:02:24 volumio volumio[749]: info:
Nov 02 11:02:24 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:24 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:24 volumio volumio[749]: info:
Nov 02 11:02:24 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:24 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:24 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:24 volumio volumio[749]: info: ------------------------------ 66ms
Nov 02 11:02:24 volumio volumio[749]: info: sendMpdCommand playlistinfo took 61 milliseconds
Nov 02 11:02:24 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:24 volumio volumio[749]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Nov 02 11:02:24 volumio volumio[749]: info: ------------------------------ 86ms
Nov 02 11:02:24 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:24 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:24 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:24 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:24 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:24 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:24 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:24 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:24 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand add "http://88.208.244.107:8002/stream"
Nov 02 11:02:24 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:24 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:24 volumio volumio[749]: info:
Nov 02 11:02:24 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:24 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:24 volumio volumio[749]: info: ------------------------------ 641ms
Nov 02 11:02:24 volumio volumio[749]: info: ------------------------------ 640ms
Nov 02 11:02:24 volumio volumio[749]: info: sendMpdCommand add "http://88.208.244.107:8002/stream" took 5 milliseconds
Nov 02 11:02:24 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService mpd
Nov 02 11:02:24 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand play
Nov 02 11:02:24 volumio volumio[749]: info:
Nov 02 11:02:24 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:24 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:24 volumio volumio[749]: info:
Nov 02 11:02:24 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:24 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:24 volumio volumio[749]: info: ------------------------------ 15ms
Nov 02 11:02:24 volumio volumio[749]: info: sendMpdCommand play took 13 milliseconds
Nov 02 11:02:24 volumio volumio[749]: info: ------------------------------ 10ms
Nov 02 11:02:24 volumio volumio[749]: info: ------------------------------ 8ms
Nov 02 11:02:24 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:24 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:24 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:26 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Nov 02 11:02:26 volumio volumio[749]: info:
Nov 02 11:02:26 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:26 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:26 volumio volumio[749]: info:
Nov 02 11:02:26 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:26 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:26 volumio volumio[749]: info:
Nov 02 11:02:26 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:26 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:26 volumio volumio[749]: info:
Nov 02 11:02:26 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:26 volumio volumio[749]: info: sendMpdCommand status took 16 milliseconds
Nov 02 11:02:26 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:26 volumio volumio[749]: info: sendMpdCommand status took 19 milliseconds
Nov 02 11:02:26 volumio volumio[749]: info: sendMpdCommand status took 17 milliseconds
Nov 02 11:02:26 volumio volumio[749]: info: sendMpdCommand status took 5 milliseconds
Nov 02 11:02:26 volumio volumio[749]: info: sendMpdCommand playlistinfo took 5 milliseconds
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:26 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:26 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:26 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://88.208.244.107:8002/stream","trackType":""}
Nov 02 11:02:26 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus stop
Nov 02 11:02:26 volumio volumio[749]: info: ------------------------------ 33ms
Nov 02 11:02:26 volumio volumio[749]: info:
Nov 02 11:02:26 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:26 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:26 volumio volumio[749]: info:
Nov 02 11:02:26 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:26 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:26 volumio volumio[749]: info: sendMpdCommand playlistinfo took 16 milliseconds
Nov 02 11:02:26 volumio volumio[749]: info: sendMpdCommand playlistinfo took 15 milliseconds
Nov 02 11:02:26 volumio volumio[749]: info: sendMpdCommand playlistinfo took 15 milliseconds
Nov 02 11:02:26 volumio volumio[749]: info: sendMpdCommand status took 8 milliseconds
Nov 02 11:02:26 volumio volumio[749]: info: sendMpdCommand status took 5 milliseconds
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:26 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:26 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:26 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://88.208.244.107:8002/stream","trackType":""}
Nov 02 11:02:26 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:26 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:26 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:26 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:26 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://88.208.244.107:8002/stream","trackType":""}
Nov 02 11:02:26 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:26 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:26 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:26 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:26 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://88.208.244.107:8002/stream","trackType":""}
Nov 02 11:02:26 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:26 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:26 volumio volumio[749]: info: ------------------------------ 188ms
Nov 02 11:02:26 volumio volumio[749]: info: ------------------------------ 188ms
Nov 02 11:02:26 volumio volumio[749]: info: ------------------------------ 177ms
Nov 02 11:02:26 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:26 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:26 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:26 volumio volumio[749]: info: sendMpdCommand playlistinfo took 152 milliseconds
Nov 02 11:02:26 volumio volumio[749]: info: sendMpdCommand playlistinfo took 152 milliseconds
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:26 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:26 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:26 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:26 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://88.208.244.107:8002/stream","trackType":""}
Nov 02 11:02:26 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:26 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:26 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:26 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:26 volumio volumio[749]: 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":"stream","artist":null,"album":null,"uri":"http://88.208.244.107:8002/stream","trackType":""}
Nov 02 11:02:26 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:26 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:26 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:26 volumio volumio[749]: info: ------------------------------ 246ms
Nov 02 11:02:26 volumio volumio[749]: info: ------------------------------ 245ms
Nov 02 11:02:26 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:26 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:26 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:26 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:26 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:26 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:26 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:26 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:26 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:26 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:27 volumio volumio[749]: info:
Nov 02 11:02:27 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:27 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:27 volumio volumio[749]: info:
Nov 02 11:02:27 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:27 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:27 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:27 volumio volumio[749]: info:
Nov 02 11:02:27 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:27 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:27 volumio volumio[749]: info:
Nov 02 11:02:27 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:27 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:27 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:27 volumio volumio[749]: info:
Nov 02 11:02:27 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:27 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:27 volumio volumio[749]: info:
Nov 02 11:02:27 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:27 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:27 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:27 volumio volumio[749]: info: ------------------------------ 16ms
Nov 02 11:02:27 volumio volumio[749]: info: sendMpdCommand status took 13 milliseconds
Nov 02 11:02:27 volumio volumio[749]: info: ------------------------------ 12ms
Nov 02 11:02:27 volumio volumio[749]: info: sendMpdCommand status took 10 milliseconds
Nov 02 11:02:27 volumio volumio[749]: info: ------------------------------ 9ms
Nov 02 11:02:27 volumio volumio[749]: info: sendMpdCommand status took 7 milliseconds
Nov 02 11:02:27 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:27 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:27 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:27 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:27 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:27 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:27 volumio volumio[749]: info: sendMpdCommand playlistinfo took 4 milliseconds
Nov 02 11:02:27 volumio volumio[749]: info: sendMpdCommand playlistinfo took 4 milliseconds
Nov 02 11:02:27 volumio volumio[749]: info: sendMpdCommand playlistinfo took 4 milliseconds
Nov 02 11:02:27 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:27 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:27 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:27 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:27 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:27 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:27 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"stream","artist":"arlondon","album":null,"uri":"http://88.208.244.107:8002/stream","trackType":""}
Nov 02 11:02:27 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:27 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:27 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:27 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:27 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:27 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:27 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:27 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:27 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:27 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:27 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:27 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:27 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:27 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:27 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"stream","artist":"arlondon","album":null,"uri":"http://88.208.244.107:8002/stream","trackType":""}
Nov 02 11:02:27 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:27 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:27 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:27 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:27 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:27 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:27 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:27 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:27 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:27 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:27 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:27 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:27 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:27 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"stream","artist":"arlondon","album":null,"uri":"http://88.208.244.107:8002/stream","trackType":""}
Nov 02 11:02:27 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:27 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:27 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:27 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:27 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:27 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:27 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:27 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:27 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:27 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:27 volumio volumio[749]: info: ------------------------------ 141ms
Nov 02 11:02:27 volumio volumio[749]: info: ------------------------------ 138ms
Nov 02 11:02:27 volumio volumio[749]: info: ------------------------------ 136ms
Nov 02 11:02:27 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:27 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:27 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:27 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:27 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:27 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:27 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:27 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:27 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:27 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:27 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:27 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:28 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:02:28 volumio volumio[749]: info: CoreCommandRouter::volumioReplaceandPlayItems
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::ClearQueue
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::stPlaybackTimer
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::updateTrackBlock
Nov 02 11:02:28 volumio volumio[749]: info: CorePlayQueue::getTrackBlock
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:28 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:28 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:28 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::serviceStop
Nov 02 11:02:28 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:28 volumio volumio[749]: info: CoreCommandRouter::serviceStop
Nov 02 11:02:28 volumio volumio[749]: info: [1762092148440] ControllerWebradio::stop
Nov 02 11:02:28 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand stop
Nov 02 11:02:28 volumio volumio[749]: info: CorePlayQueue::clearPlayQueue
Nov 02 11:02:28 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:02:28 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::addQueueItems
Nov 02 11:02:28 volumio volumio[749]: info: CorePlayQueue::addQueueItems
Nov 02 11:02:28 volumio volumio[749]: info: Preload queue cleared
Nov 02 11:02:28 volumio volumio[749]: info: Adding Item to queue: http://streams.abidingradio.org:7840/1
Nov 02 11:02:28 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Nov 02 11:02:28 volumio volumio[749]: info: CoreCommandRouter::volumioPushQueue
Nov 02 11:02:28 volumio volumio[749]: info: CorePlayQueue::saveQueue
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::updateTrackBlock
Nov 02 11:02:28 volumio volumio[749]: info: CorePlayQueue::getTrackBlock
Nov 02 11:02:28 volumio volumio[749]: info: CoreCommandRouter::volumioPlay
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::play index 0
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::stop
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::play index undefined
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 02 11:02:28 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::startPlaybackTimer
Nov 02 11:02:28 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:28 volumio volumio[749]: info: [1762092148480] ControllerWebradio::clearAddPlayTrack
Nov 02 11:02:28 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand stop
Nov 02 11:02:28 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:28 volumio volumio[749]: info:
Nov 02 11:02:28 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:28 volumio volumio[749]: info: sendMpdCommand stop took 50 milliseconds
Nov 02 11:02:28 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:28 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:28 volumio volumio[749]: info: sendMpdCommand stop took 11 milliseconds
Nov 02 11:02:28 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand clear
Nov 02 11:02:28 volumio volumio[749]: info:
Nov 02 11:02:28 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:28 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:28 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:28 volumio volumio[749]: info:
Nov 02 11:02:28 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:28 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:28 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:28 volumio volumio[749]: info:
Nov 02 11:02:28 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:28 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:28 volumio volumio[749]: info: sendMpdCommand status took 13 milliseconds
Nov 02 11:02:28 volumio volumio[749]: info: sendMpdCommand clear took 12 milliseconds
Nov 02 11:02:28 volumio volumio[749]: info: sendMpdCommand status took 11 milliseconds
Nov 02 11:02:28 volumio volumio[749]: info: sendMpdCommand status took 19 milliseconds
Nov 02 11:02:28 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:28 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:28 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand load "http://streams.abidingradio.org:7840/1"
Nov 02 11:02:28 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:28 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:28 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:28 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:28 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:28 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:28 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:28 volumio volumio[749]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Nov 02 11:02:28 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:28 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:28 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:28 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:28 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:28 volumio volumio[749]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Nov 02 11:02:28 volumio volumio[749]: info: ------------------------------ 82ms
Nov 02 11:02:28 volumio volumio[749]: info: ------------------------------ 81ms
Nov 02 11:02:28 volumio volumio[749]: info:
Nov 02 11:02:28 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:28 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:28 volumio volumio[749]: info:
Nov 02 11:02:28 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:28 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:28 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:28 volumio volumio[749]: info: ------------------------------ 83ms
Nov 02 11:02:28 volumio volumio[749]: info: sendMpdCommand playlistinfo took 67 milliseconds
Nov 02 11:02:28 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:28 volumio volumio[749]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Nov 02 11:02:28 volumio volumio[749]: info: ------------------------------ 100ms
Nov 02 11:02:28 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:28 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:28 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:28 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:28 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:28 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:28 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:28 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:28 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand add "http://streams.abidingradio.org:7840/1"
Nov 02 11:02:28 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:28 volumio volumio[749]: error: updateQueue error: null
Nov 02 11:02:28 volumio volumio[749]: info:
Nov 02 11:02:28 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:28 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:28 volumio volumio[749]: info: ------------------------------ 366ms
Nov 02 11:02:28 volumio volumio[749]: info: ------------------------------ 364ms
Nov 02 11:02:28 volumio volumio[749]: info: sendMpdCommand add "http://streams.abidingradio.org:7840/1" took 5 milliseconds
Nov 02 11:02:28 volumio volumio[749]: info: CoreStateMachine::setConsumeUpdateService mpd
Nov 02 11:02:28 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand play
Nov 02 11:02:28 volumio volumio[749]: info:
Nov 02 11:02:28 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:28 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:28 volumio volumio[749]: info:
Nov 02 11:02:28 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:28 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:28 volumio volumio[749]: info: ------------------------------ 15ms
Nov 02 11:02:28 volumio volumio[749]: info: sendMpdCommand play took 12 milliseconds
Nov 02 11:02:28 volumio volumio[749]: info: ------------------------------ 10ms
Nov 02 11:02:28 volumio volumio[749]: info: ------------------------------ 7ms
Nov 02 11:02:28 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:28 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:28 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:29 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand status took 17 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand status took 19 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand status took 16 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand status took 6 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand playlistinfo took 6 milliseconds
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: 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":"1","artist":null,"album":null,"uri":"http://streams.abidingradio.org:7840/1","trackType":"org:7840/1"}
Nov 02 11:02:29 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus stop
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 35ms
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand playlistinfo took 15 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand playlistinfo took 15 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand playlistinfo took 14 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand status took 7 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand status took 4 milliseconds
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: 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":"1","artist":null,"album":null,"uri":"http://streams.abidingradio.org:7840/1","trackType":"org:7840/1"}
Nov 02 11:02:29 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:29 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: 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":"1","artist":null,"album":null,"uri":"http://streams.abidingradio.org:7840/1","trackType":"org:7840/1"}
Nov 02 11:02:29 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:29 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: 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":"1","artist":null,"album":null,"uri":"http://streams.abidingradio.org:7840/1","trackType":"org:7840/1"}
Nov 02 11:02:29 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:29 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 194ms
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 193ms
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 184ms
Nov 02 11:02:29 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:29 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:29 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:29 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand playlistinfo took 160 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand playlistinfo took 160 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: 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":"1","artist":null,"album":null,"uri":"http://streams.abidingradio.org:7840/1","trackType":"org:7840/1"}
Nov 02 11:02:29 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:29 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: 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":"1","artist":null,"album":null,"uri":"http://streams.abidingradio.org:7840/1","trackType":"org:7840/1"}
Nov 02 11:02:29 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:29 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 272ms
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 272ms
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:29 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:29 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:29 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 162ms
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand status took 160 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 15ms
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand status took 13 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 12ms
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand status took 10 milliseconds
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:29 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces system playlist update
Nov 02 11:02:29 volumio volumio[749]: info: Ignoring MPD Status Update
Nov 02 11:02:29 volumio volumio[749]: info:
Nov 02 11:02:29 volumio volumio[749]: ---------------------------- MPD announces state update: player
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::getState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand status
Nov 02 11:02:29 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'playlist'
Nov 02 11:02:29 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:29 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem playlist: 0
Nov 02 11:02:29 volumio volumio[749]: [MPDPlayer.MPDEventListener] Event received for subsystem 'player'
Nov 02 11:02:29 volumio volumio[749]: [MPDPlayer.MPDEventListener] Calling handler.handle()...
Nov 02 11:02:29 volumio volumio[749]: [MPDPlayer.MPDEventHandler] Number of callbacks for subsystem player: 1
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 44ms
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand status took 41 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand playlistinfo took 36 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand playlistinfo took 36 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand playlistinfo took 35 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 35ms
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand status took 32 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 32ms
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand status took 30 milliseconds
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseState
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Craig Duncan - Wonderful Words of Life","artist":"Abiding Radio - Bluegrass Hymns","album":null,"uri":"http://streams.abidingradio.org:7840/1","trackType":"org:7840/1"}
Nov 02 11:02:29 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:29 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Craig Duncan - Wonderful Words of Life","artist":"Abiding Radio - Bluegrass Hymns","album":null,"uri":"http://streams.abidingradio.org:7840/1","trackType":"org:7840/1"}
Nov 02 11:02:29 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:29 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1077,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Craig Duncan - Wonderful Words of Life","artist":"Abiding Radio - Bluegrass Hymns","album":null,"uri":"http://streams.abidingradio.org:7840/1","trackType":"org:7840/1"}
Nov 02 11:02:29 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:29 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 332ms
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 185ms
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 183ms
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand playlistinfo took 131 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand playlistinfo took 130 milliseconds
Nov 02 11:02:29 volumio volumio[749]: info: sendMpdCommand playlistinfo took 130 milliseconds
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:29 volumio volumio[749]: verbose: ControllerMpd::parseTrackInfo
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1077,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Craig Duncan - Wonderful Words of Life","artist":"Abiding Radio - Bluegrass Hymns","album":null,"uri":"http://streams.abidingradio.org:7840/1","trackType":"org:7840/1"}
Nov 02 11:02:29 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:29 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1077,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Craig Duncan - Wonderful Words of Life","artist":"Abiding Radio - Bluegrass Hymns","album":null,"uri":"http://streams.abidingradio.org:7840/1","trackType":"org:7840/1"}
Nov 02 11:02:29 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:29 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: ControllerMpd::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::servicePushState
Nov 02 11:02:29 volumio volumio[749]: info: CorePlayQueue::getTrack 0
Nov 02 11:02:29 volumio volumio[749]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1077,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Craig Duncan - Wonderful Words of Life","artist":"Abiding Radio - Bluegrass Hymns","album":null,"uri":"http://streams.abidingradio.org:7840/1","trackType":"org:7840/1"}
Nov 02 11:02:29 volumio volumio[749]: verbose: CURRENT POSITION 0
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState stateService play
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::syncState currentStatus play
Nov 02 11:02:29 volumio volumio[749]: info: Received an update from plugin. extracting info from payload
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreStateMachine::pushState
Nov 02 11:02:29 volumio volumio[749]: info: CoreCommandRouter::volumioPushState
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 295ms
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 284ms
Nov 02 11:02:29 volumio volumio[749]: info: ------------------------------ 282ms
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:29 volumio volumio[749]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Nov 02 11:02:30 volumio volumio[749]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Nov 02 11:02:30 volumio volumio[749]: [Error: ENOSPC: no space left on device, write] {
Nov 02 11:02:30 volumio volumio[749]: errno: -28,
Nov 02 11:02:30 volumio volumio[749]: code: 'ENOSPC',
Nov 02 11:02:30 volumio volumio[749]: syscall: 'write'
Nov 02 11:02:30 volumio volumio[749]: }
Nov 02 11:02:30 volumio volumio[749]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Nov 02 11:02:31 volumio sudo[1753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 11:01
Nov 02 11:02:31 volumio sudo[1753]: 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="f6610a1100d2380eef33bd3004af43153c786f96"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET"
VOLUMIO_VERSION="3.785"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"