-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Sat 2025-04-19 11:00:32 MSK. -- Apr 19 11:00:01 volumio systemd-timedated[1588]: Changed local time to Sat Apr 19 11:00:01 2025 Apr 19 11:00:01 volumio volumio[1303]: info: Discovery: A device disappeared from network Apr 19 11:00:01 volumio volumio[1303]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Apr 19 11:00:01 volumio volumio-time-update[772]: volumio-time-update-util: System time updated successfully. Apr 19 11:00:01 volumio sudo[1572]: pam_unix(sudo:session): session closed for user root Apr 19 11:00:01 volumio systemd[1]: Starting Daily apt download activities... Apr 19 11:00:01 volumio systemd[1]: Starting Daily man-db regeneration... Apr 19 11:00:01 volumio systemd[1]: Started Volumio Time Update Utility. Apr 19 11:00:01 volumio systemd[1]: Reached target Multi-User System. Apr 19 11:00:01 volumio systemd[1]: Reached target Graphical Interface. Apr 19 11:00:01 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 19 11:00:01 volumio volumio[1303]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 19 11:00:01 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 19 11:00:01 volumio volumio[1303]: info: touch_display: X display number found: 0 Apr 19 11:00:01 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 19 11:00:01 volumio systemd[1]: man-db.service: Succeeded. Apr 19 11:00:01 volumio systemd[1]: Started Daily man-db regeneration. Apr 19 11:00:01 volumio volumio[1303]: info: CoreCommandRouter::volumioGetState Apr 19 11:00:01 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:01 volumio kernel: broken atomic modeset userspace detected, disabling atomic Apr 19 11:00:01 volumio startx[1567]: MESA-LOADER: failed to open drm-rp1-dsi (search paths /usr/lib/arm-linux-gnueabihf/dri:\$${ORIGIN}/dri:/usr/lib/dri) Apr 19 11:00:01 volumio startx[1567]: failed to load driver: drm-rp1-dsi Apr 19 11:00:01 volumio kernel: broken atomic modeset userspace detected, disabling atomic Apr 19 11:00:01 volumio startx[1567]: V3D 7.1 not supported by this version of Mesa. Apr 19 11:00:01 volumio kernel: broken atomic modeset userspace detected, disabling atomic Apr 19 11:00:01 volumio startx[1567]: (II) modeset(G0): Initializing kms color map for depth 24, 8 bpc. Apr 19 11:00:01 volumio startx[1567]: (II) modeset(G1): Initializing kms color map for depth 24, 8 bpc. Apr 19 11:00:01 volumio startx[1567]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Apr 19 11:00:01 volumio kernel: panel-simple 100000001.panel_disp: [drm:drm_panel_disable [drm]] failed to disable backlight: -121 Apr 19 11:00:01 volumio systemd[1]: apt-daily.service: Succeeded. Apr 19 11:00:01 volumio systemd[1]: Started Daily apt download activities. Apr 19 11:00:01 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Apr 19 11:00:01 volumio volumio[1303]: info: touch_display: Setting screensaver timeout to 3600 seconds. Apr 19 11:00:02 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Apr 19 11:00:02 volumio systemd[1]: Started Daily apt upgrade and clean activities. Apr 19 11:00:02 volumio volumio-remote-updater[784]: [2025-04-19 11:00:02] [connect] Successful connection Apr 19 11:00:02 volumio volumio-remote-updater[784]: [2025-04-19 11:00:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745049602 101 Apr 19 11:00:02 volumio volumio[1303]: 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 Apr 19 11:00:03 volumio sudo[1345]: pam_unix(sudo:session): session closed for user root Apr 19 11:00:03 volumio volumio[1303]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Apr 19 11:00:03 volumio volumio[1303]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 11:00:03 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 11:00:03 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 11:00:03 volumio volumio[1303]: info: CoreCommandRouter::volumioGetState Apr 19 11:00:03 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:03 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 19 11:00:03 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 19 11:00:03 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 19 11:00:03 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 11:00:04 volumio volumio[1303]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Apr 19 11:00:04 volumio volumio[1303]: info: CoreCommandRouter::volumioGetState Apr 19 11:00:04 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:04 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 11:00:04 volumio volumio[1303]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 11:00:04 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 11:00:04 volumio volumio[1303]: info: Listing playlists Apr 19 11:00:04 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 19 11:00:04 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 19 11:00:04 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 19 11:00:04 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 11:00:05 volumio volumio[1303]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 19 11:00:06 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 11:00:06 volumio volumio[1303]: info: Received Get System Info Apr 19 11:00:06 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 11:00:06 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 11:00:06 volumio volumio[1303]: info: Discovery: Getting this device information Apr 19 11:00:06 volumio volumio[1303]: info: CoreCommandRouter::volumioGetState Apr 19 11:00:06 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:06 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 11:00:07 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 19 11:00:07 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 11:00:07 volumio volumio[1303]: info: Received Get System Info Apr 19 11:00:07 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 11:00:07 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 11:00:07 volumio volumio[1303]: info: Discovery: Getting this device information Apr 19 11:00:07 volumio volumio[1303]: info: CoreCommandRouter::volumioGetState Apr 19 11:00:07 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:07 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 11:00:07 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 19 11:00:09 volumio sudo[2128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 11:00:09 volumio sudo[2128]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 11:00:09 volumio sudo[2128]: pam_unix(sudo:session): session closed for user root Apr 19 11:00:09 volumio sudo[2130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 11:00:09 volumio sudo[2130]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 11:00:09 volumio sudo[2130]: pam_unix(sudo:session): session closed for user root Apr 19 11:00:09 volumio sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 19 11:00:09 volumio sudo[2134]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 11:00:09 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 19 11:00:09 volumio sudo[2134]: pam_unix(sudo:session): session closed for user root Apr 19 11:00:09 volumio volumio[1303]: info: Upmpdcli Daemon Started Apr 19 11:00:09 volumio volumio[2136]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 19 11:00:09 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 19 11:00:09 volumio volumio[2136]: ............++++ Apr 19 11:00:10 volumio sh[745]: timed out Apr 19 11:00:10 volumio dhcpcd[853]: timed out Apr 19 11:00:10 volumio sh[745]: dhcpcd exited Apr 19 11:00:10 volumio dhcpcd[853]: dhcpcd exited Apr 19 11:00:10 volumio sh[745]: ifup: failed to bring up eth0 Apr 19 11:00:10 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 19 11:00:10 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 19 11:00:10 volumio volumio[1303]: info: Discovery: adding 31218363-247f-4e68-ae70-f11e65ff8c45 Apr 19 11:00:10 volumio volumio[1303]: info: Discovery: Found device Volumio Apr 19 11:00:10 volumio volumio[1303]: info: CoreCommandRouter::volumioGetState Apr 19 11:00:10 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:11 volumio volumio[1303]: info: Discovery: this is already registered, 31218363-247f-4e68-ae70-f11e65ff8c45 Apr 19 11:00:11 volumio volumio[1303]: info: Discovery: Found device Volumio Apr 19 11:00:11 volumio volumio[1303]: info: CoreCommandRouter::volumioGetState Apr 19 11:00:11 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 19 11:00:11 volumio volumio[1303]: info: Adding plugin bluetooth to MyMusic Plugins Apr 19 11:00:11 volumio volumio[1303]: info: Adding plugin multiroom to MyMusic Plugins Apr 19 11:00:11 volumio volumio[1303]: info: Adding plugin metavolumio to MyMusic Plugins Apr 19 11:00:11 volumio volumio[1303]: info: Adding plugin cd_controller to MyMusic Plugins Apr 19 11:00:11 volumio volumio[1303]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 19 11:00:11 volumio volumio[1303]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 19 11:00:11 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 19 11:00:11 volumio volumio[2136]: .................................++++ Apr 19 11:00:11 volumio volumio[2136]: e is 65537 (0x010001) Apr 19 11:00:11 volumio volumio[2136]: writing RSA key Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 19 11:00:11 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 11:00:11 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 11:00:11 volumio volumio[1303]: info: Starting MyVolumio Remote Streaming Endpoints Apr 19 11:00:11 volumio volumio[1303]: info: MyVolumio login type: Token Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 19 11:00:11 volumio volumio[1303]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 19 11:00:12 volumio volumio[1303]: info: Starting Streaming Service Transparent Proxy Apr 19 11:00:12 volumio volumio[1303]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 19 11:00:12 volumio volumio[1303]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 19 11:00:12 volumio volumio[1303]: info: Streaming services startup Apr 19 11:00:12 volumio volumio[1303]: info: Starting Streaming Daemon Apr 19 11:00:12 volumio sudo[2178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 19 11:00:12 volumio sudo[2178]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 11:00:12 volumio volumio[1303]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 19 11:00:12 volumio sudo[2178]: pam_unix(sudo:session): session closed for user root Apr 19 11:00:12 volumio volumio[1303]: error: Cannot start Volumio Streaming Daemon Apr 19 11:00:12 volumio volumio[1303]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 19 11:00:12 volumio volumio[1303]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 19 11:00:12 volumio volumio[1303]: STREAMING PROXY: Starting server on port 3245 Apr 19 11:00:12 volumio volumio[1303]: Node JS runtime: 14 Apr 19 11:00:12 volumio volumio[1303]: error: MyVolumio Custom Token format not valid, refreshing it Apr 19 11:00:13 volumio volumio[1303]: info: MyVolumio login type: Token Apr 19 11:00:13 volumio volumio[1303]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 19 11:00:14 volumio volumio[1303]: info: MyVolumio token set successfully Apr 19 11:00:14 volumio volumio[1303]: info: MYVOLUMIO: Adding device Apr 19 11:00:14 volumio volumio[1303]: info: MYVOLUMIO: Evaluating Server Apr 19 11:00:14 volumio volumio[1303]: info: MyVolumio status changed Apr 19 11:00:14 volumio volumio[1303]: info: Streaming services startup Apr 19 11:00:14 volumio volumio[1303]: info: Starting Streaming Daemon Apr 19 11:00:14 volumio volumio[1303]: info: Removing browser output: myVolumio user plan is not superstar Apr 19 11:00:14 volumio volumio[1303]: info: Removing audio output: Apr 19 11:00:14 volumio volumio[1303]: info: Stoppping Tunnel 1 Apr 19 11:00:14 volumio sudo[2213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 19 11:00:14 volumio sudo[2213]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 11:00:14 volumio sudo[2216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 19 11:00:14 volumio sudo[2216]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 11:00:14 volumio sudo[2213]: pam_unix(sudo:session): session closed for user root Apr 19 11:00:14 volumio volumio[1303]: error: Cannot start Volumio Streaming Daemon Apr 19 11:00:14 volumio volumio[1303]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 19 11:00:14 volumio volumio[1303]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 19 11:00:14 volumio sudo[2216]: pam_unix(sudo:session): session closed for user root Apr 19 11:00:14 volumio volumio[1303]: info: Remote SSH Stopped Apr 19 11:00:15 volumio volumio[1303]: info: Setting Geolocation for MyVolumio to eu10 Apr 19 11:00:15 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 11:00:15 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 11:00:15 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 11:00:16 volumio volumio[1303]: info: Updating MyVolumio device info Apr 19 11:00:16 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 11:00:16 volumio volumio[1303]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 19 11:00:16 volumio volumio[1303]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 19 11:00:20 volumio volumio[1303]: info: AutoStart - Plugin is starting Apr 19 11:00:20 volumio volumio[1303]: info: CoreCommandRouter::volumioGetQueue Apr 19 11:00:20 volumio volumio[1303]: info: CoreStateMachine::getQueue Apr 19 11:00:20 volumio volumio[1303]: info: CorePlayQueue::getQueue Apr 19 11:00:20 volumio volumio[1303]: info: AutoStart - start playing Apr 19 11:00:20 volumio volumio[1303]: info: AutoStart - start playing with no specific position Apr 19 11:00:20 volumio volumio[1303]: info: CoreCommandRouter::volumioPlay Apr 19 11:00:20 volumio volumio[1303]: info: CoreStateMachine::play index 0 Apr 19 11:00:20 volumio volumio[1303]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 11:00:20 volumio volumio[1303]: info: CoreStateMachine::stop Apr 19 11:00:20 volumio volumio[1303]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 11:00:20 volumio volumio[1303]: info: CoreStateMachine::play index undefined Apr 19 11:00:20 volumio volumio[1303]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 11:00:20 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:20 volumio volumio[1303]: info: CoreStateMachine::startPlaybackTimer Apr 19 11:00:20 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:20 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 11:00:20 volumio volumio[1303]: info: sendMpdCommand stop took 1 milliseconds Apr 19 11:00:20 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 11:00:20 volumio volumio[1303]: info: Apr 19 11:00:20 volumio volumio[1303]: ---------------------------- MPD announces system playlist update Apr 19 11:00:20 volumio volumio[1303]: info: Ignoring MPD Status Update Apr 19 11:00:20 volumio volumio[1303]: info: sendMpdCommand clear took 1 milliseconds Apr 19 11:00:20 volumio volumio[1303]: info: Apr 19 11:00:20 volumio volumio[1303]: ---------------------------- MPD announces system playlist update Apr 19 11:00:20 volumio volumio[1303]: info: Ignoring MPD Status Update Apr 19 11:00:20 volumio volumio[1303]: info: Apr 19 11:00:20 volumio volumio[1303]: ---------------------------- MPD announces system playlist update Apr 19 11:00:20 volumio volumio[1303]: info: Ignoring MPD Status Update Apr 19 11:00:20 volumio volumio[1303]: error: updateQueue error: null Apr 19 11:00:20 volumio volumio[1303]: error: updateQueue error: null Apr 19 11:00:20 volumio volumio[1303]: error: updateQueue error: null Apr 19 11:00:20 volumio volumio[1303]: info: ------------------------------ 6ms Apr 19 11:00:20 volumio volumio[1303]: info: ------------------------------ 2ms Apr 19 11:00:20 volumio volumio[1303]: info: ------------------------------ 1ms Apr 19 11:00:20 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-6.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D45b418037aefaf7c83a45d31213775b6edff26cfa69fa6ea8bfccf343875a863%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68115c1a%2F0%2F234643%2F46e921b1.198336008.6.134227846%2F320.mp3&ext=.mp3" Apr 19 11:00:20 volumio volumio[1303]: info: Apr 19 11:00:20 volumio volumio[1303]: ---------------------------- MPD announces system playlist update Apr 19 11:00:20 volumio volumio[1303]: info: Ignoring MPD Status Update Apr 19 11:00:20 volumio volumio[1303]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-6.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D45b418037aefaf7c83a45d31213775b6edff26cfa69fa6ea8bfccf343875a863%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68115c1a%2F0%2F234643%2F46e921b1.198336008.6.134227846%2F320.mp3&ext=.mp3" took 1 milliseconds Apr 19 11:00:20 volumio volumio[1303]: verbose: MPD COMMAND [object Object] Apr 19 11:00:20 volumio volumio[1303]: verbose: MPD COMMAND [object Object] Apr 19 11:00:20 volumio volumio[1303]: verbose: MPD COMMAND [object Object] Apr 19 11:00:20 volumio volumio[1303]: info: Apr 19 11:00:20 volumio volumio[1303]: ---------------------------- MPD announces system playlist update Apr 19 11:00:20 volumio volumio[1303]: info: Ignoring MPD Status Update Apr 19 11:00:20 volumio volumio[1303]: info: Apr 19 11:00:20 volumio volumio[1303]: ---------------------------- MPD announces system playlist update Apr 19 11:00:20 volumio volumio[1303]: info: Ignoring MPD Status Update Apr 19 11:00:20 volumio volumio[1303]: info: Apr 19 11:00:20 volumio volumio[1303]: ---------------------------- MPD announces system playlist update Apr 19 11:00:20 volumio volumio[1303]: info: Ignoring MPD Status Update Apr 19 11:00:20 volumio volumio[1303]: info: ------------------------------ 3ms Apr 19 11:00:20 volumio volumio[1303]: info: ------------------------------ 1ms Apr 19 11:00:20 volumio volumio[1303]: info: ------------------------------ 1ms Apr 19 11:00:20 volumio volumio[1303]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 11:00:20 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand play Apr 19 11:00:20 volumio volumio[1303]: info: Apr 19 11:00:20 volumio volumio[1303]: ---------------------------- MPD announces system playlist update Apr 19 11:00:20 volumio volumio[1303]: info: Ignoring MPD Status Update Apr 19 11:00:20 volumio volumio[1303]: info: Apr 19 11:00:20 volumio volumio[1303]: ---------------------------- MPD announces system playlist update Apr 19 11:00:20 volumio volumio[1303]: info: Ignoring MPD Status Update Apr 19 11:00:20 volumio volumio[1303]: info: ------------------------------ 2ms Apr 19 11:00:20 volumio volumio[1303]: info: sendMpdCommand play took 2 milliseconds Apr 19 11:00:20 volumio volumio[1303]: info: ------------------------------ 1ms Apr 19 11:00:20 volumio volumio[1303]: info: ------------------------------ 1ms Apr 19 11:00:20 volumio volumio[1303]: info: MYVOLUMIO: Adding device Apr 19 11:00:20 volumio volumio[1303]: info: MYVOLUMIO: Evaluating Server Apr 19 11:00:21 volumio volumio[1303]: info: Setting Geolocation for MyVolumio to eu10 Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 11:00:21 volumio volumio[1303]: info: Apr 19 11:00:21 volumio volumio[1303]: ---------------------------- MPD announces state update: player Apr 19 11:00:21 volumio volumio[1303]: info: ControllerMpd::getState Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand status Apr 19 11:00:21 volumio volumio[1303]: info: Apr 19 11:00:21 volumio volumio[1303]: ---------------------------- MPD announces state update: player Apr 19 11:00:21 volumio volumio[1303]: info: ControllerMpd::getState Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand status Apr 19 11:00:21 volumio volumio[1303]: info: Apr 19 11:00:21 volumio volumio[1303]: ---------------------------- MPD announces state update: player Apr 19 11:00:21 volumio volumio[1303]: info: ControllerMpd::getState Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand status Apr 19 11:00:21 volumio volumio[1303]: info: sendMpdCommand status took 24 milliseconds Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::parseState Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 11:00:21 volumio volumio[1303]: info: Apr 19 11:00:21 volumio volumio[1303]: ---------------------------- MPD announces state update: player Apr 19 11:00:21 volumio volumio[1303]: info: ControllerMpd::getState Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand status Apr 19 11:00:21 volumio volumio[1303]: info: sendMpdCommand status took 25 milliseconds Apr 19 11:00:21 volumio volumio[1303]: info: sendMpdCommand status took 24 milliseconds Apr 19 11:00:21 volumio volumio[1303]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::parseState Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::parseState Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::parseTrackInfo Apr 19 11:00:21 volumio volumio[1303]: info: ControllerMpd::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::servicePushState Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:21 volumio volumio[1303]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Dusk","artist":"Ziggan","album":"The Dusk","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-6.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D45b418037aefaf7c83a45d31213775b6edff26cfa69fa6ea8bfccf343875a863%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68115c1a%2F0%2F234643%2F46e921b1.198336008.6.134227846%2F320.mp3&ext=.mp3","trackType":"mp3"} Apr 19 11:00:21 volumio volumio[1303]: verbose: CURRENT POSITION 0 Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::syncState stateService play Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::syncState currentStatus stop Apr 19 11:00:21 volumio volumio[1303]: info: ------------------------------ 29ms Apr 19 11:00:21 volumio volumio[1303]: info: Apr 19 11:00:21 volumio volumio[1303]: ---------------------------- MPD announces state update: player Apr 19 11:00:21 volumio volumio[1303]: info: ControllerMpd::getState Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand status Apr 19 11:00:21 volumio volumio[1303]: info: Apr 19 11:00:21 volumio volumio[1303]: ---------------------------- MPD announces state update: player Apr 19 11:00:21 volumio volumio[1303]: info: ControllerMpd::getState Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand status Apr 19 11:00:21 volumio volumio[1303]: info: sendMpdCommand status took 4 milliseconds Apr 19 11:00:21 volumio volumio[1303]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 19 11:00:21 volumio volumio[1303]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 19 11:00:21 volumio volumio[1303]: info: sendMpdCommand status took 1 milliseconds Apr 19 11:00:21 volumio volumio[1303]: info: sendMpdCommand status took 1 milliseconds Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::parseState Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::parseTrackInfo Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::parseTrackInfo Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::parseState Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::parseState Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 11:00:21 volumio volumio[1303]: info: ControllerMpd::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::servicePushState Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:21 volumio volumio[1303]: verbose: STATE SERVICE {"status":"play","position":0,"seek":908,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Dusk","artist":"Ziggan","album":"The Dusk","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-6.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D45b418037aefaf7c83a45d31213775b6edff26cfa69fa6ea8bfccf343875a863%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68115c1a%2F0%2F234643%2F46e921b1.198336008.6.134227846%2F320.mp3&ext=.mp3","trackType":"mp3"} Apr 19 11:00:21 volumio volumio[1303]: verbose: CURRENT POSITION 0 Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::syncState stateService play Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::syncState currentStatus play Apr 19 11:00:21 volumio volumio[1303]: info: Received an update from plugin. extracting info from payload Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioPushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioPushState Apr 19 11:00:21 volumio volumio[1303]: info: ControllerMpd::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::servicePushState Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:21 volumio volumio[1303]: verbose: STATE SERVICE {"status":"play","position":0,"seek":908,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Dusk","artist":"Ziggan","album":"The Dusk","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-6.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D45b418037aefaf7c83a45d31213775b6edff26cfa69fa6ea8bfccf343875a863%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68115c1a%2F0%2F234643%2F46e921b1.198336008.6.134227846%2F320.mp3&ext=.mp3","trackType":"mp3"} Apr 19 11:00:21 volumio volumio[1303]: verbose: CURRENT POSITION 0 Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::syncState stateService play Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::syncState currentStatus play Apr 19 11:00:21 volumio volumio[1303]: info: Received an update from plugin. extracting info from payload Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioPushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioPushState Apr 19 11:00:21 volumio volumio[1303]: info: ------------------------------ 41ms Apr 19 11:00:21 volumio volumio[1303]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 19 11:00:21 volumio volumio[1303]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 19 11:00:21 volumio volumio[1303]: verbose: ControllerMpd::parseTrackInfo Apr 19 11:00:21 volumio volumio[1303]: info: ControllerMpd::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::servicePushState Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:21 volumio volumio[1303]: verbose: STATE SERVICE {"status":"play","position":0,"seek":908,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Dusk","artist":"Ziggan","album":"The Dusk","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-6.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D45b418037aefaf7c83a45d31213775b6edff26cfa69fa6ea8bfccf343875a863%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68115c1a%2F0%2F234643%2F46e921b1.198336008.6.134227846%2F320.mp3&ext=.mp3","trackType":"mp3"} Apr 19 11:00:21 volumio volumio[1303]: verbose: CURRENT POSITION 0 Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::syncState stateService play Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::syncState currentStatus play Apr 19 11:00:21 volumio volumio[1303]: info: Received an update from plugin. extracting info from payload Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioPushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioPushState Apr 19 11:00:21 volumio volumio[1303]: info: ControllerMpd::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::servicePushState Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:21 volumio volumio[1303]: verbose: STATE SERVICE {"status":"play","position":0,"seek":908,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Dusk","artist":"Ziggan","album":"The Dusk","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-6.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D45b418037aefaf7c83a45d31213775b6edff26cfa69fa6ea8bfccf343875a863%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68115c1a%2F0%2F234643%2F46e921b1.198336008.6.134227846%2F320.mp3&ext=.mp3","trackType":"mp3"} Apr 19 11:00:21 volumio volumio[1303]: verbose: CURRENT POSITION 0 Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::syncState stateService play Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::syncState currentStatus play Apr 19 11:00:21 volumio volumio[1303]: info: Received an update from plugin. extracting info from payload Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioPushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioPushState Apr 19 11:00:21 volumio volumio[1303]: info: ControllerMpd::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::servicePushState Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getTrack 0 Apr 19 11:00:21 volumio volumio[1303]: verbose: STATE SERVICE {"status":"play","position":0,"seek":908,"duration":265,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Dusk","artist":"Ziggan","album":"The Dusk","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-6.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D45b418037aefaf7c83a45d31213775b6edff26cfa69fa6ea8bfccf343875a863%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68115c1a%2F0%2F234643%2F46e921b1.198336008.6.134227846%2F320.mp3&ext=.mp3","trackType":"mp3"} Apr 19 11:00:21 volumio volumio[1303]: verbose: CURRENT POSITION 0 Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::syncState stateService play Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::syncState currentStatus play Apr 19 11:00:21 volumio volumio[1303]: info: Received an update from plugin. extracting info from payload Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioPushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::pushState Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioPushState Apr 19 11:00:21 volumio volumio[1303]: info: ------------------------------ 33ms Apr 19 11:00:21 volumio volumio[1303]: info: ------------------------------ 30ms Apr 19 11:00:21 volumio volumio[1303]: info: ------------------------------ 32ms Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioGetQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioGetQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioGetQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioGetQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioGetQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioGetQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioGetQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioGetQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioGetQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreCommandRouter::volumioGetQueue Apr 19 11:00:21 volumio volumio[1303]: info: CoreStateMachine::getQueue Apr 19 11:00:21 volumio volumio[1303]: info: CorePlayQueue::getQueue Apr 19 11:00:21 volumio dhcpcd[1208]: wlan0: carrier lost Apr 19 11:00:21 volumio wpa_supplicant[1196]: wlan0: Associated with 52:ff:20:8c:00:c5 Apr 19 11:00:21 volumio wpa_supplicant[1196]: wlan0: CTRL-EVENT-CONNECTED - Connection to 52:ff:20:8c:00:c5 completed [id=0 id_str=] Apr 19 11:00:21 volumio wpa_supplicant[1196]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 19 11:00:21 volumio avahi-daemon[786]: Withdrawing address record for 192.168.1.41 on wlan0. Apr 19 11:00:21 volumio avahi-daemon[786]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.41. Apr 19 11:00:21 volumio dhcpcd[1208]: wlan0: deleting route to 192.168.1.0/24 Apr 19 11:00:21 volumio dhcpcd[1208]: wlan0: deleting default route via 192.168.1.1 Apr 19 11:00:21 volumio avahi-daemon[786]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 19 11:00:21 volumio volumio[1303]: info: Discovery: A device disappeared from network Apr 19 11:00:21 volumio volumio[1303]: info: Discovery: Device volumio disappeared from network Apr 19 11:00:21 volumio dhcpcd[1208]: wlan0: carrier acquired Apr 19 11:00:21 volumio dhcpcd[1208]: wlan0: IAID 67:66:5f:20 Apr 19 11:00:22 volumio volumio[1303]: info: Updating MyVolumio device info Apr 19 11:00:22 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 11:00:22 volumio volumio[1303]: error: Failed to update MyVolumio device: undefined Apr 19 11:00:22 volumio dhcpcd[1208]: wlan0: soliciting a DHCP lease Apr 19 11:00:22 volumio dhcpcd[1208]: wlan0: offered 192.168.1.41 from 192.168.1.1 Apr 19 11:00:22 volumio dhcpcd[1208]: wlan0: soliciting an IPv6 router Apr 19 11:00:22 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 11:00:22 volumio volumio[1303]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 19 11:00:22 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 19 11:00:22 volumio volumio[1303]: info: Received Get System Version Apr 19 11:00:22 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 11:00:22 volumio volumio[1303]: info: Received Get System Info Apr 19 11:00:22 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 11:00:22 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 11:00:22 volumio volumio[1303]: info: Discovery: Getting this device information Apr 19 11:00:22 volumio volumio[1303]: info: CoreCommandRouter::volumioGetState Apr 19 11:00:22 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 11:00:22 volumio dhcpcd[1208]: wlan0: probing address 192.168.1.41/24 Apr 19 11:00:23 volumio ntpd[985]: Deleting interface #3 wlan0, 192.168.1.41#123, interface stats: received=0, sent=0, dropped=0, active_time=24 secs Apr 19 11:00:26 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 11:00:26 volumio volumio[1303]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 19 11:00:26 volumio volumio[1303]: info: CoreCommandRouter::volumioGetState Apr 19 11:00:27 volumio dhcpcd[1208]: wlan0: leased 192.168.1.41 for 25200 seconds Apr 19 11:00:27 volumio avahi-daemon[786]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.41. Apr 19 11:00:27 volumio avahi-daemon[786]: New relevant interface wlan0.IPv4 for mDNS. Apr 19 11:00:27 volumio dhcpcd[1208]: wlan0: adding route to 192.168.1.0/24 Apr 19 11:00:27 volumio avahi-daemon[786]: Registering new address record for 192.168.1.41 on wlan0.IPv4. Apr 19 11:00:27 volumio dhcpcd[1208]: wlan0: adding default route via 192.168.1.1 Apr 19 11:00:29 volumio ntpd[985]: Listen normally on 4 wlan0 192.168.1.41:123 Apr 19 11:00:29 volumio ntpd[985]: new interface(s) found: waking up resolver Apr 19 11:00:30 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 11:00:30 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 11:00:30 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 11:00:30 volumio volumio-remote-updater[784]: No test mode Apr 19 11:00:30 volumio volumio-remote-updater[784]: No alpha test mode Apr 19 11:00:30 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 19 11:00:30 volumio volumio[1303]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES


NEW ADDITIONS

 

 

","title":"Update v3.799","updateavailable":true} Apr 19 11:00:30 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 19 11:00:30 volumio volumio[1303]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 19 11:00:31 volumio systemd[1]: systemd-timedated.service: Succeeded. Apr 19 11:00:31 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 11:00:31 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 11:00:31 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 19 11:00:31 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 19 11:00:31 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 19 11:00:31 volumio volumio[1303]: info: CoreCommandRouter::volumioGetBrowseSources Apr 19 11:00:31 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 11:00:32 volumio volumio[1303]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 19 11:00:32 volumio volumio[1303]: info: Completed starting MyVolumio Plugin Apr 19 11:00:32 volumio volumio[1303]: [Metrics] CommandRouter: 33s 653.22ms Apr 19 11:00:32 volumio volumio[1303]: info: CoreCommandRouter::volumiosetStartupVolume Apr 19 11:00:32 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 11:00:32 volumio volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 11:00:32 volumio volumio[1303]: info: CoreCommandRouter::Close All Modals sent Apr 19 11:00:32 volumio volumio[1303]: info: CoreCommandRouter::Close All Modals sent Apr 19 11:00:32 volumio volumio[1303]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Apr 19 11:00:32 volumio volumio[1303]: aplay: main:828: audio open error: Device or resource busy Apr 19 11:00:32 volumio volumio[1303]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 11:00:32 volumio volumio[1303]: Error: read ECONNRESET Apr 19 11:00:32 volumio volumio[1303]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Apr 19 11:00:32 volumio volumio[1303]: errno: -104, Apr 19 11:00:32 volumio volumio[1303]: code: 'ECONNRESET', Apr 19 11:00:32 volumio volumio[1303]: syscall: 'read' Apr 19 11:00:32 volumio volumio[1303]: } Apr 19 11:00:32 volumio volumio[1303]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 11:00:32 volumio sudo[2380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 10:59 Apr 19 11:00:32 volumio sudo[2380]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"