-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2026-02-09 17:02:30 CET. -- Feb 09 17:01:00 volumio3a kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Feb 09 17:01:01 volumio3a volumio[912]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 09 17:01:01 volumio3a volumio[912]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 09 17:01:01 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 17:01:01 volumio3a volumio[912]: info: Starting MyVolumio Remote Streaming Endpoints Feb 09 17:01:01 volumio3a volumio[912]: info: MyVolumio login type: Token Feb 09 17:01:01 volumio3a volumio[912]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 09 17:01:01 volumio3a volumio[912]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 09 17:01:03 volumio3a volumio[912]: info: Starting Streaming Service Transparent Proxy Feb 09 17:01:03 volumio3a volumio[912]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 09 17:01:03 volumio3a volumio[912]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 09 17:01:03 volumio3a volumio[912]: info: Streaming services startup Feb 09 17:01:03 volumio3a volumio[912]: info: Starting Streaming Daemon Feb 09 17:01:03 volumio3a sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 09 17:01:03 volumio3a sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 17:01:03 volumio3a sudo[1279]: pam_unix(sudo:session): session closed for user root Feb 09 17:01:03 volumio3a volumio[912]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 09 17:01:03 volumio3a volumio[912]: info: Feb 09 17:01:03 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:03 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:03 volumio3a volumio[912]: info: Feb 09 17:01:03 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:03 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:03 volumio3a volumio[912]: info: Feb 09 17:01:03 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:03 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:03 volumio3a volumio[912]: error: Cannot start Volumio Streaming Daemon Feb 09 17:01:03 volumio3a volumio[912]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 09 17:01:03 volumio3a volumio[912]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 09 17:01:03 volumio3a volumio[912]: info: Volumio called home Feb 09 17:01:03 volumio3a volumio[912]: info: Feb 09 17:01:03 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:03 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:03 volumio3a volumio[912]: info: Feb 09 17:01:03 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:03 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:03 volumio3a volumio[912]: info: sendMpdCommand status took 40 milliseconds Feb 09 17:01:03 volumio3a volumio[912]: info: sendMpdCommand status took 44 milliseconds Feb 09 17:01:03 volumio3a volumio[912]: info: sendMpdCommand status took 42 milliseconds Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:03 volumio3a volumio[912]: info: Feb 09 17:01:03 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:03 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:03 volumio3a volumio[912]: info: Feb 09 17:01:03 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:03 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:03 volumio3a volumio[912]: info: Feb 09 17:01:03 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:03 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:03 volumio3a volumio[912]: info: Feb 09 17:01:03 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:03 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:03 volumio3a volumio[912]: Upnp client error: Error: This socket has been ended by the other party Feb 09 17:01:03 volumio3a volumio[912]: info: ------------------------------ 38ms Feb 09 17:01:03 volumio3a volumio[912]: info: sendMpdCommand status took 34 milliseconds Feb 09 17:01:03 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 09 17:01:03 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 09 17:01:03 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 09 17:01:03 volumio3a volumio[912]: info: ------------------------------ 19ms Feb 09 17:01:03 volumio3a volumio[912]: info: sendMpdCommand status took 17 milliseconds Feb 09 17:01:03 volumio3a volumio[912]: info: ------------------------------ 15ms Feb 09 17:01:03 volumio3a volumio[912]: info: sendMpdCommand status took 12 milliseconds Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:03 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:03 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:03 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3611,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Youth Gone Wild - Skid Row","artist":null,"album":null,"uri":"https://live-bauerdk.sharp-stream.com/myrock_dk_mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=myr","trackType":""} Feb 09 17:01:03 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus stop Feb 09 17:01:03 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:03 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:03 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3611,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Youth Gone Wild - Skid Row","artist":null,"album":null,"uri":"https://live-bauerdk.sharp-stream.com/myrock_dk_mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=myr","trackType":""} Feb 09 17:01:03 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:03 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:03 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:03 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:03 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3611,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Youth Gone Wild - Skid Row","artist":null,"album":null,"uri":"https://live-bauerdk.sharp-stream.com/myrock_dk_mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=myr","trackType":""} Feb 09 17:01:03 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:03 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:03 volumio3a volumio[912]: info: ------------------------------ 122ms Feb 09 17:01:03 volumio3a volumio[912]: info: ------------------------------ 146ms Feb 09 17:01:03 volumio3a volumio[912]: info: ------------------------------ 145ms Feb 09 17:01:03 volumio3a volumio[912]: Upnp client error: Error: This socket has been ended by the other party Feb 09 17:01:03 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 84 milliseconds Feb 09 17:01:03 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 80 milliseconds Feb 09 17:01:03 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 81 milliseconds Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:03 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:03 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:03 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:03 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3611,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Youth Gone Wild - Skid Row","artist":null,"album":null,"uri":"https://live-bauerdk.sharp-stream.com/myrock_dk_mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=myr","trackType":""} Feb 09 17:01:03 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:03 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:03 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:03 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:03 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3611,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Youth Gone Wild - Skid Row","artist":null,"album":null,"uri":"https://live-bauerdk.sharp-stream.com/myrock_dk_mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=myr","trackType":""} Feb 09 17:01:03 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:03 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:03 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:03 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:03 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3737,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Youth Gone Wild - Skid Row","artist":null,"album":null,"uri":"https://live-bauerdk.sharp-stream.com/myrock_dk_mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=myr","trackType":""} Feb 09 17:01:03 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:03 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:03 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:03 volumio3a volumio[912]: info: ------------------------------ 194ms Feb 09 17:01:03 volumio3a volumio[912]: info: ------------------------------ 173ms Feb 09 17:01:03 volumio3a volumio[912]: info: ------------------------------ 170ms Feb 09 17:01:04 volumio3a volumio[912]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 09 17:01:04 volumio3a volumio[912]: info: MyVolumio token set successfully Feb 09 17:01:04 volumio3a volumio[912]: info: MYVOLUMIO: Adding device Feb 09 17:01:04 volumio3a volumio[912]: info: MYVOLUMIO: Evaluating Server Feb 09 17:01:05 volumio3a volumio[912]: STREAMING PROXY: Starting server on port 3245 Feb 09 17:01:05 volumio3a volumio[912]: Node JS runtime: 14 Feb 09 17:01:05 volumio3a volumio[912]: info: MyVolumio status changed Feb 09 17:01:05 volumio3a volumio[912]: info: Streaming services startup Feb 09 17:01:05 volumio3a volumio[912]: info: Starting Streaming Daemon Feb 09 17:01:05 volumio3a volumio[912]: info: Removing browser output: myVolumio user plan is not superstar Feb 09 17:01:05 volumio3a volumio[912]: info: Removing audio output: Feb 09 17:01:05 volumio3a volumio[912]: info: Stoppping Tunnel 1 Feb 09 17:01:05 volumio3a sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 09 17:01:05 volumio3a sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 17:01:05 volumio3a sudo[1315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 09 17:01:05 volumio3a sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 17:01:05 volumio3a sudo[1313]: pam_unix(sudo:session): session closed for user root Feb 09 17:01:05 volumio3a volumio[912]: error: Cannot start Volumio Streaming Daemon Feb 09 17:01:05 volumio3a volumio[912]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 09 17:01:05 volumio3a volumio[912]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 09 17:01:05 volumio3a sudo[1315]: pam_unix(sudo:session): session closed for user root Feb 09 17:01:05 volumio3a volumio[912]: info: Remote SSH Stopped Feb 09 17:01:05 volumio3a volumio[912]: info: Setting Geolocation for MyVolumio to eu5 Feb 09 17:01:05 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 17:01:06 volumio3a volumio[912]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 09 17:01:06 volumio3a volumio[912]: info: Updating MyVolumio device info Feb 09 17:01:06 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 17:01:07 volumio3a volumio[1224]: .........................................................................................................................++++ Feb 09 17:01:07 volumio3a volumio[912]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 09 17:01:07 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 09 17:01:07 volumio3a volumio[912]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 09 17:01:07 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 09 17:01:07 volumio3a volumio[912]: info: Received Get System Version Feb 09 17:01:07 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 09 17:01:07 volumio3a volumio[912]: info: Received Get System Info Feb 09 17:01:07 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 17:01:07 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 17:01:07 volumio3a volumio[912]: info: Discovery: Getting this device information Feb 09 17:01:07 volumio3a volumio[912]: info: CoreCommandRouter::volumioGetState Feb 09 17:01:07 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 17:01:07 volumio3a volumio[1224]: .....++++ Feb 09 17:01:07 volumio3a volumio[1224]: e is 65537 (0x010001) Feb 09 17:01:07 volumio3a volumio[1224]: writing RSA key Feb 09 17:01:09 volumio3a systemd[1]: systemd-timedated.service: Succeeded. Feb 09 17:01:10 volumio3a volumio[912]: info: MYVOLUMIO: Adding device Feb 09 17:01:10 volumio3a volumio[912]: info: MYVOLUMIO: Evaluating Server Feb 09 17:01:11 volumio3a volumio[912]: info: Setting Geolocation for MyVolumio to eu9 Feb 09 17:01:11 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 17:01:11 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 17:01:11 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 17:01:11 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 17:01:11 volumio3a volumio[912]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 09 17:01:12 volumio3a volumio[912]: info: Updating MyVolumio device info Feb 09 17:01:12 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 17:01:12 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 17:01:12 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 17:01:12 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 17:01:12 volumio3a volumio[912]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 09 17:01:13 volumio3a volumio[912]: info: CoreCommandRouter::volumioGetVisibleSources Feb 09 17:01:13 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 17:01:13 volumio3a volumio[912]: info: CoreCommandRouter::volumioGetQueue Feb 09 17:01:13 volumio3a volumio[912]: info: CoreStateMachine::getQueue Feb 09 17:01:13 volumio3a volumio[912]: info: CorePlayQueue::getQueue Feb 09 17:01:13 volumio3a volumio[912]: info: Listing playlists Feb 09 17:01:15 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 09 17:01:15 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 09 17:01:15 volumio3a volumio[912]: info: Preload queue cleared Feb 09 17:01:16 volumio3a volumio[912]: verbose: New Socket.io Connection to 192.168.1.179:3000 from 192.168.1.193 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 09 17:01:16 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 09 17:01:16 volumio3a volumio[912]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 09 17:01:16 volumio3a volumio[912]: info: CoreCommandRouter::volumioGetState Feb 09 17:01:16 volumio3a volumio[912]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Feb 09 17:01:16 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 17:01:16 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 09 17:01:16 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 17:01:16 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 09 17:01:17 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 09 17:01:17 volumio3a volumio-remote-updater[524]: No test mode Feb 09 17:01:17 volumio3a volumio-remote-updater[524]: No alpha test mode Feb 09 17:01:17 volumio3a volumio[912]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES





NEW ADDITIONS



 

 

","title":"Update v3.886","updateavailable":true} Feb 09 17:01:17 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 09 17:01:19 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 09 17:01:19 volumio3a volumio[912]: info: Preload queue cleared Feb 09 17:01:21 volumio3a volumio[912]: info: Preload queue cleared Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::ClearQueue Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::stop Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::stPlaybackTimer Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::updateTrackBlock Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrackBlock Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::serviceStop Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::serviceStop Feb 09 17:01:21 volumio3a volumio[912]: info: [1770652881375] ControllerWebradio::stop Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::clearPlayQueue Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::saveQueue Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushQueue Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::addQueueItems Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::addQueueItems Feb 09 17:01:21 volumio3a volumio[912]: info: Preload queue cleared Feb 09 17:01:21 volumio3a volumio[912]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s299628 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 09 17:01:21 volumio3a volumio[912]: info: Feb 09 17:01:21 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:21 volumio3a volumio[912]: info: sendMpdCommand stop took 39 milliseconds Feb 09 17:01:21 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:21 volumio3a volumio[912]: info: Feb 09 17:01:21 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:21 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:21 volumio3a volumio[912]: info: Feb 09 17:01:21 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:21 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:21 volumio3a volumio[912]: info: sendMpdCommand status took 6 milliseconds Feb 09 17:01:21 volumio3a volumio[912]: info: sendMpdCommand status took 3 milliseconds Feb 09 17:01:21 volumio3a volumio[912]: info: sendMpdCommand status took 2 milliseconds Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:21 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 09 17:01:21 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 09 17:01:21 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:21 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: 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":"Youth Gone Wild - Skid Row","artist":null,"album":null,"uri":"https://live-bauerdk.sharp-stream.com/myrock_dk_mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=myr","trackType":""} Feb 09 17:01:21 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService stop Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus stop Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:21 volumio3a volumio[912]: info: No code Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:21 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: 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":"Youth Gone Wild - Skid Row","artist":null,"album":null,"uri":"https://live-bauerdk.sharp-stream.com/myrock_dk_mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=myr","trackType":""} Feb 09 17:01:21 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService stop Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus stop Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:21 volumio3a volumio[912]: info: No code Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:21 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: 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":"Youth Gone Wild - Skid Row","artist":null,"album":null,"uri":"https://live-bauerdk.sharp-stream.com/myrock_dk_mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=myr","trackType":""} Feb 09 17:01:21 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService stop Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus stop Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:21 volumio3a volumio[912]: info: No code Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:21 volumio3a volumio[912]: info: ------------------------------ 111ms Feb 09 17:01:21 volumio3a volumio[912]: info: ------------------------------ 105ms Feb 09 17:01:21 volumio3a volumio[912]: info: ------------------------------ 104ms Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioGetBrowseSources Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushQueue Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::saveQueue Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::updateTrackBlock Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrackBlock Feb 09 17:01:21 volumio3a volumio[912]: info: CoreCommandRouter::volumioPlay Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::play index 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::stop Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::play index undefined Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: info: CoreStateMachine::startPlaybackTimer Feb 09 17:01:21 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:21 volumio3a volumio[912]: info: [1770652881827] ControllerWebradio::clearAddPlayTrack Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 17:01:21 volumio3a volumio[912]: info: sendMpdCommand stop took 3 milliseconds Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 17:01:21 volumio3a volumio[912]: info: Feb 09 17:01:21 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:21 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:21 volumio3a volumio[912]: info: sendMpdCommand clear took 4 milliseconds Feb 09 17:01:21 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand load "http://stream.vinylhits.dk/vinylmp3" Feb 09 17:01:21 volumio3a volumio[912]: info: Feb 09 17:01:21 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:21 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:21 volumio3a volumio[912]: info: Feb 09 17:01:21 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:21 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:22 volumio3a volumio[912]: error: updateQueue error: null Feb 09 17:01:22 volumio3a volumio[912]: info: ------------------------------ 1107ms Feb 09 17:01:22 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand add "http://stream.vinylhits.dk/vinylmp3" Feb 09 17:01:22 volumio3a volumio[912]: error: updateQueue error: null Feb 09 17:01:22 volumio3a volumio[912]: error: updateQueue error: null Feb 09 17:01:22 volumio3a volumio[912]: info: Feb 09 17:01:22 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:22 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:22 volumio3a volumio[912]: info: ------------------------------ 1107ms Feb 09 17:01:22 volumio3a volumio[912]: info: ------------------------------ 1105ms Feb 09 17:01:22 volumio3a volumio[912]: info: sendMpdCommand add "http://stream.vinylhits.dk/vinylmp3" took 2 milliseconds Feb 09 17:01:22 volumio3a volumio[912]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 17:01:22 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand play Feb 09 17:01:22 volumio3a volumio[912]: info: Feb 09 17:01:22 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:22 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:22 volumio3a volumio[912]: info: Feb 09 17:01:22 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:22 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:22 volumio3a volumio[912]: info: ------------------------------ 7ms Feb 09 17:01:22 volumio3a volumio[912]: info: sendMpdCommand play took 5 milliseconds Feb 09 17:01:22 volumio3a volumio[912]: info: ------------------------------ 5ms Feb 09 17:01:22 volumio3a volumio[912]: info: ------------------------------ 3ms Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:23 volumio3a kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand status took 12 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand status took 12 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand status took 11 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand status took 3 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: 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":"vinylmp3","artist":null,"album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:23 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus stop Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 26ms Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand status took 4 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand status took 3 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: 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":"vinylmp3","artist":null,"album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:23 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:23 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: 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":"vinylmp3","artist":null,"album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:23 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:23 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: 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":"vinylmp3","artist":null,"album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:23 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:23 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 96ms Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 95ms Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 88ms Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 72 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 72 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: 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":"vinylmp3","artist":null,"album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:23 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:23 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: 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":"vinylmp3","artist":null,"album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:23 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:23 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 132ms Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 131ms Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:23 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:23 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:23 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:23 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 12ms Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand status took 11 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 10ms Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand status took 9 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 8ms Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand status took 6 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:23 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:23 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:23 volumio3a volumio[912]: info: Feb 09 17:01:23 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 17ms Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand status took 14 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 9ms Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand status took 8 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 7ms Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand status took 5 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"vinylmp3","artist":"vinylmp3","album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:23 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:23 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"vinylmp3","artist":"vinylmp3","album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:23 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:23 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"vinylmp3","artist":"vinylmp3","album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:23 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:23 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 108ms Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 106ms Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 104ms Feb 09 17:01:23 volumio3a volumio[912]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 09 17:01:23 volumio3a volumio[912]: info: Completed starting MyVolumio Plugin Feb 09 17:01:23 volumio3a volumio[912]: [Metrics] CommandRouter: 44s 673.65ms Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumiosetStartupVolume Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::Close All Modals sent Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::Close All Modals sent Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 118 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 116 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 117 milliseconds Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:23 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"vinylmp3","artist":"vinylmp3","album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:23 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:23 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"vinylmp3","artist":"vinylmp3","album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:23 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:23 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"vinylmp3","artist":"vinylmp3","album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:23 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:23 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:23 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 209ms Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 200ms Feb 09 17:01:23 volumio3a volumio[912]: info: ------------------------------ 198ms Feb 09 17:01:23 volumio3a volumio[912]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 09 17:01:23 volumio3a volumio[912]: aplay: main:828: audio open error: Device or resource busy Feb 09 17:01:24 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 09 17:01:24 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 09 17:01:24 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 09 17:01:26 volumio3a ntpd[727]: Soliciting pool server 192.36.143.130 Feb 09 17:01:26 volumio3a volumio[912]: info: Preload queue cleared Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::ClearQueue Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::stop Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::stPlaybackTimer Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::updateTrackBlock Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrackBlock Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::serviceStop Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::serviceStop Feb 09 17:01:26 volumio3a volumio[912]: info: [1770652886497] ControllerWebradio::stop Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::clearPlayQueue Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::saveQueue Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushQueue Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::addQueueItems Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::addQueueItems Feb 09 17:01:26 volumio3a volumio[912]: info: Preload queue cleared Feb 09 17:01:26 volumio3a volumio[912]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s224662 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 09 17:01:26 volumio3a volumio[912]: info: Feb 09 17:01:26 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:26 volumio3a volumio[912]: info: sendMpdCommand stop took 32 milliseconds Feb 09 17:01:26 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:26 volumio3a volumio[912]: info: Feb 09 17:01:26 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:26 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:26 volumio3a volumio[912]: info: Feb 09 17:01:26 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:26 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:26 volumio3a volumio[912]: info: sendMpdCommand status took 5 milliseconds Feb 09 17:01:26 volumio3a volumio[912]: info: sendMpdCommand status took 3 milliseconds Feb 09 17:01:26 volumio3a volumio[912]: info: sendMpdCommand status took 2 milliseconds Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:26 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 09 17:01:26 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 09 17:01:26 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:26 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: 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":"vinylmp3","artist":"vinylmp3","album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:26 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService stop Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus stop Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:26 volumio3a volumio[912]: info: No code Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:26 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: 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":"vinylmp3","artist":"vinylmp3","album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:26 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService stop Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus stop Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:26 volumio3a volumio[912]: info: No code Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:26 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: 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":"vinylmp3","artist":"vinylmp3","album":null,"uri":"http://stream.vinylhits.dk/vinylmp3","trackType":""} Feb 09 17:01:26 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService stop Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus stop Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:26 volumio3a volumio[912]: info: No code Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:26 volumio3a volumio[912]: info: ------------------------------ 102ms Feb 09 17:01:26 volumio3a volumio[912]: info: ------------------------------ 100ms Feb 09 17:01:26 volumio3a volumio[912]: info: ------------------------------ 99ms Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushQueue Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::saveQueue Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::updateTrackBlock Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrackBlock Feb 09 17:01:26 volumio3a volumio[912]: info: CoreCommandRouter::volumioPlay Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::play index 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::stop Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::play index undefined Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: info: CoreStateMachine::startPlaybackTimer Feb 09 17:01:26 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:26 volumio3a volumio[912]: info: [1770652886926] ControllerWebradio::clearAddPlayTrack Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 17:01:26 volumio3a volumio[912]: info: sendMpdCommand stop took 3 milliseconds Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 17:01:26 volumio3a volumio[912]: info: Feb 09 17:01:26 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:26 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:26 volumio3a volumio[912]: info: sendMpdCommand clear took 2 milliseconds Feb 09 17:01:26 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand load "http://netradio.classicfm.dk/classicrock" Feb 09 17:01:26 volumio3a volumio[912]: info: Feb 09 17:01:26 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:26 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:26 volumio3a volumio[912]: info: Feb 09 17:01:26 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:26 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:26 volumio3a volumio[912]: error: updateQueue error: null Feb 09 17:01:26 volumio3a volumio[912]: info: ------------------------------ 5ms Feb 09 17:01:27 volumio3a ntpd[727]: Soliciting pool server 77.37.97.124 Feb 09 17:01:27 volumio3a ntpd[727]: Soliciting pool server 193.200.91.90 Feb 09 17:01:27 volumio3a ntpd[727]: Soliciting pool server 217.198.219.101 Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand add "http://netradio.classicfm.dk/classicrock" Feb 09 17:01:27 volumio3a volumio[912]: error: updateQueue error: null Feb 09 17:01:27 volumio3a volumio[912]: error: updateQueue error: null Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:27 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 493ms Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 491ms Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand add "http://netradio.classicfm.dk/classicrock" took 3 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand play Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:27 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:27 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 7ms Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand play took 4 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 5ms Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 3ms Feb 09 17:01:27 volumio3a ntpd[727]: Soliciting pool server 213.5.39.34 Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:27 volumio3a kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand status took 12 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand status took 12 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand status took 10 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand status took 4 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:27 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:27 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"classicrock","artist":null,"album":null,"uri":"http://netradio.classicfm.dk/classicrock","trackType":""} Feb 09 17:01:27 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus stop Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 24ms Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:27 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:27 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand status took 10 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand status took 11 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:27 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:27 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"classicrock","artist":null,"album":null,"uri":"http://netradio.classicfm.dk/classicrock","trackType":""} Feb 09 17:01:27 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:27 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:27 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:27 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"classicrock","artist":null,"album":null,"uri":"http://netradio.classicfm.dk/classicrock","trackType":""} Feb 09 17:01:27 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:27 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:27 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:27 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"classicrock","artist":null,"album":null,"uri":"http://netradio.classicfm.dk/classicrock","trackType":""} Feb 09 17:01:27 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:27 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 112ms Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 110ms Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 104ms Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:27 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces system playlist update Feb 09 17:01:27 volumio3a volumio[912]: info: Ignoring MPD Status Update Feb 09 17:01:27 volumio3a volumio[912]: info: Feb 09 17:01:27 volumio3a volumio[912]: ---------------------------- MPD announces state update: player Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::getState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand status Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 94ms Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand status took 87 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 94ms Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand status took 87 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 83 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 83 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 9ms Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand status took 7 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 7ms Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand status took 6 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseState Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:27 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:27 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Green Day - Boulevard of Broken Dreams","artist":"Classic Rock","album":null,"uri":"http://netradio.classicfm.dk/classicrock","trackType":""} Feb 09 17:01:27 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:27 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:27 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:27 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Green Day - Boulevard of Broken Dreams","artist":"Classic Rock","album":null,"uri":"http://netradio.classicfm.dk/classicrock","trackType":""} Feb 09 17:01:27 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:27 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 150ms Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 148ms Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 61 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 62 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 61 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: info: sendMpdCommand playlistinfo took 60 milliseconds Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:27 volumio3a volumio[912]: verbose: ControllerMpd::parseTrackInfo Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:27 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:27 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Green Day - Boulevard of Broken Dreams","artist":"Classic Rock","album":null,"uri":"http://netradio.classicfm.dk/classicrock","trackType":""} Feb 09 17:01:27 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:27 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:27 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:27 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Green Day - Boulevard of Broken Dreams","artist":"Classic Rock","album":null,"uri":"http://netradio.classicfm.dk/classicrock","trackType":""} Feb 09 17:01:27 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:27 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:27 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:27 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Green Day - Boulevard of Broken Dreams","artist":"Classic Rock","album":null,"uri":"http://netradio.classicfm.dk/classicrock","trackType":""} Feb 09 17:01:27 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:27 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: ControllerMpd::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::servicePushState Feb 09 17:01:27 volumio3a volumio[912]: info: CorePlayQueue::getTrack 0 Feb 09 17:01:27 volumio3a volumio[912]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Green Day - Boulevard of Broken Dreams","artist":"Classic Rock","album":null,"uri":"http://netradio.classicfm.dk/classicrock","trackType":""} Feb 09 17:01:27 volumio3a volumio[912]: verbose: CURRENT POSITION 0 Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState stateService play Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::syncState currentStatus play Feb 09 17:01:27 volumio3a volumio[912]: info: Received an update from plugin. extracting info from payload Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreStateMachine::pushState Feb 09 17:01:27 volumio3a volumio[912]: info: CoreCommandRouter::volumioPushState Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 234ms Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 233ms Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 149ms Feb 09 17:01:27 volumio3a volumio[912]: info: ------------------------------ 148ms Feb 09 17:01:28 volumio3a ntpd[727]: Soliciting pool server 212.99.225.86 Feb 09 17:01:28 volumio3a ntpd[727]: Soliciting pool server 162.159.200.123 Feb 09 17:01:28 volumio3a ntpd[727]: Soliciting pool server 162.159.200.1 Feb 09 17:01:29 volumio3a ntpd[727]: Soliciting pool server 86.52.112.177 Feb 09 17:01:29 volumio3a ntpd[727]: Soliciting pool server 185.181.223.169 Feb 09 17:01:29 volumio3a ntpd[727]: Soliciting pool server 80.209.68.118 Feb 09 17:01:30 volumio3a ntpd[727]: Soliciting pool server 87.63.200.138 Feb 09 17:01:30 volumio3a ntpd[727]: Soliciting pool server 5.186.224.242 Feb 09 17:01:30 volumio3a ntpd[727]: Soliciting pool server 86.52.51.137 Feb 09 17:01:30 volumio3a volumio[912]: info: BOOT COMPLETED Feb 09 17:01:31 volumio3a ntpd[727]: Soliciting pool server 2a06:4001:f047:f:: Feb 09 17:01:31 volumio3a ntpd[727]: Soliciting pool server 86.52.118.195 Feb 09 17:02:15 volumio3a volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 09 17:02:15 volumio3a volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 09 17:02:15 volumio3a volumio[912]: info: Retrieving Cloud Streaming UI Feb 09 17:02:15 volumio3a volumio[912]: info: Getting Tidal Cloud Configuration Feb 09 17:02:15 volumio3a volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 09 17:02:15 volumio3a volumio[912]: info: Getting Qobuz Cloud Configuration Feb 09 17:02:15 volumio3a volumio[912]: info: Asking plugin for UI Config Feb 09 17:02:15 volumio3a volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 09 17:02:15 volumio3a volumio[912]: info: Getting Spotify Cloud Configuration Feb 09 17:02:15 volumio3a volumio[912]: info: Asking plugin for UI Config Feb 09 17:02:15 volumio3a volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 09 17:02:15 volumio3a volumio[912]: info: Saving Spotify Acccount Feb 09 17:02:15 volumio3a volumio[912]: info: Got Tidal Cloud Configuration Feb 09 17:02:15 volumio3a volumio[912]: info: Got it Feb 09 17:02:15 volumio3a volumio[912]: info: Got it Feb 09 17:02:15 volumio3a volumio[912]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 09 17:02:15 volumio3a volumio[912]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 09 17:02:15 volumio3a volumio[912]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Feb 09 17:02:15 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 09 17:02:15 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 09 17:02:15 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 17:02:15 volumio3a volumio[912]: info: CoreCommandRouter::volumioGetBrowseSources Feb 09 17:02:15 volumio3a volumio[912]: info: CoreCommandRouter::volumioGetBrowseSources Feb 09 17:02:15 volumio3a volumio[912]: info: CoreCommandRouter::volumioGetBrowseSources Feb 09 17:02:15 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 09 17:02:15 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 09 17:02:19 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 09 17:02:23 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 09 17:02:24 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 09 17:02:24 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 09 17:02:27 volumio3a volumio[912]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 09 17:02:29 volumio3a volumio[912]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 17:02:29 volumio3a volumio[912]: Error: dns service error: unknown Feb 09 17:02:29 volumio3a volumio[912]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Feb 09 17:02:29 volumio3a volumio[912]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Feb 09 17:02:29 volumio3a volumio[912]: errorCode: -65537 Feb 09 17:02:29 volumio3a volumio[912]: } Feb 09 17:02:29 volumio3a volumio[912]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 17:02:30 volumio3a sudo[1765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-09 17:01 Feb 09 17:02:30 volumio3a sudo[1765]: 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"