-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Sun 2025-02-23 11:36:06 EET. -- Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 23 11:35:01 volumio volumio[827]: info: Adding plugin bluetooth to MyMusic Plugins Feb 23 11:35:01 volumio volumio[827]: info: Adding plugin multiroom to MyMusic Plugins Feb 23 11:35:01 volumio volumio[827]: info: Adding plugin metavolumio to MyMusic Plugins Feb 23 11:35:01 volumio volumio[827]: info: Adding plugin cd_controller to MyMusic Plugins Feb 23 11:35:01 volumio volumio[827]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 23 11:35:01 volumio volumio[827]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 23 11:35:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:35:01 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:35:01 volumio volumio[827]: info: Starting MyVolumio Remote Streaming Endpoints Feb 23 11:35:01 volumio volumio[827]: info: MyVolumio login type: Token Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 23 11:35:01 volumio volumio[827]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 23 11:35:02 volumio volumio[827]: info: Starting Streaming Service Transparent Proxy Feb 23 11:35:02 volumio volumio[827]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 23 11:35:02 volumio volumio[827]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 23 11:35:02 volumio volumio[827]: info: Streaming services startup Feb 23 11:35:02 volumio volumio[827]: info: Starting Streaming Daemon Feb 23 11:35:02 volumio sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 23 11:35:02 volumio sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 11:35:02 volumio sudo[1191]: pam_unix(sudo:session): session closed for user root Feb 23 11:35:02 volumio volumio[827]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 23 11:35:03 volumio volumio[827]: error: Cannot start Volumio Streaming Daemon Feb 23 11:35:03 volumio volumio[827]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 23 11:35:03 volumio volumio[827]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 23 11:35:03 volumio volumio[827]: STREAMING PROXY: Starting server on port 3245 Feb 23 11:35:03 volumio volumio[827]: Node JS runtime: 14 Feb 23 11:35:05 volumio volumio[827]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 23 11:35:07 volumio systemd[1]: systemd-fsckd.service: Succeeded. Feb 23 11:35:08 volumio volumio[827]: info: MyVolumio token set successfully Feb 23 11:35:08 volumio volumio[827]: info: MYVOLUMIO: Adding device Feb 23 11:35:08 volumio volumio[827]: info: MYVOLUMIO: Evaluating Server Feb 23 11:35:09 volumio volumio[827]: info: MyVolumio status changed Feb 23 11:35:09 volumio volumio[827]: info: Streaming services startup Feb 23 11:35:09 volumio volumio[827]: info: Starting Streaming Daemon Feb 23 11:35:09 volumio volumio[827]: info: Removing browser output: myVolumio user plan is not superstar Feb 23 11:35:09 volumio volumio[827]: info: Removing audio output: Feb 23 11:35:09 volumio volumio[827]: info: Stoppping Tunnel 1 Feb 23 11:35:09 volumio sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 23 11:35:09 volumio sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 11:35:09 volumio sudo[1233]: pam_unix(sudo:session): session closed for user root Feb 23 11:35:09 volumio sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 23 11:35:09 volumio sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 11:35:09 volumio sudo[1236]: pam_unix(sudo:session): session closed for user root Feb 23 11:35:09 volumio volumio[827]: info: Setting Geolocation for MyVolumio to eu4 Feb 23 11:35:09 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:35:09 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:35:09 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:35:09 volumio volumio[827]: info: Remote SSH Stopped Feb 23 11:35:09 volumio volumio[827]: error: Cannot start Volumio Streaming Daemon Feb 23 11:35:09 volumio volumio[827]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 23 11:35:09 volumio volumio[827]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 23 11:35:09 volumio volumio[827]: info: AutoStart - Plugin is starting Feb 23 11:35:09 volumio volumio[827]: info: CoreCommandRouter::volumioGetQueue Feb 23 11:35:09 volumio volumio[827]: info: CoreStateMachine::getQueue Feb 23 11:35:09 volumio volumio[827]: info: CorePlayQueue::getQueue Feb 23 11:35:09 volumio volumio[827]: info: AutoStart - start playing Feb 23 11:35:09 volumio volumio[827]: info: AutoStart - start playing with no specific position Feb 23 11:35:09 volumio volumio[827]: info: CoreCommandRouter::volumioPlay Feb 23 11:35:09 volumio volumio[827]: info: CoreStateMachine::play index 0 Feb 23 11:35:09 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 11:35:09 volumio volumio[827]: info: CoreStateMachine::stop Feb 23 11:35:09 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 11:35:09 volumio volumio[827]: info: CoreStateMachine::play index undefined Feb 23 11:35:09 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 11:35:09 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Feb 23 11:35:09 volumio volumio[827]: info: CoreStateMachine::startPlaybackTimer Feb 23 11:35:09 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Feb 23 11:35:09 volumio volumio[827]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Progressive House/House of the past years (2025)/001. Worakls - Good Night My Love (Joachim Pastor Remix) (Joachim Pastor Remix) (Joachim Pastor Remix).mp3 Feb 23 11:35:09 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 11:35:10 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Feb 23 11:35:10 volumio volumio[827]: info: Updating MyVolumio device info Feb 23 11:35:10 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:35:12 volumio volumio[827]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 23 11:35:13 volumio volumio[827]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 23 11:35:15 volumio volumio[1168]: ...................................................................................................................................................................................................++++ Feb 23 11:35:16 volumio volumio[827]: info: MYVOLUMIO: Adding device Feb 23 11:35:16 volumio volumio[827]: info: MYVOLUMIO: Evaluating Server Feb 23 11:35:17 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 11:35:18 volumio volumio[827]: info: Setting Geolocation for MyVolumio to eu4 Feb 23 11:35:18 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:35:18 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:35:18 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:35:18 volumio volumio[1168]: ...............................++++ Feb 23 11:35:18 volumio volumio[1168]: e is 65537 (0x010001) Feb 23 11:35:18 volumio volumio[1168]: writing RSA key Feb 23 11:35:19 volumio volumio[827]: 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 23 11:35:19 volumio volumio[827]: info: Updating MyVolumio device info Feb 23 11:35:19 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:35:20 volumio systemd[1]: systemd-timedated.service: Succeeded. Feb 23 11:35:20 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 11:35:20 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:35:20 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 11:35:21 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Feb 23 11:35:21 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Feb 23 11:35:21 volumio volumio-remote-updater[646]: No test mode Feb 23 11:35:21 volumio volumio-remote-updater[646]: No alpha test mode Feb 23 11:35:21 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 23 11:35:21 volumio volumio[827]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 23 11:35:21 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:35:21 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:35:21 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 23 11:35:21 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 23 11:35:21 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 23 11:35:21 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 23 11:35:21 volumio volumio[827]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 11:35:21 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 11:35:22 volumio volumio[827]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 23 11:35:22 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 23 11:35:23 volumio volumio[827]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 23 11:35:23 volumio volumio[827]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 23 11:35:23 volumio volumio[827]: info: Completed starting MyVolumio Plugin Feb 23 11:35:23 volumio volumio[827]: [Metrics] CommandRouter: 38s 288.70ms Feb 23 11:35:23 volumio volumio[827]: info: CoreCommandRouter::volumiosetStartupVolume Feb 23 11:35:23 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 11:35:23 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 11:35:23 volumio volumio[827]: info: CoreCommandRouter::Close All Modals sent Feb 23 11:35:23 volumio volumio[827]: info: CoreCommandRouter::Close All Modals sent Feb 23 11:35:25 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 23 11:35:25 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 23 11:35:25 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 23 11:35:26 volumio volumio[827]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.218 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/27.0 Chrome/125.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 23 11:35:27 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Feb 23 11:35:27 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Feb 23 11:35:28 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 11:35:28 volumio volumio[827]: info: Listing playlists Feb 23 11:35:28 volumio volumio[827]: info: CoreCommandRouter::volumioGetQueue Feb 23 11:35:28 volumio volumio[827]: info: CoreStateMachine::getQueue Feb 23 11:35:28 volumio volumio[827]: info: CorePlayQueue::getQueue Feb 23 11:35:30 volumio volumio[827]: info: BOOT COMPLETED Feb 23 11:35:34 volumio volumio[827]: Cannot compose Albumart path Feb 23 11:35:37 volumio volumio[827]: Cannot compose Albumart path Feb 23 11:35:44 volumio volumio-remote-updater[646]: [2025-02-23 11:35:44] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Feb 23 11:35:44 volumio ntpd[792]: Soliciting pool server 194.8.146.38 Feb 23 11:35:45 volumio ntpd[792]: Soliciting pool server 94.158.46.209 Feb 23 11:35:46 volumio ntpd[792]: Soliciting pool server 193.106.144.6 Feb 23 11:35:47 volumio ntpd[792]: Soliciting pool server 193.84.22.254 Feb 23 11:35:48 volumio ntpd[792]: Soliciting pool server 193.106.144.7 Feb 23 11:35:48 volumio ntpd[792]: Soliciting pool server 213.231.2.124 Feb 23 11:35:48 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 11:35:48 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 23 11:35:49 volumio volumio-remote-updater[646]: [2025-02-23 11:35:49] [connect] Successful connection Feb 23 11:35:49 volumio volumio[827]: info: sendMpdCommand stop took 39296 milliseconds Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 11:35:49 volumio volumio[827]: info: Feb 23 11:35:49 volumio volumio[827]: ---------------------------- MPD announces system playlist update Feb 23 11:35:49 volumio volumio[827]: info: Ignoring MPD Status Update Feb 23 11:35:49 volumio volumio[827]: info: sendMpdCommand clear took 3 milliseconds Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Progressive House/House of the past years (2025)/001. Worakls - Good Night My Love (Joachim Pastor Remix) (Joachim Pastor Remix) (Joachim Pastor Remix).mp3" Feb 23 11:35:49 volumio volumio[827]: info: Feb 23 11:35:49 volumio volumio[827]: ---------------------------- MPD announces system playlist update Feb 23 11:35:49 volumio volumio[827]: info: Ignoring MPD Status Update Feb 23 11:35:49 volumio volumio[827]: info: Feb 23 11:35:49 volumio volumio[827]: ---------------------------- MPD announces system playlist update Feb 23 11:35:49 volumio volumio[827]: info: Ignoring MPD Status Update Feb 23 11:35:49 volumio volumio-remote-updater[646]: [2025-02-23 11:35:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740303349 101 Feb 23 11:35:49 volumio volumio[827]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Feb 23 11:35:49 volumio volumio[827]: error: updateQueue error: null Feb 23 11:35:49 volumio volumio[827]: info: Feb 23 11:35:49 volumio volumio[827]: ---------------------------- MPD announces system playlist update Feb 23 11:35:49 volumio volumio[827]: info: Ignoring MPD Status Update Feb 23 11:35:49 volumio volumio[827]: info: ------------------------------ 33ms Feb 23 11:35:49 volumio volumio[827]: info: sendMpdCommand add "NAS/Music/Progressive House/House of the past years (2025)/001. Worakls - Good Night My Love (Joachim Pastor Remix) (Joachim Pastor Remix) (Joachim Pastor Remix).mp3" took 32 milliseconds Feb 23 11:35:49 volumio volumio[827]: info: ------------------------------ 26ms Feb 23 11:35:49 volumio volumio[827]: info: ------------------------------ 25ms Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand play Feb 23 11:35:49 volumio volumio[827]: info: Feb 23 11:35:49 volumio volumio[827]: ---------------------------- MPD announces system playlist update Feb 23 11:35:49 volumio volumio[827]: info: Ignoring MPD Status Update Feb 23 11:35:49 volumio volumio[827]: info: Feb 23 11:35:49 volumio volumio[827]: ---------------------------- MPD announces system playlist update Feb 23 11:35:49 volumio volumio[827]: info: Ignoring MPD Status Update Feb 23 11:35:49 volumio volumio[827]: info: ------------------------------ 13ms Feb 23 11:35:49 volumio volumio[827]: info: sendMpdCommand play took 12 milliseconds Feb 23 11:35:49 volumio volumio[827]: info: ------------------------------ 10ms Feb 23 11:35:49 volumio volumio[827]: info: ------------------------------ 9ms Feb 23 11:35:49 volumio volumio[827]: info: Feb 23 11:35:49 volumio volumio[827]: ---------------------------- MPD announces state update: player Feb 23 11:35:49 volumio volumio[827]: info: ControllerMpd::getState Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status Feb 23 11:35:49 volumio volumio[827]: info: Feb 23 11:35:49 volumio volumio[827]: ---------------------------- MPD announces state update: player Feb 23 11:35:49 volumio volumio[827]: info: ControllerMpd::getState Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status Feb 23 11:35:49 volumio volumio[827]: info: Feb 23 11:35:49 volumio volumio[827]: ---------------------------- MPD announces state update: player Feb 23 11:35:49 volumio volumio[827]: info: ControllerMpd::getState Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status Feb 23 11:35:49 volumio volumio[827]: info: sendMpdCommand status took 8 milliseconds Feb 23 11:35:49 volumio volumio[827]: info: sendMpdCommand status took 4 milliseconds Feb 23 11:35:49 volumio volumio[827]: info: sendMpdCommand status took 4 milliseconds Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::parseState Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::parseState Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::parseState Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 11:35:49 volumio volumio[827]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 11:35:49 volumio volumio[827]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 23 11:35:49 volumio volumio[827]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo Feb 23 11:35:49 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo Feb 23 11:35:49 volumio volumio[827]: info: ControllerMpd::pushState Feb 23 11:35:49 volumio volumio[827]: info: CoreCommandRouter::servicePushState Feb 23 11:35:49 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Feb 23 11:35:49 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":400,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Good Night My Love (Joachim Pastor Remix)","artist":"Worakls","album":"House of the past years","uri":"NAS/Music/Progressive House/House of the past years (2025)/001. Worakls - Good Night My Love (Joachim Pastor Remix) (Joachim Pastor Remix) (Joachim Pastor Remix).mp3","trackType":"mp3"} Feb 23 11:35:49 volumio volumio[827]: verbose: CURRENT POSITION 0 Feb 23 11:35:49 volumio volumio[827]: info: CoreStateMachine::syncState stateService play Feb 23 11:35:49 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus stop Feb 23 11:35:49 volumio volumio[827]: info: ControllerMpd::pushState Feb 23 11:35:49 volumio volumio[827]: info: CoreCommandRouter::servicePushState Feb 23 11:35:49 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Feb 23 11:35:49 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":400,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Good Night My Love (Joachim Pastor Remix)","artist":"Worakls","album":"House of the past years","uri":"NAS/Music/Progressive House/House of the past years (2025)/001. Worakls - Good Night My Love (Joachim Pastor Remix) (Joachim Pastor Remix) (Joachim Pastor Remix).mp3","trackType":"mp3"} Feb 23 11:35:49 volumio volumio[827]: verbose: CURRENT POSITION 0 Feb 23 11:35:49 volumio volumio[827]: info: CoreStateMachine::syncState stateService play Feb 23 11:35:49 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play Feb 23 11:35:49 volumio volumio[827]: info: Received an update from plugin. extracting info from payload Feb 23 11:35:49 volumio volumio[827]: info: CoreStateMachine::pushState Feb 23 11:35:49 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Feb 23 11:35:49 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 11:35:49 volumio volumio[827]: info: CoreCommandRouter::volumioPushState Feb 23 11:35:49 volumio volumio[827]: info: CoreStateMachine::pushState Feb 23 11:35:49 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Feb 23 11:35:49 volumio volumio[827]: info: CoreCommandRouter::volumioPushState Feb 23 11:35:49 volumio volumio[827]: info: ControllerMpd::pushState Feb 23 11:35:49 volumio volumio[827]: info: CoreCommandRouter::servicePushState Feb 23 11:35:49 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Feb 23 11:35:49 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":400,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Good Night My Love (Joachim Pastor Remix)","artist":"Worakls","album":"House of the past years","uri":"NAS/Music/Progressive House/House of the past years (2025)/001. Worakls - Good Night My Love (Joachim Pastor Remix) (Joachim Pastor Remix) (Joachim Pastor Remix).mp3","trackType":"mp3"} Feb 23 11:35:49 volumio volumio[827]: verbose: CURRENT POSITION 0 Feb 23 11:35:49 volumio volumio[827]: info: CoreStateMachine::syncState stateService play Feb 23 11:35:49 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play Feb 23 11:35:49 volumio volumio[827]: info: Received an update from plugin. extracting info from payload Feb 23 11:35:49 volumio volumio[827]: info: CoreStateMachine::pushState Feb 23 11:35:49 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Feb 23 11:35:49 volumio volumio[827]: info: CoreCommandRouter::volumioPushState Feb 23 11:35:49 volumio volumio[827]: info: CoreStateMachine::pushState Feb 23 11:35:49 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Feb 23 11:35:49 volumio volumio[827]: info: CoreCommandRouter::volumioPushState Feb 23 11:35:49 volumio volumio[827]: info: ------------------------------ 38ms Feb 23 11:35:49 volumio volumio[827]: info: ------------------------------ 40ms Feb 23 11:35:49 volumio volumio[827]: info: ------------------------------ 40ms Feb 23 11:35:49 volumio volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 23 11:35:49 volumio ntpd[792]: Soliciting pool server 37.53.92.252 Feb 23 11:35:49 volumio ntpd[792]: Soliciting pool server 162.159.200.123 Feb 23 11:35:49 volumio sudo[1398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 11:35:49 volumio sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 11:35:49 volumio sudo[1398]: pam_unix(sudo:session): session closed for user root Feb 23 11:35:49 volumio sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 11:35:49 volumio sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 11:35:49 volumio sudo[1401]: pam_unix(sudo:session): session closed for user root Feb 23 11:35:49 volumio volumio[827]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.218 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/27.0 Chrome/125.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 23 11:35:50 volumio volumio[827]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 23 11:35:50 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 11:35:50 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 11:35:50 volumio volumio[827]: info: CoreCommandRouter::volumioGetState Feb 23 11:35:50 volumio volumio[827]: info: CorePlayQueue::getTrack 0 Feb 23 11:35:50 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 23 11:35:50 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 11:35:50 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 11:35:50 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 11:35:50 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 23 11:35:50 volumio ntpd[792]: Soliciting pool server 162.159.200.1 Feb 23 11:35:50 volumio ntpd[792]: Soliciting pool server 91.236.251.34 Feb 23 11:35:51 volumio ntpd[792]: Soliciting pool server 79.142.192.4 Feb 23 11:35:52 volumio ntpd[792]: Soliciting pool server 91.231.182.17 Feb 23 11:35:55 volumio ntpd[792]: receive: Unexpected origin timestamp 0xeb656a7a.bfb4e336 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xeb656a7b.6704e48c Feb 23 11:35:55 volumio ntpd[792]: receive: Unexpected origin timestamp 0xeb656a7a.bfb6008d does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xeb656a7b.66f9d2b0 Feb 23 11:36:03 volumio volumio[827]: info: Starting Uninstall of plugin system_controller - ampswitch Feb 23 11:36:03 volumio volumio[827]: info: Uninstalling plugin ampswitch Feb 23 11:36:03 volumio volumio[827]: info: [ASDebug] Port: 24 Feb 23 11:36:03 volumio volumio[827]: info: [ASDebug] Inverted: false Feb 23 11:36:03 volumio volumio[827]: info: [ASDebug] Delay: 720 Feb 23 11:36:06 volumio volumio[827]: info: [ASDebug] Port: 24 Feb 23 11:36:06 volumio volumio[827]: info: [ASDebug] Inverted: false Feb 23 11:36:06 volumio volumio[827]: info: [ASDebug] Delay: 720 Feb 23 11:36:06 volumio volumio[827]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 11:36:06 volumio volumio[827]: Error: EBADF: bad file descriptor, close Feb 23 11:36:06 volumio volumio[827]: at Object.closeSync (fs.js:439:3) Feb 23 11:36:06 volumio volumio[827]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Feb 23 11:36:06 volumio volumio[827]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Feb 23 11:36:06 volumio volumio[827]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Feb 23 11:36:06 volumio volumio[827]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Feb 23 11:36:06 volumio volumio[827]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Feb 23 11:36:06 volumio volumio[827]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Feb 23 11:36:06 volumio volumio[827]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Feb 23 11:36:06 volumio volumio[827]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1550:29) Feb 23 11:36:06 volumio volumio[827]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1395:47) Feb 23 11:36:06 volumio volumio[827]: at Socket.emit (events.js:315:20) Feb 23 11:36:06 volumio volumio[827]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 23 11:36:06 volumio volumio[827]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Feb 23 11:36:06 volumio volumio[827]: errno: -9, Feb 23 11:36:06 volumio volumio[827]: syscall: 'close', Feb 23 11:36:06 volumio volumio[827]: code: 'EBADF' Feb 23 11:36:06 volumio volumio[827]: } Feb 23 11:36:06 volumio volumio[827]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 11:36:06 volumio sudo[1467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 11:35 Feb 23 11:36:06 volumio sudo[1467]: 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"