-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Mon 2025-02-24 10:30:28 MSK. -- Feb 24 10:29:08 volumio volumio-remote-updater[674]: [2025-02-24 10:29:08] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Feb 24 10:29:09 volumio volumio[898]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 24 10:29:09 volumio volumio[898]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 24 10:29:09 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 10:29:09 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 10:29:09 volumio volumio[898]: info: Starting MyVolumio Remote Streaming Endpoints Feb 24 10:29:09 volumio volumio[898]: info: MyVolumio not started Feb 24 10:29:09 volumio volumio[898]: info: Initializing device activation check Feb 24 10:29:09 volumio volumio[898]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 24 10:29:09 volumio volumio[898]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 24 10:29:11 volumio volumio[898]: info: Starting Streaming Service Transparent Proxy Feb 24 10:29:11 volumio volumio[898]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 24 10:29:11 volumio volumio[898]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 24 10:29:11 volumio volumio[898]: info: Streaming services startup Feb 24 10:29:11 volumio volumio[898]: info: Starting Streaming Daemon Feb 24 10:29:11 volumio volumio[898]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 24 10:29:11 volumio sudo[1855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 24 10:29:11 volumio sudo[1855]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 10:29:11 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 10:29:11 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 10:29:11 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 24 10:29:11 volumio sudo[1855]: pam_unix(sudo:session): session closed for user root Feb 24 10:29:11 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:29:11 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:11 volumio volumio[898]: info: CoreCommandRouter::volumioPlay Feb 24 10:29:11 volumio volumio[898]: info: CoreStateMachine::play index undefined Feb 24 10:29:11 volumio volumio[898]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 10:29:11 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:11 volumio volumio[898]: info: CoreStateMachine::startPlaybackTimer Feb 24 10:29:11 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:11 volumio volumio[898]: info: [1740382151478] ControllerUPNPBrowser::clearAddPlayTrack Feb 24 10:29:11 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 10:29:11 volumio volumio[898]: error: updateQueue error: null Feb 24 10:29:11 volumio volumio[898]: error: updateQueue error: null Feb 24 10:29:11 volumio volumio[898]: error: updateQueue error: null Feb 24 10:29:11 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.1:8200/MediaItems/5280.flac" Feb 24 10:29:11 volumio volumio[898]: info: ------------------------------ 19854ms Feb 24 10:29:11 volumio volumio[898]: info: ------------------------------ 19850ms Feb 24 10:29:11 volumio volumio[898]: info: ------------------------------ 19848ms Feb 24 10:29:11 volumio volumio[898]: info: sendMpdCommand stop took 16 milliseconds Feb 24 10:29:11 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 10:29:11 volumio volumio[898]: error: Cannot start Volumio Streaming Daemon Feb 24 10:29:11 volumio volumio[898]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 24 10:29:11 volumio volumio[898]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 24 10:29:11 volumio volumio[898]: info: Feb 24 10:29:11 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:11 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:11 volumio volumio[898]: info: Feb 24 10:29:11 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:11 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:11 volumio volumio[898]: info: sendMpdCommand add "http://192.168.1.1:8200/MediaItems/5280.flac" took 55 milliseconds Feb 24 10:29:11 volumio volumio[898]: info: sendMpdCommand clear took 53 milliseconds Feb 24 10:29:11 volumio volumio[898]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 10:29:11 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand play Feb 24 10:29:11 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.1:8200/MediaItems/5280.flac" Feb 24 10:29:11 volumio volumio[898]: info: Feb 24 10:29:11 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:11 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:11 volumio volumio[898]: info: Feb 24 10:29:11 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:11 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:11 volumio volumio[898]: verbose: New Socket.io Connection to 192.168.1.117 from 192.168.1.103 UA: Mozilla/5.0 (Linux; Android 13; 21051182G Build/TKQ1.221013.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.163 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 24 10:29:11 volumio volumio[898]: verbose: New Socket.io Connection to 192.168.1.117:3000 from 192.168.1.103 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Feb 24 10:29:11 volumio volumio[898]: verbose: New Socket.io Connection to 192.168.1.117 from 192.168.1.103 UA: Mozilla/5.0 (Linux; Android 13; 21051182G Build/TKQ1.221013.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.163 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Feb 24 10:29:11 volumio volumio[898]: verbose: New Socket.io Connection to 192.168.1.117 from 192.168.1.103 UA: Mozilla/5.0 (Linux; Android 13; 21051182G Build/TKQ1.221013.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.163 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 24 10:29:11 volumio volumio[898]: error: updateQueue error: null Feb 24 10:29:11 volumio volumio[898]: info: ------------------------------ 99ms Feb 24 10:29:11 volumio volumio[898]: info: ------------------------------ 98ms Feb 24 10:29:11 volumio volumio[898]: info: sendMpdCommand play took 95 milliseconds Feb 24 10:29:12 volumio volumio-remote-updater[674]: [2025-02-24 10:29:12] [connect] Successful connection Feb 24 10:29:12 volumio volumio-remote-updater[674]: [2025-02-24 10:29:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740382152 101 Feb 24 10:29:12 volumio volumio[898]: 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: 5 Feb 24 10:29:13 volumio volumio[898]: verbose: New Socket.io Connection to 192.168.1.117 from 192.168.1.103 UA: Mozilla/5.0 (Linux; Android 13; 21051182G Build/TKQ1.221013.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.163 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 24 10:29:13 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 24 10:29:13 volumio volumio[898]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 10:29:13 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 10:29:13 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:29:13 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:29:13 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 24 10:29:13 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 10:29:13 volumio volumio[898]: info: Received Get System Info Feb 24 10:29:13 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 10:29:13 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 10:29:13 volumio volumio[898]: info: Discovery: Getting this device information Feb 24 10:29:13 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:29:13 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 10:29:13 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:29:13 volumio volumio[898]: info: Listing playlists Feb 24 10:29:13 volumio volumio[898]: info: CoreCommandRouter::volumioGetQueue Feb 24 10:29:13 volumio volumio[898]: info: CoreStateMachine::getQueue Feb 24 10:29:13 volumio volumio[898]: info: CorePlayQueue::getQueue Feb 24 10:29:15 volumio volumio[898]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 24 10:29:15 volumio volumio[898]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 24 10:29:20 volumio ntpd[861]: Soliciting pool server 178.217.98.201 Feb 24 10:29:21 volumio volumio[898]: error: updateQueue error: null Feb 24 10:29:21 volumio volumio[898]: error: updateQueue error: null Feb 24 10:29:21 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.1:8200/MediaItems/5280.flac" Feb 24 10:29:21 volumio volumio[898]: info: ------------------------------ 9771ms Feb 24 10:29:21 volumio volumio[898]: info: ------------------------------ 9769ms Feb 24 10:29:21 volumio volumio[898]: info: Feb 24 10:29:21 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:21 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:21 volumio volumio[898]: info: Feb 24 10:29:21 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:21 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:21 volumio volumio[898]: info: Feb 24 10:29:21 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:21 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:21 volumio volumio[898]: info: sendMpdCommand add "http://192.168.1.1:8200/MediaItems/5280.flac" took 16 milliseconds Feb 24 10:29:21 volumio volumio[898]: info: ------------------------------ 15ms Feb 24 10:29:21 volumio volumio[898]: info: ------------------------------ 13ms Feb 24 10:29:21 volumio volumio[898]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 10:29:21 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand play Feb 24 10:29:21 volumio volumio[898]: info: Feb 24 10:29:21 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:21 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:21 volumio volumio[898]: info: Feb 24 10:29:21 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:21 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:21 volumio volumio[898]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 24 10:29:21 volumio volumio[898]: info: ------------------------------ 35ms Feb 24 10:29:21 volumio volumio[898]: info: sendMpdCommand play took 24 milliseconds Feb 24 10:29:21 volumio volumio[898]: info: ------------------------------ 23ms Feb 24 10:29:21 volumio volumio[898]: info: ------------------------------ 22ms Feb 24 10:29:21 volumio volumio[898]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 24 10:29:22 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 24 10:29:22 volumio volumio[898]: info: CoreCommandRouter::volumioPlay Feb 24 10:29:22 volumio volumio[898]: info: CoreStateMachine::play index undefined Feb 24 10:29:22 volumio volumio[898]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 10:29:22 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:22 volumio volumio[898]: info: CoreStateMachine::startPlaybackTimer Feb 24 10:29:22 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:22 volumio volumio[898]: info: [1740382162532] ControllerUPNPBrowser::clearAddPlayTrack Feb 24 10:29:22 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 10:29:22 volumio volumio[898]: info: sendMpdCommand stop took 1 milliseconds Feb 24 10:29:22 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 10:29:22 volumio volumio[898]: info: Feb 24 10:29:22 volumio volumio[898]: ---------------------------- MPD announces state update: player Feb 24 10:29:22 volumio volumio[898]: info: Feb 24 10:29:22 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:22 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:22 volumio volumio[898]: info: ControllerMpd::getState Feb 24 10:29:22 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status Feb 24 10:29:22 volumio volumio[898]: info: sendMpdCommand clear took 4 milliseconds Feb 24 10:29:22 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.1:8200/MediaItems/5280.flac" Feb 24 10:29:22 volumio volumio[898]: info: Feb 24 10:29:22 volumio volumio[898]: ---------------------------- MPD announces state update: player Feb 24 10:29:22 volumio volumio[898]: info: ControllerMpd::getState Feb 24 10:29:22 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status Feb 24 10:29:22 volumio volumio[898]: info: Feb 24 10:29:22 volumio volumio[898]: ---------------------------- MPD announces state update: player Feb 24 10:29:22 volumio volumio[898]: info: ControllerMpd::getState Feb 24 10:29:22 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status Feb 24 10:29:22 volumio volumio[898]: error: updateQueue error: null Feb 24 10:29:22 volumio volumio[898]: info: sendMpdCommand status took 10 milliseconds Feb 24 10:29:22 volumio volumio[898]: info: ------------------------------ 13ms Feb 24 10:29:22 volumio volumio[898]: verbose: ControllerMpd::parseState Feb 24 10:29:22 volumio volumio[898]: info: ControllerMpd::pushState Feb 24 10:29:22 volumio volumio[898]: info: CoreCommandRouter::servicePushState Feb 24 10:29:22 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:22 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:22 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:22 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:22 volumio volumio[898]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 24 10:29:22 volumio volumio[898]: verbose: CURRENT POSITION 0 Feb 24 10:29:22 volumio volumio[898]: info: CoreStateMachine::syncState stateService stop Feb 24 10:29:22 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus stop Feb 24 10:29:22 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:22 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:22 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:22 volumio volumio[898]: info: No code Feb 24 10:29:22 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:22 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:22 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:22 volumio volumio[898]: info: ------------------------------ 71ms Feb 24 10:29:24 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 10:29:24 volumio volumio[898]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Feb 24 10:29:24 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:29:24 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:25 volumio volumio[898]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Feb 24 10:29:26 volumio ntpd[861]: Soliciting pool server 92.63.177.245 Feb 24 10:29:26 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 24 10:29:26 volumio volumio[898]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 10:29:26 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 10:29:26 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:29:26 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:26 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 24 10:29:26 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 10:29:26 volumio volumio[898]: info: Received Get System Info Feb 24 10:29:26 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 10:29:26 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 10:29:26 volumio volumio[898]: info: Discovery: Getting this device information Feb 24 10:29:26 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:29:26 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:26 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 10:29:26 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:29:26 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:27 volumio volumio[898]: info: Listing playlists Feb 24 10:29:27 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 10:29:27 volumio volumio[898]: info: Received Get System Info Feb 24 10:29:27 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 10:29:27 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 10:29:27 volumio volumio[898]: info: Discovery: Getting this device information Feb 24 10:29:27 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:29:27 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:27 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 10:29:27 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:29:27 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:27 volumio ntpd[861]: Soliciting pool server 45.90.217.6 Feb 24 10:29:27 volumio volumio[898]: STREAMING PROXY: Starting server on port 3245 Feb 24 10:29:27 volumio volumio[898]: Node JS runtime: 14 Feb 24 10:29:27 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 24 10:29:27 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 24 10:29:29 volumio ntpd[861]: Soliciting pool server 195.90.182.235 Feb 24 10:29:29 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.1:8200/MediaItems/5280.flac" Feb 24 10:29:29 volumio volumio[898]: info: sendMpdCommand status took 6734 milliseconds Feb 24 10:29:29 volumio volumio[898]: info: sendMpdCommand status took 6732 milliseconds Feb 24 10:29:29 volumio volumio[898]: verbose: ControllerMpd::parseState Feb 24 10:29:29 volumio volumio[898]: info: ControllerMpd::pushState Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::servicePushState Feb 24 10:29:29 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:29 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:29 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:29 volumio volumio[898]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 24 10:29:29 volumio volumio[898]: verbose: CURRENT POSITION 0 Feb 24 10:29:29 volumio volumio[898]: info: CoreStateMachine::syncState stateService stop Feb 24 10:29:29 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus stop Feb 24 10:29:29 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:29 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:29 volumio volumio[898]: info: No code Feb 24 10:29:29 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:29 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:29 volumio volumio[898]: info: ControllerMpd::pushState Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::servicePushState Feb 24 10:29:29 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:29 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:29 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:29 volumio volumio[898]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 24 10:29:29 volumio volumio[898]: verbose: CURRENT POSITION 0 Feb 24 10:29:29 volumio volumio[898]: info: CoreStateMachine::syncState stateService stop Feb 24 10:29:29 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus stop Feb 24 10:29:29 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:29 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:29 volumio volumio[898]: info: No code Feb 24 10:29:29 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:29 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:29 volumio volumio[898]: info: ------------------------------ 6877ms Feb 24 10:29:29 volumio volumio[898]: info: ------------------------------ 6876ms Feb 24 10:29:29 volumio volumio[898]: info: Feb 24 10:29:29 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:29 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:29 volumio volumio[898]: info: Feb 24 10:29:29 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:29 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:29 volumio volumio[898]: info: Feb 24 10:29:29 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:29 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:29 volumio volumio[898]: info: sendMpdCommand add "http://192.168.1.1:8200/MediaItems/5280.flac" took 177 milliseconds Feb 24 10:29:29 volumio volumio[898]: info: ------------------------------ 32ms Feb 24 10:29:29 volumio volumio[898]: info: ------------------------------ 31ms Feb 24 10:29:29 volumio volumio[898]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 10:29:29 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand play Feb 24 10:29:29 volumio volumio[898]: info: Feb 24 10:29:29 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:29 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:29 volumio volumio[898]: info: Feb 24 10:29:29 volumio volumio[898]: ---------------------------- MPD announces system playlist update Feb 24 10:29:29 volumio volumio[898]: info: Ignoring MPD Status Update Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 10:29:29 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 10:29:29 volumio volumio[898]: info: ------------------------------ 156ms Feb 24 10:29:29 volumio volumio[898]: info: sendMpdCommand play took 151 milliseconds Feb 24 10:29:29 volumio volumio[898]: info: ------------------------------ 150ms Feb 24 10:29:29 volumio volumio[898]: info: ------------------------------ 127ms Feb 24 10:29:29 volumio ntpd[861]: Soliciting pool server 192.36.143.130 Feb 24 10:29:30 volumio ntpd[861]: Soliciting pool server 5.178.87.94 Feb 24 10:29:30 volumio ntpd[861]: Soliciting pool server 162.159.200.1 Feb 24 10:29:31 volumio volumio[898]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 24 10:29:31 volumio volumio[898]: info: Completed starting MyVolumio Plugin Feb 24 10:29:31 volumio volumio[898]: [Metrics] CommandRouter: 72s 718.20ms Feb 24 10:29:31 volumio volumio[898]: info: CoreCommandRouter::volumiosetStartupVolume Feb 24 10:29:31 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 10:29:31 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 10:29:31 volumio volumio[898]: info: CoreCommandRouter::Close All Modals sent Feb 24 10:29:31 volumio volumio[898]: info: CoreCommandRouter::Close All Modals sent Feb 24 10:29:31 volumio volumio[898]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 24 10:29:31 volumio volumio[898]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Feb 24 10:29:31 volumio volumio[898]: aplay: main:828: audio open error: No such device Feb 24 10:29:31 volumio volumio[898]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 10:29:31 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 24 10:29:32 volumio volumio[898]: info: Received Get System Version Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 24 10:29:32 volumio volumio-remote-updater[674]: No test mode Feb 24 10:29:32 volumio volumio-remote-updater[674]: No alpha test mode Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 24 10:29:32 volumio volumio[898]: info: Feb 24 10:29:32 volumio volumio[898]: ---------------------------- MPD announces state update: player Feb 24 10:29:32 volumio volumio[898]: info: ControllerMpd::getState Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status Feb 24 10:29:32 volumio volumio[898]: info: Feb 24 10:29:32 volumio volumio[898]: ---------------------------- MPD announces state update: player Feb 24 10:29:32 volumio volumio[898]: info: ControllerMpd::getState Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status Feb 24 10:29:32 volumio volumio[898]: info: Feb 24 10:29:32 volumio volumio[898]: ---------------------------- MPD announces state update: player Feb 24 10:29:32 volumio volumio[898]: info: ControllerMpd::getState Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status Feb 24 10:29:32 volumio volumio[898]: info: Feb 24 10:29:32 volumio volumio[898]: ---------------------------- MPD announces state update: player Feb 24 10:29:32 volumio volumio[898]: info: ControllerMpd::getState Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand status took 22 milliseconds Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand status took 22 milliseconds Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand status took 22 milliseconds Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::parseState Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::parseState Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::parseState Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 10:29:32 volumio volumio[898]: info: Feb 24 10:29:32 volumio volumio[898]: ---------------------------- MPD announces state update: player Feb 24 10:29:32 volumio volumio[898]: info: ControllerMpd::getState Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status Feb 24 10:29:32 volumio volumio[898]: info: Feb 24 10:29:32 volumio volumio[898]: ---------------------------- MPD announces state update: player Feb 24 10:29:32 volumio volumio[898]: info: ControllerMpd::getState Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand status Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand status took 60 milliseconds Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand clearerror took 49 milliseconds Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand clearerror took 47 milliseconds Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand clearerror took 46 milliseconds Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand playlistinfo took 30 milliseconds Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand playlistinfo took 29 milliseconds Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand playlistinfo took 28 milliseconds Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand status took 25 milliseconds Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand status took 23 milliseconds Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::parseState Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::parseTrackInfo Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::parseTrackInfo Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::parseTrackInfo Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::parseState Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::parseState Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 10:29:32 volumio volumio[898]: info: ControllerMpd::pushState Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::servicePushState Feb 24 10:29:32 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:32 volumio volumio[898]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5280.flac","artist":null,"album":null,"uri":"http://192.168.1.1:8200/MediaItems/5280.flac","trackType":"flac"} Feb 24 10:29:32 volumio volumio[898]: verbose: CURRENT POSITION 0 Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::syncState stateService pause Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus stop Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:32 volumio volumio[898]: info: ControllerMpd::pushState Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::servicePushState Feb 24 10:29:32 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:32 volumio volumio[898]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5280.flac","artist":null,"album":null,"uri":"http://192.168.1.1:8200/MediaItems/5280.flac","trackType":"flac"} Feb 24 10:29:32 volumio volumio[898]: verbose: CURRENT POSITION 0 Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::syncState stateService pause Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus stop Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:32 volumio volumio[898]: info: ControllerMpd::pushState Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::servicePushState Feb 24 10:29:32 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:32 volumio volumio[898]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5280.flac","artist":null,"album":null,"uri":"http://192.168.1.1:8200/MediaItems/5280.flac","trackType":"flac"} Feb 24 10:29:32 volumio volumio[898]: verbose: CURRENT POSITION 0 Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::syncState stateService pause Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus stop Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:32 volumio volumio[898]: info: ------------------------------ 167ms Feb 24 10:29:32 volumio volumio[898]: info: ------------------------------ 166ms Feb 24 10:29:32 volumio volumio[898]: info: ------------------------------ 165ms Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand clearerror took 120 milliseconds Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand playlistinfo took 113 milliseconds Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand playlistinfo took 107 milliseconds Feb 24 10:29:32 volumio volumio[898]: info: sendMpdCommand playlistinfo took 107 milliseconds Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::parseTrackInfo Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::parseTrackInfo Feb 24 10:29:32 volumio volumio[898]: verbose: ControllerMpd::parseTrackInfo Feb 24 10:29:32 volumio volumio[898]: info: ControllerMpd::pushState Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::servicePushState Feb 24 10:29:32 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:32 volumio volumio[898]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5280.flac","artist":null,"album":null,"uri":"http://192.168.1.1:8200/MediaItems/5280.flac","trackType":"flac"} Feb 24 10:29:32 volumio volumio[898]: verbose: CURRENT POSITION 0 Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::syncState stateService pause Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus stop Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:32 volumio volumio[898]: info: ControllerMpd::pushState Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::servicePushState Feb 24 10:29:32 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:32 volumio volumio[898]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5280.flac","artist":null,"album":null,"uri":"http://192.168.1.1:8200/MediaItems/5280.flac","trackType":"flac"} Feb 24 10:29:32 volumio volumio[898]: verbose: CURRENT POSITION 0 Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::syncState stateService pause Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus stop Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:32 volumio volumio[898]: info: ControllerMpd::pushState Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::servicePushState Feb 24 10:29:32 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Feb 24 10:29:32 volumio volumio[898]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5280.flac","artist":null,"album":null,"uri":"http://192.168.1.1:8200/MediaItems/5280.flac","trackType":"flac"} Feb 24 10:29:32 volumio volumio[898]: verbose: CURRENT POSITION 0 Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::syncState stateService pause Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::syncState currentStatus stop Feb 24 10:29:32 volumio volumio[898]: info: CoreStateMachine::pushState Feb 24 10:29:32 volumio volumio[898]: info: CoreCommandRouter::volumioPushState Feb 24 10:29:33 volumio volumio[898]: info: ------------------------------ 291ms Feb 24 10:29:33 volumio volumio[898]: info: ------------------------------ 254ms Feb 24 10:29:33 volumio volumio[898]: info: ------------------------------ 252ms Feb 24 10:29:33 volumio ntpd[861]: Soliciting pool server 5.23.48.22 Feb 24 10:29:33 volumio ntpd[861]: Soliciting pool server 193.192.36.3 Feb 24 10:29:34 volumio ntpd[861]: Soliciting pool server 94.141.168.72 Feb 24 10:29:34 volumio ntpd[861]: Soliciting pool server 5.188.119.216 Feb 24 10:29:34 volumio ntpd[861]: Soliciting pool server 195.218.227.230 Feb 24 10:29:35 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 10:29:36 volumio ntpd[861]: Soliciting pool server 95.182.120.195 Feb 24 10:29:36 volumio ntpd[861]: Soliciting pool server 95.79.30.113 Feb 24 10:29:36 volumio ntpd[861]: Soliciting pool server 151.0.2.54 Feb 24 10:29:37 volumio volumio[898]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 24 10:29:37 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 24 10:29:39 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e60.12a6553f does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xeb669e61.e9be92ea Feb 24 10:29:39 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e60.12a07897 does not match aorg 0000000000.00000000 from server@5.188.119.216 xmt 0xeb669e61.e9d28c92 Feb 24 10:29:39 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e60.12aa8abb does not match aorg 0000000000.00000000 from server@45.90.217.6 xmt 0xeb669e61.eb65175a Feb 24 10:29:39 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e60.12980db0 does not match aorg 0000000000.00000000 from server@94.141.168.72 xmt 0xeb669e61.eb309228 Feb 24 10:29:39 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e60.12a8a4f9 does not match aorg 0000000000.00000000 from server@192.36.143.130 xmt 0xeb669e61.eb38f80d Feb 24 10:29:39 volumio ntpd[861]: Soliciting pool server 2001:678:68c::3 Feb 24 10:29:39 volumio ntpd[861]: Soliciting pool server 51.250.110.169 Feb 24 10:29:39 volumio ntpd[861]: Soliciting pool server 217.69.139.133 Feb 24 10:29:40 volumio volumio[898]: info: BOOT COMPLETED Feb 24 10:29:40 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e61.1288a570 does not match aorg 0000000000.00000000 from server@5.178.87.94 xmt 0xeb669e62.ea9c28ea Feb 24 10:29:40 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e61.12916b9c does not match aorg 0000000000.00000000 from server@178.217.98.201 xmt 0xeb669e62.ed26fe64 Feb 24 10:29:41 volumio ntpd[861]: Soliciting pool server 212.113.99.6 Feb 24 10:29:44 volumio ntpd[861]: Soliciting pool server 188.124.37.90 Feb 24 10:29:48 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e69.c04e0ff8 does not match aorg 0xeb669e6b.c055f62a from server@217.69.139.133 xmt 0xeb669e69.ebc9230d Feb 24 10:29:49 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:29:49 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6a.c054aa01 does not match aorg 0xeb669e6c.c058a764 from server@95.182.120.195 xmt 0xeb669e6a.eb607c41 Feb 24 10:29:49 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6a.c04e59f8 does not match aorg 0xeb669e6c.c05664be from server@151.0.2.54 xmt 0xeb669e6a.eebdb893 Feb 24 10:29:50 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6b.c055f62a does not match aorg 0xeb669e6d.c04f9f5d from server@217.69.139.133 xmt 0xeb669e6b.eb5b18aa Feb 24 10:29:50 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6b.c0592beb does not match aorg 0xeb669e6d.c0570d6b from server@195.218.227.230 xmt 0xeb669e6b.eb99bed7 Feb 24 10:29:50 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6b.c057a0cd does not match aorg 0xeb669e6d.c0555e2b from server@95.79.30.113 xmt 0xeb669e6b.eb9a7c17 Feb 24 10:29:51 volumio volumio[898]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 10:29:51 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 24 10:29:51 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 24 10:29:51 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 24 10:29:51 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 24 10:29:51 volumio sudo[1985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 10:29:51 volumio sudo[1967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 24 10:29:51 volumio sudo[1963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 24 10:29:51 volumio sudo[1967]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 10:29:51 volumio sudo[1972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 24 10:29:51 volumio sudo[1983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 10:29:51 volumio sudo[1963]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 10:29:51 volumio sudo[1983]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 10:29:51 volumio sudo[1978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 24 10:29:51 volumio sudo[1985]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 10:29:51 volumio sudo[1978]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 10:29:51 volumio sudo[1972]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 10:29:52 volumio sudo[1983]: pam_unix(sudo:session): session closed for user root Feb 24 10:29:52 volumio sudo[1985]: pam_unix(sudo:session): session closed for user root Feb 24 10:29:52 volumio sudo[1972]: pam_unix(sudo:session): session closed for user root Feb 24 10:29:52 volumio sudo[1967]: pam_unix(sudo:session): session closed for user root Feb 24 10:29:52 volumio sudo[1978]: pam_unix(sudo:session): session closed for user root Feb 24 10:29:52 volumio sudo[1963]: pam_unix(sudo:session): session closed for user root Feb 24 10:29:52 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6c.c058a764 does not match aorg 0xeb669e6e.c05b45ab from server@95.182.120.195 xmt 0xeb669e6c.eca2fcef Feb 24 10:29:52 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6c.c04f5b45 does not match aorg 0xeb669e6e.c0527a44 from server@212.113.99.6 xmt 0xeb669e6c.ed856386 Feb 24 10:29:52 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6c.c05664be does not match aorg 0xeb669e6e.c0597f5f from server@151.0.2.54 xmt 0xeb669e6c.ef7ab6e0 Feb 24 10:29:52 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6d.c04f9f5d does not match aorg 0xeb669e6f.c04f7e77 from server@217.69.139.133 xmt 0xeb669e6d.eba32037 Feb 24 10:29:52 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6d.c0570d6b does not match aorg 0xeb669e6f.c0582931 from server@195.218.227.230 xmt 0xeb669e6d.ebd53e70 Feb 24 10:29:52 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6d.c0555e2b does not match aorg 0xeb669e6f.c05697cd from server@95.79.30.113 xmt 0xeb669e6d.ebef7c24 Feb 24 10:29:53 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6e.c05b45ab does not match aorg 0xeb669e70.c0597f09 from server@95.182.120.195 xmt 0xeb669e6e.ec685553 Feb 24 10:29:53 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6e.c0527a44 does not match aorg 0xeb669e70.c0564777 from server@212.113.99.6 xmt 0xeb669e6e.ecb9e99b Feb 24 10:29:53 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6e.c0597f5f does not match aorg 0xeb669e70.c057fa65 from server@151.0.2.54 xmt 0xeb669e6e.efbd60d4 Feb 24 10:29:55 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e70.c04f7251 does not match aorg 0xeb669e72.c04f7e61 from server@188.124.37.90 xmt 0xeb669e70.ea0742ee Feb 24 10:29:55 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e70.c0597f09 does not match aorg 0xeb669e72.c05b65ef from server@95.182.120.195 xmt 0xeb669e70.eb7e2c55 Feb 24 10:29:55 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e70.c0564777 does not match aorg 0xeb669e72.c0574bef from server@212.113.99.6 xmt 0xeb669e70.ebeb277a Feb 24 10:29:55 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6f.c0582931 does not match aorg 0xeb669e71.c05a4b6b from server@195.218.227.230 xmt 0xeb669e70.ebf781fa Feb 24 10:29:55 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e6f.c05697cd does not match aorg 0xeb669e71.c0584a5a from server@95.79.30.113 xmt 0xeb669e70.ec07570c Feb 24 10:29:55 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e70.c057fa65 does not match aorg 0xeb669e72.c0598c51 from server@151.0.2.54 xmt 0xeb669e70.eeda07b7 Feb 24 10:29:56 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e71.c05a4b6b does not match aorg 0xeb669e73.c05632d3 from server@195.218.227.230 xmt 0xeb669e71.eb47b12f Feb 24 10:29:56 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e71.c0584a5a does not match aorg 0xeb669e73.c04f9add from server@95.79.30.113 xmt 0xeb669e71.eb43d89c Feb 24 10:29:58 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e72.c04f7e61 does not match aorg 0xeb669e74.c04f783c from server@188.124.37.90 xmt 0xeb669e72.e9e0529a Feb 24 10:29:58 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e72.c0574bef does not match aorg 0xeb669e74.c055ccd2 from server@212.113.99.6 xmt 0xeb669e72.eb8ee625 Feb 24 10:29:58 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:30:00 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e74.c04f783c does not match aorg 0xeb669e76.c04fd651 from server@188.124.37.90 xmt 0xeb669e74.e9744680 Feb 24 10:30:01 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 10:30:02 volumio ntpd[861]: receive: Unexpected origin timestamp 0xeb669e76.c04fd651 does not match aorg 0xeb669e78.c04f7786 from server@188.124.37.90 xmt 0xeb669e76.e98021e2 Feb 24 10:30:03 volumio volumio[898]: info: CoreCommandRouter::Close All Modals sent Feb 24 10:30:07 volumio volumio[898]: info: Enabling plugin pirateaudio Feb 24 10:30:07 volumio volumio[898]: info: Loading plugin "pirateaudio"... Feb 24 10:30:07 volumio volumio[898]: info: PLUGIN START: pirateaudio Feb 24 10:30:07 volumio volumio[898]: info: Done. Feb 24 10:30:07 volumio sudo[2031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start pirateaudio.service Feb 24 10:30:07 volumio sudo[2031]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 10:30:08 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 24 10:30:08 volumio sudo[2031]: pam_unix(sudo:session): session closed for user root Feb 24 10:30:08 volumio volumio[898]: info: Pirate audio service (daemon) started Feb 24 10:30:09 volumio volumio[2033]: Traceback (most recent call last): Feb 24 10:30:09 volumio volumio[2033]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Feb 24 10:30:09 volumio volumio[2033]: import ST7789 # v0.0.6 Feb 24 10:30:09 volumio volumio[2033]: ModuleNotFoundError: No module named 'ST7789' Feb 24 10:30:09 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 24 10:30:09 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 24 10:30:09 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 24 10:30:09 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1. Feb 24 10:30:09 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 24 10:30:09 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 24 10:30:10 volumio volumio[2034]: Traceback (most recent call last): Feb 24 10:30:10 volumio volumio[2034]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Feb 24 10:30:10 volumio volumio[2034]: import ST7789 # v0.0.6 Feb 24 10:30:10 volumio volumio[2034]: ModuleNotFoundError: No module named 'ST7789' Feb 24 10:30:10 volumio volumio[898]: info: Enabling plugin now_playing Feb 24 10:30:10 volumio volumio[898]: info: Loading plugin "now_playing"... Feb 24 10:30:10 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 24 10:30:10 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 24 10:30:10 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 24 10:30:10 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 2. Feb 24 10:30:10 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 24 10:30:10 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 24 10:30:10 volumio volumio[898]: error: !!!! WARNING !!!! Feb 24 10:30:10 volumio volumio[898]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Feb 24 10:30:10 volumio volumio[898]: Require stack: Feb 24 10:30:10 volumio volumio[898]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 24 10:30:10 volumio volumio[898]: - /volumio/app/pluginmanager.js Feb 24 10:30:10 volumio volumio[898]: - /volumio/app/index.js Feb 24 10:30:10 volumio volumio[898]: - /volumio/index.js Feb 24 10:30:10 volumio volumio[898]: error: Stack trace: Error: Cannot find module 'geo-tz' Feb 24 10:30:10 volumio volumio[898]: Require stack: Feb 24 10:30:10 volumio volumio[898]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 24 10:30:10 volumio volumio[898]: - /volumio/app/pluginmanager.js Feb 24 10:30:10 volumio volumio[898]: - /volumio/app/index.js Feb 24 10:30:10 volumio volumio[898]: - /volumio/index.js Feb 24 10:30:10 volumio volumio[898]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) Feb 24 10:30:10 volumio volumio[898]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) Feb 24 10:30:10 volumio volumio[898]: at Module.require (internal/modules/cjs/loader.js:952:19) Feb 24 10:30:10 volumio volumio[898]: at require (internal/modules/cjs/helpers.js:88:18) Feb 24 10:30:10 volumio volumio[898]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Feb 24 10:30:10 volumio volumio[898]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Feb 24 10:30:10 volumio volumio[898]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Feb 24 10:30:10 volumio volumio[898]: at Module.load (internal/modules/cjs/loader.js:928:32) Feb 24 10:30:10 volumio volumio[898]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Feb 24 10:30:10 volumio volumio[898]: at Module.require (internal/modules/cjs/loader.js:952:19) Feb 24 10:30:10 volumio volumio[898]: at require (internal/modules/cjs/helpers.js:88:18) Feb 24 10:30:10 volumio volumio[898]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Feb 24 10:30:10 volumio volumio[898]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 24 10:30:10 volumio volumio[898]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 24 10:30:10 volumio volumio[898]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 24 10:30:10 volumio volumio[898]: error: !!!! WARNING !!!! Feb 24 10:30:10 volumio volumio[898]: info: Done. Feb 24 10:30:11 volumio volumio[2035]: Traceback (most recent call last): Feb 24 10:30:11 volumio volumio[2035]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Feb 24 10:30:11 volumio volumio[2035]: import ST7789 # v0.0.6 Feb 24 10:30:11 volumio volumio[2035]: ModuleNotFoundError: No module named 'ST7789' Feb 24 10:30:11 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 24 10:30:11 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 24 10:30:11 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 24 10:30:11 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 3. Feb 24 10:30:11 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 24 10:30:11 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 24 10:30:11 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 10:30:11 volumio volumio[898]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 24 10:30:11 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 24 10:30:11 volumio volumio[898]: error: Failed callmethod call: TypeError: Cannot read property 'broadcastMessage' of null Feb 24 10:30:11 volumio volumio[898]: info: Received Get System Version Feb 24 10:30:11 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 10:30:11 volumio volumio[898]: info: Received Get System Info Feb 24 10:30:11 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 10:30:11 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 10:30:11 volumio volumio[898]: info: Discovery: Getting this device information Feb 24 10:30:11 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:30:11 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 10:30:12 volumio volumio[2077]: Traceback (most recent call last): Feb 24 10:30:12 volumio volumio[2077]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Feb 24 10:30:12 volumio volumio[2077]: import ST7789 # v0.0.6 Feb 24 10:30:12 volumio volumio[2077]: ModuleNotFoundError: No module named 'ST7789' Feb 24 10:30:12 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 24 10:30:12 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 24 10:30:12 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 24 10:30:12 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 4. Feb 24 10:30:12 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 24 10:30:12 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 24 10:30:13 volumio volumio[2082]: Traceback (most recent call last): Feb 24 10:30:13 volumio volumio[2082]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Feb 24 10:30:13 volumio volumio[2082]: import ST7789 # v0.0.6 Feb 24 10:30:13 volumio volumio[2082]: ModuleNotFoundError: No module named 'ST7789' Feb 24 10:30:13 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 24 10:30:13 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 24 10:30:13 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 24 10:30:13 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 5. Feb 24 10:30:13 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 24 10:30:13 volumio systemd[1]: pirateaudio.service: Start request repeated too quickly. Feb 24 10:30:13 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 24 10:30:13 volumio systemd[1]: Failed to start Service for using pirate audio display in volumio. Feb 24 10:30:20 volumio volumio[898]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 10:30:24 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Feb 24 10:30:24 volumio volumio[898]: error: error Feb 24 10:30:26 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 10:30:27 volumio volumio[898]: info: CoreCommandRouter::Close All Modals sent Feb 24 10:30:27 volumio volumio[898]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 10:30:27 volumio volumio[898]: TypeError: Cannot read property 'broadcastMessage' of null Feb 24 10:30:27 volumio volumio[898]: at /volumio/app/index.js:1528:32 Feb 24 10:30:27 volumio volumio[898]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Feb 24 10:30:27 volumio volumio[898]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Feb 24 10:30:27 volumio volumio[898]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1526:13) Feb 24 10:30:27 volumio volumio[898]: at CoreCommandRouter.closeModals (/volumio/app/index.js:1948:15) Feb 24 10:30:27 volumio volumio[898]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1947:26) Feb 24 10:30:27 volumio volumio[898]: at Socket.emit (events.js:315:20) Feb 24 10:30:27 volumio volumio[898]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 24 10:30:27 volumio volumio[898]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 24 10:30:27 volumio volumio[898]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 10:30:28 volumio sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 10:29 Feb 24 10:30:28 volumio sudo[2134]: 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"