Dec 09 16:02:04 volumio volumio[1275]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 09 16:02:04 volumio volumio[1275]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 09 16:02:04 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:04 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:04 volumio volumio[1275]: info: Starting MyVolumio Remote Streaming Endpoints Dec 09 16:02:04 volumio volumio[1275]: info: MyVolumio login type: Token Dec 09 16:02:04 volumio volumio[1275]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 09 16:02:04 volumio volumio[1275]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 09 16:02:13 volumio volumio[1275]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 09 16:02:13 volumio volumio[1275]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 09 16:02:13 volumio volumio[1275]: info: Streaming services startup Dec 09 16:02:13 volumio volumio[1275]: info: Starting Streaming Daemon Dec 09 16:02:14 volumio volumio[1275]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 09 16:02:14 volumio sudo[1731]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 09 16:02:14 volumio sudo[1731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:14 volumio sudo[1731]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:14 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 09 16:02:14 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:14 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 09 16:02:14 volumio volumio[1275]: info: AutoStart - Plugin is starting Dec 09 16:02:14 volumio volumio[1275]: info: CoreCommandRouter::volumioGetQueue Dec 09 16:02:14 volumio volumio[1275]: info: CoreStateMachine::getQueue Dec 09 16:02:14 volumio volumio[1275]: info: CorePlayQueue::getQueue Dec 09 16:02:14 volumio volumio[1275]: info: AutoStart - start playing Dec 09 16:02:14 volumio volumio[1275]: info: AutoStart - start playing with no specific position Dec 09 16:02:14 volumio volumio[1275]: info: CoreCommandRouter::volumioPlay Dec 09 16:02:14 volumio volumio[1275]: info: CoreStateMachine::play index 0 Dec 09 16:02:14 volumio volumio[1275]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 16:02:14 volumio volumio[1275]: info: CoreStateMachine::stop Dec 09 16:02:14 volumio volumio[1275]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 16:02:14 volumio volumio[1275]: info: CoreStateMachine::play index undefined Dec 09 16:02:14 volumio volumio[1275]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 16:02:14 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:14 volumio volumio[1275]: info: CoreStateMachine::startPlaybackTimer Dec 09 16:02:14 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:14 volumio volumio[1275]: info: [1765306934787] ControllerWebradio::clearAddPlayTrack Dec 09 16:02:14 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand stop Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 16:02:15 volumio volumio[1275]: info: Received Get System Info Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 16:02:15 volumio volumio[1275]: info: Discovery: Getting this device information Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 09 16:02:15 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 09 16:02:15 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 16:02:15 volumio volumio[1275]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 09 16:02:15 volumio volumio[1275]: info: Received Get System Version Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 09 16:02:15 volumio volumio[1275]: info: Received Get System Info Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 16:02:15 volumio volumio[1275]: info: Discovery: Getting this device information Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 09 16:02:15 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 16:02:15 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 09 16:02:15 volumio volumio[1275]: info: sendMpdCommand stop took 603 milliseconds Dec 09 16:02:15 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand clear Dec 09 16:02:15 volumio volumio[1275]: error: Cannot start Volumio Streaming Daemon Dec 09 16:02:15 volumio volumio[1275]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 09 16:02:15 volumio volumio[1275]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 09 16:02:15 volumio volumio[1275]: info: Preload queue cleared Dec 09 16:02:15 volumio volumio[1275]: info: Dec 09 16:02:15 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:15 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:15 volumio volumio[1275]: info: sendMpdCommand clear took 337 milliseconds Dec 09 16:02:15 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand load "http://stream.zeno.fm/8d992a5zrf9uv" Dec 09 16:02:15 volumio volumio[1275]: info: Dec 09 16:02:15 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:15 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:15 volumio volumio[1275]: info: Dec 09 16:02:15 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:15 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:16 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 09 16:02:16 volumio volumio[1275]: info: MyVolumio login type: Token Dec 09 16:02:16 volumio volumio[1275]: error: MyVolumio Custom Token format not valid, refreshing it Dec 09 16:02:16 volumio volumio[1275]: error: updateQueue error: null Dec 09 16:02:16 volumio volumio[1275]: error: updateQueue error: null Dec 09 16:02:16 volumio volumio[1275]: error: updateQueue error: null Dec 09 16:02:16 volumio volumio[1275]: info: ------------------------------ 1027ms Dec 09 16:02:16 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand add "http://stream.zeno.fm/8d992a5zrf9uv" Dec 09 16:02:16 volumio volumio[1275]: info: ------------------------------ 1018ms Dec 09 16:02:16 volumio volumio[1275]: info: ------------------------------ 1023ms Dec 09 16:02:16 volumio volumio[1275]: info: Dec 09 16:02:16 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:16 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:16 volumio volumio[1275]: info: sendMpdCommand add "http://stream.zeno.fm/8d992a5zrf9uv" took 199 milliseconds Dec 09 16:02:16 volumio volumio[1275]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 09 16:02:17 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand play Dec 09 16:02:17 volumio volumio[1275]: info: Dec 09 16:02:17 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:17 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:17 volumio volumio[1275]: info: Dec 09 16:02:17 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:17 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:17 volumio volumio[1275]: info: ------------------------------ 127ms Dec 09 16:02:17 volumio volumio[1275]: info: sendMpdCommand play took 205 milliseconds Dec 09 16:02:17 volumio volumio[1275]: info: ------------------------------ 203ms Dec 09 16:02:17 volumio volumio[1275]: info: ------------------------------ 147ms Dec 09 16:02:17 volumio volumio[1275]: info: Dec 09 16:02:17 volumio volumio[1275]: ---------------------------- MPD announces state update: player Dec 09 16:02:17 volumio volumio[1275]: info: ControllerMpd::getState Dec 09 16:02:17 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status Dec 09 16:02:17 volumio volumio[1275]: info: Dec 09 16:02:17 volumio volumio[1275]: ---------------------------- MPD announces state update: player Dec 09 16:02:17 volumio volumio[1275]: info: ControllerMpd::getState Dec 09 16:02:17 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status Dec 09 16:02:17 volumio volumio[1275]: info: Dec 09 16:02:17 volumio volumio[1275]: ---------------------------- MPD announces state update: player Dec 09 16:02:17 volumio volumio[1275]: info: Dec 09 16:02:17 volumio volumio[1275]: ---------------------------- MPD announces state update: player Dec 09 16:02:17 volumio volumio[1275]: info: ControllerMpd::getState Dec 09 16:02:17 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 09 16:02:18 volumio volumio[1275]: info: sendMpdCommand status took 114 milliseconds Dec 09 16:02:18 volumio volumio[1275]: info: ControllerMpd::getState Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 09 16:02:18 volumio volumio[1275]: info: sendMpdCommand status took 86 milliseconds Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::parseState Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::parseState Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 16:02:18 volumio volumio[1275]: info: Dec 09 16:02:18 volumio volumio[1275]: ---------------------------- MPD announces state update: player Dec 09 16:02:18 volumio volumio[1275]: info: ControllerMpd::getState Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status Dec 09 16:02:18 volumio volumio[1275]: info: Dec 09 16:02:18 volumio volumio[1275]: ---------------------------- MPD announces state update: player Dec 09 16:02:18 volumio volumio[1275]: info: ControllerMpd::getState Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 09 16:02:18 volumio volumio[1275]: info: sendMpdCommand status took 792 milliseconds Dec 09 16:02:18 volumio volumio[1275]: info: sendMpdCommand clearerror took 748 milliseconds Dec 09 16:02:18 volumio volumio[1275]: info: sendMpdCommand status took 747 milliseconds Dec 09 16:02:18 volumio volumio[1275]: info: sendMpdCommand clearerror took 746 milliseconds Dec 09 16:02:18 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 652 milliseconds Dec 09 16:02:18 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 616 milliseconds Dec 09 16:02:18 volumio volumio[1275]: info: sendMpdCommand status took 75 milliseconds Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::parseState Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::parseState Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::parseState Dec 09 16:02:18 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 16:02:18 volumio volumio[1275]: info: ControllerMpd::pushState Dec 09 16:02:18 volumio volumio[1275]: info: CoreCommandRouter::servicePushState Dec 09 16:02:18 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:18 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 16:02:18 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:18 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:18 volumio volumio[1275]: 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":"8d992a5zrf9uv","artist":null,"album":null,"uri":"http://stream.zeno.fm/8d992a5zrf9uv","trackType":""} Dec 09 16:02:18 volumio volumio[1275]: verbose: CURRENT POSITION 0 Dec 09 16:02:18 volumio volumio[1275]: info: CoreStateMachine::syncState stateService stop Dec 09 16:02:18 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus stop Dec 09 16:02:18 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:18 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:18 volumio volumio[1275]: info: No code Dec 09 16:02:18 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:18 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: ControllerMpd::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::servicePushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:19 volumio volumio[1275]: 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":"8d992a5zrf9uv","artist":null,"album":null,"uri":"http://stream.zeno.fm/8d992a5zrf9uv","trackType":""} Dec 09 16:02:19 volumio volumio[1275]: verbose: CURRENT POSITION 0 Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::syncState stateService stop Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus stop Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: No code Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: ------------------------------ 1513ms Dec 09 16:02:19 volumio volumio[1275]: info: ------------------------------ 1444ms Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 16:02:19 volumio volumio[1275]: info: sendMpdCommand status took 840 milliseconds Dec 09 16:02:19 volumio volumio[1275]: info: sendMpdCommand clearerror took 787 milliseconds Dec 09 16:02:19 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 740 milliseconds Dec 09 16:02:19 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 739 milliseconds Dec 09 16:02:19 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 736 milliseconds Dec 09 16:02:19 volumio volumio[1275]: verbose: ControllerMpd::parseState Dec 09 16:02:19 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 16:02:19 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo Dec 09 16:02:19 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo Dec 09 16:02:19 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo Dec 09 16:02:19 volumio volumio[1275]: info: ControllerMpd::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::servicePushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:19 volumio volumio[1275]: 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":"8d992a5zrf9uv","artist":null,"album":null,"uri":"http://stream.zeno.fm/8d992a5zrf9uv","trackType":""} Dec 09 16:02:19 volumio volumio[1275]: verbose: CURRENT POSITION 0 Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::syncState stateService stop Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus stop Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: No code Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: ControllerMpd::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::servicePushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:19 volumio volumio[1275]: 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":"8d992a5zrf9uv","artist":null,"album":null,"uri":"http://stream.zeno.fm/8d992a5zrf9uv","trackType":""} Dec 09 16:02:19 volumio volumio[1275]: verbose: CURRENT POSITION 0 Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::syncState stateService stop Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus stop Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: No code Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: ControllerMpd::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::servicePushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:19 volumio volumio[1275]: 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":"8d992a5zrf9uv","artist":null,"album":null,"uri":"http://stream.zeno.fm/8d992a5zrf9uv","trackType":""} Dec 09 16:02:19 volumio volumio[1275]: verbose: CURRENT POSITION 0 Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::syncState stateService stop Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus stop Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: No code Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: ------------------------------ 1689ms Dec 09 16:02:19 volumio volumio[1275]: info: ------------------------------ 1689ms Dec 09 16:02:19 volumio volumio[1275]: info: ------------------------------ 946ms Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 16:02:19 volumio volumio[1275]: info: Received Get System Info Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 16:02:19 volumio volumio[1275]: info: Discovery: Getting this device information Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 16:02:19 volumio volumio[1275]: info: Received Get System Info Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 16:02:19 volumio volumio[1275]: info: Discovery: Getting this device information Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 16:02:19 volumio volumio-remote-updater[723]: Test mode disabled Dec 09 16:02:19 volumio volumio-remote-updater[723]: Alpha mode disabled Dec 09 16:02:19 volumio volumio-remote-updater[723]: Alpha legacy test mode disabled Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 09 16:02:19 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 179 milliseconds Dec 09 16:02:19 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo Dec 09 16:02:19 volumio volumio[1275]: info: ControllerMpd::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::servicePushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:19 volumio volumio[1275]: 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":"8d992a5zrf9uv","artist":null,"album":null,"uri":"http://stream.zeno.fm/8d992a5zrf9uv","trackType":""} Dec 09 16:02:19 volumio volumio[1275]: verbose: CURRENT POSITION 0 Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::syncState stateService stop Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus stop Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: No code Dec 09 16:02:19 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:19 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:19 volumio volumio[1275]: info: ------------------------------ 1049ms Dec 09 16:02:19 volumio volumio[1275]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 09 16:02:20 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 09 16:02:20 volumio volumio[1275]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 09 16:02:20 volumio volumio[1275]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 09 16:02:20 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 09 16:02:20 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 09 16:02:20 volumio volumio[1275]: info: MyVolumio login type: Token Dec 09 16:02:20 volumio volumio[1275]: info: MyVolumio login type: Token Dec 09 16:02:20 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 09 16:02:20 volumio volumio[1275]: info: MyVolumio login type: Token Dec 09 16:02:22 volumio volumio[1275]: info: MyVolumio token set successfully Dec 09 16:02:22 volumio volumio[1275]: info: MYVOLUMIO: Adding device Dec 09 16:02:22 volumio volumio[1275]: info: MYVOLUMIO: Evaluating Server Dec 09 16:02:23 volumio upmpdcli[1773]: writing RSA key Dec 09 16:02:23 volumio volumio[1275]: info: MyVolumio status changed Dec 09 16:02:23 volumio volumio[1275]: info: Streaming services startup Dec 09 16:02:23 volumio volumio[1275]: info: Starting Streaming Daemon Dec 09 16:02:23 volumio volumio[1275]: info: Removing browser output: myVolumio user plan is not superstar Dec 09 16:02:23 volumio volumio[1275]: info: Removing audio output: Dec 09 16:02:23 volumio volumio[1275]: info: Stoppping Tunnel 1 Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 16:02:23 volumio volumio[1275]: info: Received Get System Info Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 16:02:23 volumio volumio[1275]: info: Discovery: Getting this device information Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 09 16:02:23 volumio sudo[1791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 09 16:02:23 volumio sudo[1791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:23 volumio sudo[1794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 09 16:02:23 volumio sudo[1794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 16:02:23 volumio volumio[1275]: info: Received Get System Info Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 16:02:23 volumio volumio[1275]: info: Discovery: Getting this device information Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 16:02:23 volumio volumio[1275]: info: Received Get System Info Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 16:02:23 volumio volumio[1275]: info: Discovery: Getting this device information Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 09 16:02:23 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 16:02:23 volumio sudo[1791]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:24 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 09 16:02:24 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 09 16:02:24 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 09 16:02:24 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 09 16:02:24 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 09 16:02:24 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 09 16:02:24 volumio sudo[1794]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:24 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 16:02:24 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 16:02:24 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 09 16:02:24 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 09 16:02:24 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 09 16:02:24 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 09 16:02:24 volumio volumio[1275]: info: CoreCommandRouter::volumioGetBrowseSources Dec 09 16:02:24 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 09 16:02:24 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 09 16:02:24 volumio volumio[1275]: info: Remote SSH Stopped Dec 09 16:02:24 volumio volumio[1275]: error: Cannot start Volumio Streaming Daemon Dec 09 16:02:24 volumio volumio[1275]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 09 16:02:24 volumio volumio[1275]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 09 16:02:24 volumio volumio[1275]: info: Preload queue cleared Dec 09 16:02:25 volumio volumio[1275]: info: Setting Geolocation for MyVolumio to us4 Dec 09 16:02:25 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:25 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:25 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:25 volumio volumio[1275]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 09 16:02:26 volumio volumio[1275]: info: Updating MyVolumio device info Dec 09 16:02:26 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:26 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:26 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:26 volumio volumio[1275]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 09 16:02:27 volumio volumio[1275]: info: Preload queue cleared Dec 09 16:02:27 volumio volumio[1275]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 09 16:02:27 volumio volumio[1275]: info: CoreStateMachine::ClearQueue Dec 09 16:02:27 volumio volumio[1275]: info: CoreStateMachine::stop Dec 09 16:02:27 volumio volumio[1275]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 16:02:27 volumio volumio[1275]: info: CorePlayQueue::clearPlayQueue Dec 09 16:02:27 volumio volumio[1275]: info: CorePlayQueue::saveQueue Dec 09 16:02:27 volumio volumio[1275]: info: CoreCommandRouter::volumioPushQueue Dec 09 16:02:27 volumio volumio[1275]: info: CoreStateMachine::addQueueItems Dec 09 16:02:27 volumio volumio[1275]: info: CorePlayQueue::addQueueItems Dec 09 16:02:27 volumio volumio[1275]: info: Preload queue cleared Dec 09 16:02:27 volumio volumio[1275]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1760430 Dec 09 16:02:27 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 09 16:02:27 volumio volumio[1275]: info: CoreCommandRouter::volumioPushQueue Dec 09 16:02:27 volumio volumio[1275]: info: CorePlayQueue::saveQueue Dec 09 16:02:27 volumio volumio[1275]: info: CoreStateMachine::updateTrackBlock Dec 09 16:02:27 volumio volumio[1275]: info: CorePlayQueue::getTrackBlock Dec 09 16:02:27 volumio volumio[1275]: info: CoreCommandRouter::volumioPlay Dec 09 16:02:27 volumio volumio[1275]: info: CoreStateMachine::play index 0 Dec 09 16:02:27 volumio volumio[1275]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 16:02:27 volumio volumio[1275]: info: CoreStateMachine::stop Dec 09 16:02:27 volumio volumio[1275]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 16:02:27 volumio volumio[1275]: info: CoreStateMachine::play index undefined Dec 09 16:02:27 volumio volumio[1275]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 16:02:27 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:27 volumio volumio[1275]: info: CoreStateMachine::startPlaybackTimer Dec 09 16:02:27 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:27 volumio volumio[1275]: info: [1765306947932] ControllerWebradio::clearAddPlayTrack Dec 09 16:02:27 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand stop Dec 09 16:02:27 volumio volumio[1275]: info: sendMpdCommand stop took 48 milliseconds Dec 09 16:02:27 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand clear Dec 09 16:02:27 volumio volumio[1275]: info: Dec 09 16:02:27 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:28 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:28 volumio volumio[1275]: info: sendMpdCommand clear took 44 milliseconds Dec 09 16:02:28 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1760430" Dec 09 16:02:28 volumio volumio[1275]: info: Dec 09 16:02:28 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:28 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:28 volumio volumio[1275]: info: Dec 09 16:02:28 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:28 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:28 volumio volumio[1275]: error: updateQueue error: null Dec 09 16:02:28 volumio volumio[1275]: info: ------------------------------ 50ms Dec 09 16:02:28 volumio volumio[1275]: info: Dec 09 16:02:28 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:28 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:28 volumio volumio[1275]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1760430" took 702 milliseconds Dec 09 16:02:28 volumio volumio[1275]: info: ------------------------------ 701ms Dec 09 16:02:28 volumio volumio[1275]: info: ------------------------------ 728ms Dec 09 16:02:28 volumio volumio[1275]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 09 16:02:28 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand play Dec 09 16:02:28 volumio volumio[1275]: info: Dec 09 16:02:28 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:28 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:28 volumio volumio[1275]: info: Dec 09 16:02:28 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:28 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:28 volumio volumio[1275]: info: ------------------------------ 81ms Dec 09 16:02:28 volumio volumio[1275]: info: sendMpdCommand play took 47 milliseconds Dec 09 16:02:28 volumio volumio[1275]: info: ------------------------------ 41ms Dec 09 16:02:28 volumio volumio[1275]: info: ------------------------------ 45ms Dec 09 16:02:29 volumio volumio[1275]: info: MYVOLUMIO: Adding device Dec 09 16:02:29 volumio volumio[1275]: info: MYVOLUMIO: Evaluating Server Dec 09 16:02:30 volumio volumio[1275]: info: Dec 09 16:02:30 volumio volumio[1275]: ---------------------------- MPD announces state update: player Dec 09 16:02:30 volumio volumio[1275]: info: ControllerMpd::getState Dec 09 16:02:30 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status Dec 09 16:02:30 volumio volumio[1275]: info: Dec 09 16:02:30 volumio volumio[1275]: ---------------------------- MPD announces state update: player Dec 09 16:02:30 volumio volumio[1275]: info: ControllerMpd::getState Dec 09 16:02:30 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status Dec 09 16:02:31 volumio volumio[1275]: info: Dec 09 16:02:31 volumio volumio[1275]: ---------------------------- MPD announces state update: player Dec 09 16:02:31 volumio volumio[1275]: info: Dec 09 16:02:31 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:31 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:31 volumio volumio[1275]: info: Dec 09 16:02:31 volumio volumio[1275]: ---------------------------- MPD announces state update: player Dec 09 16:02:31 volumio volumio[1275]: info: ControllerMpd::getState Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status Dec 09 16:02:31 volumio volumio[1275]: info: sendMpdCommand status took 39 milliseconds Dec 09 16:02:31 volumio volumio[1275]: info: ControllerMpd::getState Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status Dec 09 16:02:31 volumio volumio[1275]: info: sendMpdCommand status took 52 milliseconds Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::parseState Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::parseState Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 16:02:31 volumio volumio[1275]: info: Dec 09 16:02:31 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:31 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:31 volumio volumio[1275]: info: Dec 09 16:02:31 volumio volumio[1275]: ---------------------------- MPD announces state update: player Dec 09 16:02:31 volumio volumio[1275]: info: ControllerMpd::getState Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status Dec 09 16:02:31 volumio volumio[1275]: info: Dec 09 16:02:31 volumio volumio[1275]: ---------------------------- MPD announces system playlist update Dec 09 16:02:31 volumio volumio[1275]: info: Ignoring MPD Status Update Dec 09 16:02:31 volumio volumio[1275]: info: Dec 09 16:02:31 volumio volumio[1275]: ---------------------------- MPD announces state update: player Dec 09 16:02:31 volumio volumio[1275]: info: ControllerMpd::getState Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status Dec 09 16:02:31 volumio volumio[1275]: info: sendMpdCommand status took 139 milliseconds Dec 09 16:02:31 volumio volumio[1275]: info: ------------------------------ 142ms Dec 09 16:02:31 volumio volumio[1275]: info: sendMpdCommand status took 103 milliseconds Dec 09 16:02:31 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 91 milliseconds Dec 09 16:02:31 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 82 milliseconds Dec 09 16:02:31 volumio volumio[1275]: info: ------------------------------ 61ms Dec 09 16:02:31 volumio volumio[1275]: info: sendMpdCommand status took 7 milliseconds Dec 09 16:02:31 volumio volumio[1275]: info: ------------------------------ 6ms Dec 09 16:02:31 volumio volumio[1275]: info: sendMpdCommand status took 5 milliseconds Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::parseState Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::parseState Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::parseState Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::parseState Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 16:02:31 volumio volumio[1275]: info: ControllerMpd::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::servicePushState Dec 09 16:02:31 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:31 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1280,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"One Direction - Night Changes","artist":"1.FM - America's Best Ballads Radio","album":null,"uri":"http://185.33.21.112:80/onelive_64a","trackType":""} Dec 09 16:02:31 volumio volumio[1275]: verbose: CURRENT POSITION 0 Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus stop Dec 09 16:02:31 volumio volumio[1275]: info: ControllerMpd::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::servicePushState Dec 09 16:02:31 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:31 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1280,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"One Direction - Night Changes","artist":"1.FM - America's Best Ballads Radio","album":null,"uri":"http://185.33.21.112:80/onelive_64a","trackType":""} Dec 09 16:02:31 volumio volumio[1275]: verbose: CURRENT POSITION 0 Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play Dec 09 16:02:31 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:31 volumio volumio[1275]: info: ------------------------------ 424ms Dec 09 16:02:31 volumio volumio[1275]: info: ------------------------------ 448ms Dec 09 16:02:31 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 267 milliseconds Dec 09 16:02:31 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 277 milliseconds Dec 09 16:02:31 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 266 milliseconds Dec 09 16:02:31 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 265 milliseconds Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo Dec 09 16:02:31 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo Dec 09 16:02:31 volumio volumio[1275]: info: ControllerMpd::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::servicePushState Dec 09 16:02:31 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:31 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1383,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"One Direction - Night Changes","artist":"1.FM - America's Best Ballads Radio","album":null,"uri":"http://185.33.21.112:80/onelive_64a","trackType":""} Dec 09 16:02:31 volumio volumio[1275]: verbose: CURRENT POSITION 0 Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play Dec 09 16:02:31 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:31 volumio volumio[1275]: info: ControllerMpd::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::servicePushState Dec 09 16:02:31 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:31 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1383,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"One Direction - Night Changes","artist":"1.FM - America's Best Ballads Radio","album":null,"uri":"http://185.33.21.112:80/onelive_64a","trackType":""} Dec 09 16:02:31 volumio volumio[1275]: verbose: CURRENT POSITION 0 Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play Dec 09 16:02:31 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:31 volumio volumio[1275]: info: ControllerMpd::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::servicePushState Dec 09 16:02:31 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:31 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1383,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"One Direction - Night Changes","artist":"1.FM - America's Best Ballads Radio","album":null,"uri":"http://185.33.21.112:80/onelive_64a","trackType":""} Dec 09 16:02:31 volumio volumio[1275]: verbose: CURRENT POSITION 0 Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play Dec 09 16:02:31 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:31 volumio volumio[1275]: info: ControllerMpd::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::servicePushState Dec 09 16:02:31 volumio volumio[1275]: info: CorePlayQueue::getTrack 0 Dec 09 16:02:31 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1383,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"One Direction - Night Changes","artist":"1.FM - America's Best Ballads Radio","album":null,"uri":"http://185.33.21.112:80/onelive_64a","trackType":""} Dec 09 16:02:31 volumio volumio[1275]: verbose: CURRENT POSITION 0 Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play Dec 09 16:02:31 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreStateMachine::pushState Dec 09 16:02:31 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState Dec 09 16:02:32 volumio volumio[1275]: info: ------------------------------ 1056ms Dec 09 16:02:32 volumio volumio[1275]: info: ------------------------------ 1079ms Dec 09 16:02:32 volumio volumio[1275]: info: ------------------------------ 947ms Dec 09 16:02:32 volumio volumio[1275]: info: ------------------------------ 955ms Dec 09 16:02:32 volumio volumio[1275]: info: Setting Geolocation for MyVolumio to us1 Dec 09 16:02:32 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:32 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:32 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:33 volumio volumio[1275]: info: Updating MyVolumio device info Dec 09 16:02:33 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:33 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:33 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:33 volumio volumio[1275]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 09 16:02:33 volumio volumio[1275]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 09 16:02:34 volumio volumio[1275]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 09 16:02:34 volumio volumio[1275]: info: Completed starting MyVolumio Plugin Dec 09 16:02:34 volumio volumio[1275]: [Metrics] CommandRouter: 82s 78.09ms Dec 09 16:02:34 volumio volumio[1275]: info: CoreCommandRouter::volumiosetStartupVolume Dec 09 16:02:34 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 09 16:02:34 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 16:02:34 volumio volumio[1275]: info: CoreCommandRouter::Close All Modals sent Dec 09 16:02:34 volumio volumio[1275]: info: CoreCommandRouter::Close All Modals sent Dec 09 16:02:34 volumio volumio[1275]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Dec 09 16:02:34 volumio volumio[1275]: aplay: main:831: audio open error: Device or resource busy Dec 09 16:02:35 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 09 16:02:35 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 09 16:02:35 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 09 16:02:37 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState Dec 09 16:02:38 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 16:02:38 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 09 16:02:41 volumio volumio[1275]: info: BOOT COMPLETED Dec 09 16:02:43 volumio volumio[1275]: info: Enabling plugin touch_display Dec 09 16:02:43 volumio volumio[1275]: info: Loading plugin "touch_display"... Dec 09 16:02:45 volumio volumio[1275]: info: PLUGIN START: touch_display Dec 09 16:02:45 volumio volumio[1275]: info: Loading i18n strings for locale pt Dec 09 16:02:45 volumio volumio[1275]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 09 16:02:45 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 09 16:02:45 volumio sudo[1885]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Dec 09 16:02:46 volumio sudo[1885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:46 volumio sudo[1887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 09 16:02:46 volumio sudo[1887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:46 volumio sudo[1885]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:46 volumio systemd[1]: Stopping getty@tty1.service - Getty on tty1... Dec 09 16:02:46 volumio volumio[1275]: info: touch_display: No backlight interface detected. Dec 09 16:02:46 volumio systemd[1]: getty@tty1.service: Deactivated successfully. Dec 09 16:02:46 volumio systemd[1]: Stopped getty@tty1.service - Getty on tty1. Dec 09 16:02:46 volumio sudo[1887]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:46 volumio sudo[1890]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 09 16:02:46 volumio sudo[1890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:46 volumio sudo[1893]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 09 16:02:46 volumio sudo[1893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:46 volumio volumio[1275]: Upnp client error: Error: This socket has been ended by the other party Dec 09 16:02:46 volumio volumio[1275]: info: touch_display: systemctl stop getty@tty1.service succeeded. Dec 09 16:02:46 volumio volumio[1275]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 09 16:02:46 volumio systemd[1]: Reloading. Dec 09 16:02:46 volumio volumio[1275]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Dec 09 16:02:46 volumio sudo[1900]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Dec 09 16:02:46 volumio sudo[1900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:46 volumio sudo[1897]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Dec 09 16:02:46 volumio sudo[1897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:47 volumio sudo[1900]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:47 volumio sudo[1897]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:47 volumio volumio[1275]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Dec 09 16:02:47 volumio volumio[1275]: info: touch_display: IframeKeyboardBridge extension installed successfully Dec 09 16:02:48 volumio sudo[1890]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:48 volumio volumio[1275]: info: Enabling plugin touch_display Dec 09 16:02:48 volumio volumio[1275]: info: Loading plugin "touch_display"... Dec 09 16:02:48 volumio systemd[1]: Reloading. Dec 09 16:02:48 volumio volumio[1275]: info: Applying required configuration parameters for plugin touch_display Dec 09 16:02:48 volumio volumio[1275]: info: PLUGIN START: touch_display Dec 09 16:02:48 volumio volumio[1275]: info: Loading i18n strings for locale pt Dec 09 16:02:48 volumio volumio[1275]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 09 16:02:48 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 09 16:02:48 volumio sudo[1929]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Dec 09 16:02:48 volumio sudo[1929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:48 volumio sudo[1929]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:48 volumio sudo[1931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 09 16:02:48 volumio sudo[1931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:48 volumio volumio[1275]: info: touch_display: No backlight interface detected. Dec 09 16:02:48 volumio sudo[1945]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 09 16:02:48 volumio volumio[1275]: info: touch_display: systemctl disable getty@tty1.service succeeded. Dec 09 16:02:48 volumio sudo[1934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 09 16:02:48 volumio sudo[1945]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:48 volumio sudo[1934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:49 volumio sudo[1961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Dec 09 16:02:49 volumio sudo[1961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:49 volumio volumio[1275]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Dec 09 16:02:49 volumio sudo[1961]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:49 volumio volumio[1275]: info: touch_display: IframeKeyboardBridge extension installed successfully Dec 09 16:02:49 volumio sudo[1978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Dec 09 16:02:49 volumio sudo[1978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:49 volumio sudo[1978]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:49 volumio volumio[1275]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Dec 09 16:02:50 volumio sudo[1893]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:50 volumio systemd[1]: Reloading. Dec 09 16:02:50 volumio volumio[1275]: info: touch_display: systemctl daemon-reload succeeded. Dec 09 16:02:50 volumio sudo[1983]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 09 16:02:50 volumio sudo[1983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:52 volumio sudo[1945]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:52 volumio sudo[1983]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:52 volumio volumio[1275]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Dec 09 16:02:52 volumio volumio[1275]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Dec 09 16:02:52 volumio systemd[1]: Reloading. Dec 09 16:02:52 volumio volumio[1275]: info: Error: Error Dec 09 16:02:52 volumio volumio[1275]: info: touch_display: systemctl daemon-reload succeeded. Dec 09 16:02:52 volumio sudo[2023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 09 16:02:52 volumio sudo[2023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:53 volumio volumio[1275]: info: Starting Uninstall of plugin user_interface - touch_display Dec 09 16:02:53 volumio volumio[1275]: info: Uninstalling plugin touch_display Dec 09 16:02:54 volumio sudo[2031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Dec 09 16:02:54 volumio sudo[2031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:54 volumio sudo[2029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 09 16:02:54 volumio sudo[2029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:54 volumio sudo[2033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Dec 09 16:02:54 volumio sudo[2033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:54 volumio sudo[2029]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:54 volumio sudo[2035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Dec 09 16:02:54 volumio volumio[1275]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 09 16:02:54 volumio sudo[2035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 09 16:02:54 volumio volumio[1275]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 16:02:54 volumio volumio[1275]: TypeError: Cannot read properties of undefined (reading 'split') Dec 09 16:02:54 volumio volumio[1275]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Dec 09 16:02:54 volumio volumio[1275]: at ChildProcess.exithandler (node:child_process:413:7) Dec 09 16:02:54 volumio volumio[1275]: at ChildProcess.emit (node:events:514:28) Dec 09 16:02:54 volumio volumio[1275]: at maybeClose (node:internal/child_process:1105:16) Dec 09 16:02:54 volumio volumio[1275]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Dec 09 16:02:54 volumio volumio[1275]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 16:02:54 volumio sudo[2023]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:54 volumio sudo[1934]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:54 volumio sudo[1931]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:54 volumio systemd[1]: Reloading. Dec 09 16:02:54 volumio sudo[2035]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:56 volumio sudo[2033]: pam_unix(sudo:session): session closed for user root Dec 09 16:02:56 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 09 16:02:56 volumio sudo[2031]: pam_unix(sudo:session): session closed for user root Dec 09 16:03:01 volumio sudo[2086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-09 16:02' Dec 09 16:03:01 volumio sudo[2086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"