-- Logs begin at Thu 2025-05-15 18:38:02 MDT, end at Thu 2025-05-15 18:41:51 MDT. -- May 15 18:40:00 volumio lircd-setup[1285]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. May 15 18:40:00 volumio lircd-setup[1285]: parser = configparser.SafeConfigParser() May 15 18:40:00 volumio systemd[1]: lircd-setup.service: Succeeded. May 15 18:40:00 volumio systemd[1]: Started lircd(8) initialization helper tool. May 15 18:40:00 volumio systemd[1]: Starting Flexible IR remote input/output application support... May 15 18:40:00 volumio lircd-0.10.1[1286]: Info: lircd: Opening log, level: Info May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Version: lircd 0.10.1 May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: System info: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l GNU/Linux May 15 18:40:00 volumio lircd-0.10.1[1286]: Info: Initial device: /dev/lirc0 May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols May 15 18:40:00 volumio lircd-0.10.1[1286]: Info: Initial device: /dev/lirc0 May 15 18:40:00 volumio lircd-0.10.1[1286]: Info: [lirc] protocol is enabled May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: driver: default May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: output: /var/run/lirc/lircd May 15 18:40:00 volumio lircd[1286]: lircd-0.10.1[1286]: Info: lircd: Opening log, level: Info May 15 18:40:00 volumio lircd[1286]: lircd-0.10.1[1286]: Notice: Using systemd fd May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: nodaemon: 1 May 15 18:40:00 volumio lircd[1286]: lircd-0.10.1[1286]: Warning: Running as root May 15 18:40:00 volumio lircd[1286]: lircd-0.10.1[1286]: Info: Using remote: /home/volumio/A1294_lircd.conf. May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: logfile: syslog May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: immediate-init: 0 May 15 18:40:00 volumio lircd[1286]: lircd-0.10.1[1286]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: permission: 666 May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: driver-options: May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: pidfile: /var/run/lirc/lircd.pid May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: listen: 0 May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: connect: (null) May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: userelease: 0 May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: effective_user: (null) May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: release_suffix: _EVUP May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: allow_simulate: 0 May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: repeat_max: 600 May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: configfile: /etc/lirc/lircd.conf May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Options: dynamic_codes: (null) May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Current driver: default May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Driver API version: 3 May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Driver version: 0.10.0 May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html May 15 18:40:00 volumio lircd-0.10.1[1286]: Info: lircd: Opening log, level: Info May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: Using systemd fd May 15 18:40:00 volumio lircd-0.10.1[1286]: Warning: Running as root May 15 18:40:00 volumio lircd-0.10.1[1286]: Info: Using remote: /home/volumio/A1294_lircd.conf. May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 15 18:40:00 volumio systemd[1]: Started Flexible IR remote input/output application support. May 15 18:40:00 volumio sudo[1282]: pam_unix(sudo:session): session closed for user root May 15 18:40:00 volumio volumio[800]: info: ir_controller: systemctl restart lircd.service succeeded. May 15 18:40:00 volumio sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service May 15 18:40:00 volumio sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 18:40:00 volumio systemd[1]: Started Handle events from IR remotes decoded by lircd(8). May 15 18:40:00 volumio sudo[1297]: pam_unix(sudo:session): session closed for user root May 15 18:40:00 volumio lircd[1286]: lircd-0.10.1[1286]: Notice: accepted new client on /var/run/lirc/lircd May 15 18:40:00 volumio volumio[800]: info: ir_controller: systemctl restart irexec.service succeeded. May 15 18:40:00 volumio lircd-0.10.1[1286]: Notice: accepted new client on /var/run/lirc/lircd May 15 18:40:00 volumio lircd[1286]: lircd-0.10.1[1286]: Info: [lirc] protocol is enabled May 15 18:40:00 volumio lircd-0.10.1[1286]: Info: [lirc] protocol is enabled May 15 18:40:01 volumio volumio[800]: info: touch_display: Setting screensaver timeout to 120 seconds. May 15 18:40:01 volumio squeezeboxserver[1144]: [25-05-15 18:39:59.6628] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 - arm-linux-gnueabihf-thread-multi-64int May 15 18:40:01 volumio squeezeboxserver[1144]: [25-05-15 18:40:01.1674] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building May 15 18:40:01 volumio squeezeboxserver[1144]: [25-05-15 18:40:01.1678] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build May 15 18:40:01 volumio squeezeboxserver[1144]: [25-05-15 18:40:01.1682] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table May 15 18:40:01 volumio squeezeboxserver[1144]: [25-05-15 18:40:01.1716] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks May 15 18:40:01 volumio squeezeboxserver[1144]: [25-05-15 18:40:01.1728] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums May 15 18:40:01 volumio squeezeboxserver[1144]: [25-05-15 18:40:01.1735] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors May 15 18:40:01 volumio squeezeboxserver[1144]: [25-05-15 18:40:01.1751] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists May 15 18:40:01 volumio squeezeboxserver[1144]: [25-05-15 18:40:01.1797] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index May 15 18:40:01 volumio squeezeboxserver[1144]: [25-05-15 18:40:01.1819] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! May 15 18:40:01 volumio squeezeboxserver[1144]: [25-05-15 18:40:01.3376] main::checkDataSource (1107) Warning: Schema updated or no media found in the database, initiating scan. May 15 18:40:02 volumio squeezeboxserver[1144]: [25-05-15 18:40:02.7342] main::main (202) Starting Logitech Media Server scanner (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 May 15 18:40:03 volumio volumio[1095]: ......................................++++ May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.3972] Slim::Plugin::Deezer::Importer::isImportEnabled (42) Failed to get Deezer accounts: Can't connect to www.mysqueezebox.com:443 (No address associated with hostname) May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.4318] Slim::Music::Import::runImporter (579) Starting Slim::Media::MediaFolderScan scan May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.4323] Slim::Music::Import::endImporter (712) Completed Slim::Media::MediaFolderScan Scan in 0.001 seconds. May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.4339] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::WiMP::Importer scan May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.4901] Slim::Plugin::WiMP::Importer::startScan (44) Failed to get TIDAL accounts: Can't connect to www.mysqueezebox.com:443 (No address associated with hostname) May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.4908] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::ExtendedBrowseModes::Libraries scan May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.4912] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::ExtendedBrowseModes::Libraries Scan in 0.000 seconds. May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.4918] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::FullTextSearch::Plugin scan May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.4935] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.4939] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.4959] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks May 15 18:40:03 volumio volumio[800]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.5595] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.5608] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.5618] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.5669] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.5751] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.5758] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::FullTextSearch::Plugin Scan in 0.084 seconds. May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.5768] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup scan May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.5788] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup Scan in 0.002 seconds. May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.5807] Slim::Music::Artwork::precacheAllArtwork (657) Starting precacheArtwork for 0 albums May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.5813] Slim::Music::Import::endImporter (712) Completed precacheArtwork Scan in 0.001 seconds. May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.5821] Slim::Music::Import::runScanPostProcessing (481) Starting Database optimization. May 15 18:40:03 volumio squeezeboxserver[1144]: [25-05-15 18:40:03.7213] Slim::Music::Import::endImporter (712) Completed dbOptimize Scan in 0.139 seconds. May 15 18:40:04 volumio sudo[1185]: pam_unix(sudo:session): session closed for user root May 15 18:40:04 volumio volumio[800]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds May 15 18:40:04 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 15 18:40:04 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 15 18:40:08 volumio volumio[800]: info: Adding plugin bluetooth to MyMusic Plugins May 15 18:40:08 volumio volumio[800]: info: Adding plugin multiroom to MyMusic Plugins May 15 18:40:08 volumio volumio[800]: info: Adding plugin metavolumio to MyMusic Plugins May 15 18:40:08 volumio volumio[800]: info: Adding plugin cd_controller to MyMusic Plugins May 15 18:40:08 volumio volumio[800]: info: Adding plugin qobuzconnect to MyMusic Plugins May 15 18:40:08 volumio volumio[800]: info: Adding plugin smart_inputs to MyMusic Plugins May 15 18:40:08 volumio volumio[800]: info: Adding plugin tidalconnect to MyMusic Plugins May 15 18:40:08 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 15 18:40:09 volumio volumio[800]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 15 18:40:09 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 15 18:40:09 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:09 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:09 volumio volumio[800]: info: Starting MyVolumio Remote Streaming Endpoints May 15 18:40:09 volumio volumio[800]: info: MyVolumio login type: Token May 15 18:40:09 volumio volumio[800]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 15 18:40:09 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 15 18:40:10 volumio volumio[800]: info: Starting Streaming Service Transparent Proxy May 15 18:40:10 volumio volumio[800]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 15 18:40:10 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 15 18:40:10 volumio volumio[800]: info: Streaming services startup May 15 18:40:10 volumio volumio[800]: info: Starting Streaming Daemon May 15 18:40:10 volumio sudo[1510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 15 18:40:10 volumio sudo[1510]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 18:40:10 volumio sudo[1510]: pam_unix(sudo:session): session closed for user root May 15 18:40:10 volumio volumio[800]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 15 18:40:10 volumio sudo[1518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hanuman1967@list.ru,password=ganesha108,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.8.117/music /mnt/NAS/music May 15 18:40:10 volumio sudo[1518]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 18:40:10 volumio volumio[800]: error: Cannot start Volumio Streaming Daemon May 15 18:40:10 volumio volumio[800]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 15 18:40:10 volumio volumio[800]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 15 18:40:10 volumio kernel: CIFS: Attempting to mount //192.168.8.117/music May 15 18:40:11 volumio volumio[800]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 15 18:40:11 volumio volumio[800]: STREAMING PROXY: Starting server on port 3245 May 15 18:40:11 volumio volumio[800]: Node JS runtime: 14 May 15 18:40:12 volumio volumio[800]: info: MyVolumio token set successfully May 15 18:40:12 volumio volumio[800]: info: MYVOLUMIO: Adding device May 15 18:40:12 volumio volumio[800]: info: MYVOLUMIO: Evaluating Server May 15 18:40:12 volumio volumio[1095]: ..................................................................................................++++ May 15 18:40:12 volumio volumio[1095]: e is 65537 (0x010001) May 15 18:40:12 volumio volumio[1095]: writing RSA key May 15 18:40:12 volumio volumio[800]: info: MyVolumio status changed May 15 18:40:12 volumio volumio[800]: info: Streaming services startup May 15 18:40:12 volumio volumio[800]: info: Starting Streaming Daemon May 15 18:40:12 volumio volumio[800]: info: Removing browser output: myVolumio user plan is not superstar May 15 18:40:12 volumio volumio[800]: info: Removing audio output: May 15 18:40:12 volumio volumio[800]: info: Stoppping Tunnel 1 May 15 18:40:12 volumio sudo[1565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 15 18:40:12 volumio sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 18:40:12 volumio sudo[1565]: pam_unix(sudo:session): session closed for user root May 15 18:40:12 volumio sudo[1568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 15 18:40:12 volumio sudo[1568]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 18:40:12 volumio volumio[800]: error: Cannot start Volumio Streaming Daemon May 15 18:40:12 volumio volumio[800]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 15 18:40:12 volumio volumio[800]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 15 18:40:12 volumio sudo[1568]: pam_unix(sudo:session): session closed for user root May 15 18:40:12 volumio volumio[800]: info: Remote SSH Stopped May 15 18:40:12 volumio volumio[800]: info: Setting Geolocation for MyVolumio to us3 May 15 18:40:12 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:12 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:12 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:13 volumio volumio[800]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 15 18:40:13 volumio volumio[800]: 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 May 15 18:40:13 volumio volumio[800]: info: Updating MyVolumio device info May 15 18:40:13 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:13 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:13 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:14 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 15 18:40:14 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 15 18:40:14 volumio volumio[800]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 15 18:40:14 volumio volumio[800]: 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: 13 May 15 18:40:15 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 15 18:40:15 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 15 18:40:15 volumio volumio[800]: info: CoreCommandRouter::volumioGetVisibleSources May 15 18:40:15 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 18:40:15 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:15 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:15 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 15 18:40:15 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 15 18:40:15 volumio volumio[800]: info: Received Get System Info May 15 18:40:15 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 18:40:15 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 18:40:15 volumio volumio[800]: info: Discovery: Getting this device information May 15 18:40:15 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:15 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:15 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 18:40:15 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:15 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:15 volumio volumio[800]: info: Listing playlists May 15 18:40:15 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 15 18:40:15 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 18:40:16 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 18:40:16 volumio volumio[800]: info: Received Get System Info May 15 18:40:16 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 18:40:16 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 18:40:16 volumio volumio[800]: info: Discovery: Getting this device information May 15 18:40:16 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:16 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:16 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 18:40:16 volumio sudo[1518]: pam_unix(sudo:session): session closed for user root May 15 18:40:16 volumio volumio[800]: info: Cannot mount NAS music at system boot, trial number 3 ,retrying in 5 seconds May 15 18:40:16 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 15 18:40:16 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 15 18:40:17 volumio volumio[800]: info: MYVOLUMIO: Adding device May 15 18:40:17 volumio volumio[800]: info: MYVOLUMIO: Evaluating Server May 15 18:40:17 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 15 18:40:17 volumio volumio[800]: info: Setting Geolocation for MyVolumio to us3 May 15 18:40:17 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:17 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:17 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:18 volumio systemd[1]: systemd-timedated.service: Succeeded. May 15 18:40:18 volumio volumio[800]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 15 18:40:18 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 18:40:18 volumio volumio[800]: info: Received Get System Info May 15 18:40:18 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 18:40:18 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 18:40:18 volumio volumio[800]: info: Discovery: Getting this device information May 15 18:40:18 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:18 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:18 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 18:40:18 volumio volumio[800]: info: Updating MyVolumio device info May 15 18:40:18 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:18 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:18 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:19 volumio volumio[800]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 15 18:40:21 volumio sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hanuman1967@list.ru,password=ganesha108,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.8.117/music /mnt/NAS/music May 15 18:40:21 volumio sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 18:40:21 volumio kernel: CIFS: Attempting to mount //192.168.8.117/music May 15 18:40:24 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 18:40:24 volumio volumio[800]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 May 15 18:40:24 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:24 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:24 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 15 18:40:26 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 18:40:26 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:26 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 15 18:40:26 volumio volumio-remote-updater[630]: Test mode enabled May 15 18:40:26 volumio volumio-remote-updater[630]: No alpha test mode May 15 18:40:26 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 15 18:40:27 volumio volumio[800]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 15 18:40:27 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 15 18:40:28 volumio sudo[1779]: pam_unix(sudo:session): session closed for user root May 15 18:40:28 volumio volumio[800]: info: Cannot mount NAS music at system boot, trial number 4 ,retrying in 5 seconds May 15 18:40:28 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 15 18:40:28 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 15 18:40:29 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 18:40:29 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 18:40:29 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 15 18:40:29 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 15 18:40:29 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 15 18:40:29 volumio volumio[800]: info: CoreCommandRouter::volumioGetBrowseSources May 15 18:40:29 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 18:40:30 volumio volumio[800]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 15 18:40:30 volumio volumio[800]: info: Completed starting MyVolumio Plugin May 15 18:40:30 volumio volumio[800]: [Metrics] CommandRouter: 58s 137.07ms May 15 18:40:30 volumio volumio[800]: info: CoreCommandRouter::volumiosetStartupVolume May 15 18:40:30 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 18:40:30 volumio volumio[800]: info: VolumeController:: Setting startup Volume 20 May 15 18:40:30 volumio volumio[800]: info: VolumeController::SetAlsaVolume20 May 15 18:40:30 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 18:40:30 volumio volumio[800]: info: CoreCommandRouter::Close All Modals sent May 15 18:40:30 volumio volumio[800]: info: CoreCommandRouter::Close All Modals sent May 15 18:40:30 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:30 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:30 volumio volumio[800]: info: CoreStateMachine::pushState May 15 18:40:30 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:30 volumio volumio[800]: info: CoreCommandRouter::volumioPushState May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 18:40:31 volumio volumio[800]: info: Retrieving Cloud Streaming UI May 15 18:40:31 volumio volumio[800]: info: Getting Tidal Cloud Configuration May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 18:40:31 volumio volumio[800]: info: Getting Qobuz Cloud Configuration May 15 18:40:31 volumio volumio[800]: info: Asking plugin for UI Config May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 18:40:31 volumio volumio[800]: info: Getting Spotify Cloud Configuration May 15 18:40:31 volumio volumio[800]: info: Asking plugin for UI Config May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 18:40:31 volumio volumio[800]: info: Saving Spotify Acccount May 15 18:40:31 volumio volumio[800]: info: Got Tidal Cloud Configuration May 15 18:40:31 volumio volumio[800]: info: Got it May 15 18:40:31 volumio volumio[800]: info: Got it May 15 18:40:31 volumio volumio[800]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 18:40:31 volumio volumio[800]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 15 18:40:31 volumio volumio[800]: info: Version has changed, forcing UI Reload May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::Reload Ui May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::volumioGetBrowseSources May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::volumioGetBrowseSources May 15 18:40:31 volumio volumio[800]: info: CoreCommandRouter::volumioGetBrowseSources May 15 18:40:32 volumio sudo[1861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 18:40:32 volumio sudo[1861]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 18:40:32 volumio sudo[1861]: pam_unix(sudo:session): session closed for user root May 15 18:40:32 volumio sudo[1864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 18:40:32 volumio sudo[1864]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 18:40:32 volumio sudo[1864]: pam_unix(sudo:session): session closed for user root May 15 18:40:32 volumio volumio[800]: verbose: New Socket.io Connection to 192.168.8.149 from 192.168.8.206 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::volumioGetVisibleSources May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:32 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 15 18:40:32 volumio volumio[800]: info: Received Get System Info May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 18:40:32 volumio volumio[800]: info: Discovery: Getting this device information May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:32 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:32 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:32 volumio volumio[800]: info: Listing playlists May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 15 18:40:32 volumio volumio[800]: info: Received Get System Version May 15 18:40:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 18:40:33 volumio sudo[1881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hanuman1967@list.ru,password=ganesha108,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.8.117/music /mnt/NAS/music May 15 18:40:33 volumio sudo[1881]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 18:40:33 volumio kernel: CIFS: Attempting to mount //192.168.8.117/music May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 15 18:40:34 volumio volumio[800]: 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 May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 18:40:34 volumio volumio[800]: info: Received Get System Info May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 18:40:34 volumio volumio[800]: info: Discovery: Getting this device information May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:34 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::volumioGetVisibleSources May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:34 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 18:40:34 volumio volumio[800]: info: Retrieving Cloud Streaming UI May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 15 18:40:34 volumio volumio[800]: info: Received Get System Info May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 18:40:34 volumio volumio[800]: info: Discovery: Getting this device information May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:34 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:34 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:34 volumio volumio[800]: info: Listing playlists May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 15 18:40:34 volumio volumio[800]: info: Getting Tidal Cloud Configuration May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 18:40:34 volumio volumio[800]: info: Getting Qobuz Cloud Configuration May 15 18:40:34 volumio volumio[800]: info: Asking plugin for UI Config May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 18:40:34 volumio volumio[800]: info: Getting Spotify Cloud Configuration May 15 18:40:34 volumio volumio[800]: info: Asking plugin for UI Config May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 18:40:34 volumio volumio[800]: info: Saving Spotify Acccount May 15 18:40:34 volumio volumio[800]: info: Got Tidal Cloud Configuration May 15 18:40:34 volumio volumio[800]: info: Got it May 15 18:40:34 volumio volumio[800]: info: Got it May 15 18:40:34 volumio volumio[800]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 18:40:34 volumio volumio[800]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::volumioGetBrowseSources May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::volumioGetBrowseSources May 15 18:40:34 volumio volumio[800]: info: CoreCommandRouter::volumioGetBrowseSources May 15 18:40:35 volumio ntpd[755]: Soliciting pool server 198.50.127.72 May 15 18:40:35 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 18:40:35 volumio volumio[800]: info: Received Get System Info May 15 18:40:35 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 18:40:35 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 18:40:35 volumio volumio[800]: info: Discovery: Getting this device information May 15 18:40:35 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:35 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:35 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 18:40:35 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:40:35 volumio ntpd[755]: Soliciting pool server 216.128.178.20 May 15 18:40:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 15 18:40:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 18:40:36 volumio volumio[800]: info: Received Get System Info May 15 18:40:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 18:40:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 18:40:36 volumio volumio[800]: info: Discovery: Getting this device information May 15 18:40:36 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:36 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 18:40:36 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 May 15 18:40:36 volumio kernel: xhci_hcd 0000:01:00.0: @00000004295161a0 00000000 00000000 0e000000 02028000 May 15 18:40:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 15 18:40:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 18:40:36 volumio volumio[800]: info: Received Get System Info May 15 18:40:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 18:40:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 18:40:36 volumio volumio[800]: info: Discovery: Getting this device information May 15 18:40:36 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:36 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 18:40:36 volumio ntpd[755]: Soliciting pool server 51.161.35.150 May 15 18:40:37 volumio ntpd[755]: Soliciting pool server 208.81.1.244 May 15 18:40:37 volumio ntpd[755]: Soliciting pool server 206.108.0.131 May 15 18:40:37 volumio ntpd[755]: Soliciting pool server 209.227.143.22 May 15 18:40:37 volumio volumio[800]: info: BOOT COMPLETED May 15 18:40:37 volumio volumio[800]: info: mpdhttpout ---Boot completed detected! Patching mpd now! May 15 18:40:37 volumio ntpd[755]: Soliciting pool server 162.159.200.123 May 15 18:40:37 volumio ntpd[755]: Soliciting pool server 206.108.0.133 May 15 18:40:37 volumio ntpd[755]: Soliciting pool server 216.232.132.95 May 15 18:40:37 volumio ntpd[755]: Soliciting pool server 54.39.196.172 May 15 18:40:37 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:37 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:37 volumio volumio[800]: info: CoreCommandRouter::volumioPause May 15 18:40:37 volumio volumio[800]: info: CoreStateMachine::pause May 15 18:40:37 volumio volumio[800]: info: mpdhttpout --- Volumio set on pause May 15 18:40:37 volumio volumio[800]: info: mpdhttpout ---Configuration successfully added to mpd.conf. May 15 18:40:38 volumio ntpd[755]: Soliciting pool server 174.142.148.226 May 15 18:40:38 volumio ntpd[755]: Soliciting pool server 149.56.19.163 May 15 18:40:38 volumio ntpd[755]: Soliciting pool server 216.232.132.102 May 15 18:40:39 volumio ntpd[755]: Soliciting pool server 199.182.221.110 May 15 18:40:39 volumio sudo[1881]: pam_unix(sudo:session): session closed for user root May 15 18:40:39 volumio volumio[800]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 15 18:40:39 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 15 18:40:39 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 15 18:40:39 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:40:39 volumio ntpd[755]: Soliciting pool server 23.133.168.245 May 15 18:40:39 volumio ntpd[755]: Soliciting pool server 23.133.168.246 May 15 18:40:39 volumio ntpd[755]: Soliciting pool server 162.159.200.1 May 15 18:40:39 volumio ntpd[755]: Soliciting pool server 158.69.247.84 May 15 18:40:40 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 15 18:40:40 volumio sudo[1903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 18:40:40 volumio sudo[1903]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 18:40:40 volumio systemd[1]: Stopping Music Player Daemon... May 15 18:40:40 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:40 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:40 volumio systemd[1]: mpd.service: Succeeded. May 15 18:40:40 volumio systemd[1]: Stopped Music Player Daemon. May 15 18:40:40 volumio systemd[1]: Starting Music Player Daemon... May 15 18:40:40 volumio sudo[1905]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 15 18:40:40 volumio sudo[1905]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 18:40:40 volumio sudo[1905]: pam_unix(sudo:session): session closed for user root May 15 18:40:41 volumio mpd[1907]: May 15 18:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 18:40:41 volumio systemd[1]: Started Music Player Daemon. May 15 18:40:41 volumio sudo[1903]: pam_unix(sudo:session): session closed for user root May 15 18:40:41 volumio volumio[800]: error: updateQueue error: null May 15 18:40:42 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 15 18:40:44 volumio ntpd[755]: receive: Unexpected origin timestamp 0xebd1080a.f78e2ddc does not match aorg 0000000000.00000000 from server@174.142.148.226 xmt 0xebd1080c.259a7a88 May 15 18:40:44 volumio ntpd[755]: receive: Unexpected origin timestamp 0xebd1080a.f78d5956 does not match aorg 0000000000.00000000 from server@149.56.19.163 xmt 0xebd1080c.27038ad2 May 15 18:40:44 volumio ntpd[755]: receive: Unexpected origin timestamp 0xebd1080a.f78ffd1a does not match aorg 0000000000.00000000 from server@54.39.196.172 xmt 0xebd1080c.27ef538a May 15 18:40:45 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:40:45 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 15 18:40:46 volumio volumio[800]: info: Disabling MyMusic plugin airplay_emulation May 15 18:40:46 volumio volumio[800]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync May 15 18:40:46 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 18:40:46 volumio volumio[800]: Cannot find translation for source YouTube2 May 15 18:40:46 volumio volumio[800]: Cannot find translation for source YouTube Music May 15 18:40:46 volumio volumio[800]: Cannot find translation for source Radio Paradise May 15 18:40:46 volumio volumio[800]: info: Disabling plugin airplay_emulation May 15 18:40:46 volumio volumio[800]: info: Done. May 15 18:40:46 volumio sudo[1925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync May 15 18:40:46 volumio sudo[1925]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 18:40:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 18:40:46 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 18:40:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 18:40:46 volumio sudo[1925]: pam_unix(sudo:session): session closed for user root May 15 18:40:46 volumio volumio[800]: info: Shairport-Sync Stopped May 15 18:40:49 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:40:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 18:40:52 volumio volumio[800]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 15 18:40:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 15 18:40:52 volumio volumio[800]: info: Received Get System Version May 15 18:40:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 18:40:52 volumio volumio[800]: info: Received Get System Info May 15 18:40:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 18:40:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 18:40:52 volumio volumio[800]: info: Discovery: Getting this device information May 15 18:40:52 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:52 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 18:40:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 18:40:53 volumio volumio[800]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 15 18:40:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 15 18:40:53 volumio volumio[800]: info: Received Get System Version May 15 18:40:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 18:40:53 volumio volumio[800]: info: Received Get System Info May 15 18:40:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 18:40:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 18:40:53 volumio volumio[800]: info: Discovery: Getting this device information May 15 18:40:53 volumio volumio[800]: info: CoreCommandRouter::volumioGetState May 15 18:40:53 volumio volumio[800]: info: CorePlayQueue::getTrack 0 May 15 18:40:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 18:40:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:40:57 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:40:57 volumio volumio[800]: info: Enabling MyMusic plugin qobuzconnect May 15 18:40:57 volumio volumio[800]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service qobuzconnect May 15 18:40:57 volumio volumio[800]: info: [MyVolumio PluginManager] Plugin music_service qobuzconnect not in user plan, not enabling May 15 18:40:57 volumio volumio[800]: info: Error: Plugin music_service qobuzconnect not in user plan, not enabling May 15 18:40:57 volumio volumio[800]: error: Could not Enable MyMusic Plugin: Error May 15 18:41:01 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:41:05 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:41:09 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:41:13 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:41:17 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:41:21 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:41:25 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:41:29 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:41:33 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:41:37 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:41:41 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:41:45 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:41:49 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 18:41:49 volumio volumio[800]: info: Disabling MyMusic plugin upnp May 15 18:41:49 volumio sudo[2075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 15 18:41:49 volumio sudo[2075]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 18:41:49 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... May 15 18:41:49 volumio volumio[800]: error: Upnp client error: Error: This socket has been ended by the other party May 15 18:41:51 volumio volumio[800]: info: Enabling MyMusic plugin upnp May 15 18:41:51 volumio volumio[800]: info: Enabling plugin upnp May 15 18:41:51 volumio volumio[800]: info: Loading plugin "upnp"... May 15 18:41:51 volumio volumio[800]: info: [1747356111023] Starting Upmpd Daemon May 15 18:41:51 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 18:41:51 volumio volumio[800]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 18:41:51 volumio volumio[800]: Error: listen EADDRINUSE: address already in use :::6599 May 15 18:41:51 volumio volumio[800]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) May 15 18:41:51 volumio volumio[800]: at listenInCluster (net.js:1366:12) May 15 18:41:51 volumio volumio[800]: at Server.listen (net.js:1452:7) May 15 18:41:51 volumio volumio[800]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 15 18:41:51 volumio volumio[800]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 15 18:41:51 volumio volumio[800]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 15 18:41:51 volumio volumio[800]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 15 18:41:51 volumio volumio[800]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { May 15 18:41:51 volumio volumio[800]: code: 'EADDRINUSE', May 15 18:41:51 volumio volumio[800]: errno: -98, May 15 18:41:51 volumio volumio[800]: syscall: 'listen', May 15 18:41:51 volumio volumio[800]: address: '::', May 15 18:41:51 volumio volumio[800]: port: 6599 May 15 18:41:51 volumio volumio[800]: } May 15 18:41:51 volumio volumio[800]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 18:41:51 volumio sudo[2086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 18:40 May 15 18:41:51 volumio sudo[2086]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"