-- Logs begin at Fri 2024-10-04 08:47:34 EEST, end at Wed 2024-11-27 14:14:53 EET. -- Nov 27 14:13:00 pioneer volumio-remote-updater[720]: [2024-11-27 14:13:00] [connect] Successful connection Nov 27 14:13:00 pioneer volumio[10018]: info: MYVOLUMIO Environment detected Nov 27 14:13:00 pioneer volumio[10018]: info: Plugin folders cleanup Nov 27 14:13:00 pioneer volumio[10018]: info: Scanning into folder /volumio/app/plugins/ Nov 27 14:13:00 pioneer volumio[10018]: info: Scanning category audio_interface Nov 27 14:13:00 pioneer volumio[10018]: info: Scanning category miscellanea Nov 27 14:13:00 pioneer volumio[10018]: info: Scanning category music_service Nov 27 14:13:00 pioneer volumio[10018]: info: Scanning category plugins.json Nov 27 14:13:00 pioneer volumio[10018]: info: Scanning category system_controller Nov 27 14:13:00 pioneer volumio[10018]: info: Scanning category user_interface Nov 27 14:13:00 pioneer volumio[10018]: info: Scanning into folder /data/plugins/ Nov 27 14:13:00 pioneer volumio[10018]: info: Scanning category music_service Nov 27 14:13:00 pioneer volumio[10018]: info: Scanning category system_controller Nov 27 14:13:00 pioneer volumio[10018]: info: Plugin folders cleanup completed Nov 27 14:13:00 pioneer volumio[10018]: info: ------------------------------------------- Nov 27 14:13:00 pioneer volumio[10018]: info: ----- Core plugins startup ---- Nov 27 14:13:00 pioneer volumio[10018]: info: ------------------------------------------- Nov 27 14:13:00 pioneer volumio[10018]: info: Loading plugins from folder /volumio/app/plugins/ Nov 27 14:13:00 pioneer volumio[10018]: info: Adding plugin upnp to MyMusic Plugins Nov 27 14:13:00 pioneer volumio[10018]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 27 14:13:00 pioneer volumio[10018]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 27 14:13:00 pioneer volumio[10018]: info: Loading plugins from folder /data/plugins/ Nov 27 14:13:00 pioneer volumio[10018]: info: Loading plugin "system"... Nov 27 14:13:00 pioneer volumio[10018]: info: Loading plugin "appearance"... Nov 27 14:13:02 pioneer volumio[10018]: info: Loading plugin "network"... Nov 27 14:13:02 pioneer volumio[10018]: info: Refreshing Cached IP Addresses Nov 27 14:13:03 pioneer volumio[10018]: info: Loading plugin "services"... Nov 27 14:13:03 pioneer sudo[10048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 27 14:13:03 pioneer sudo[10048]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:03 pioneer volumio[10018]: info: Loading plugin "alsa_controller"... Nov 27 14:13:03 pioneer sudo[10050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 27 14:13:03 pioneer sudo[10050]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:03 pioneer sudo[10048]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:03 pioneer sudo[10050]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:03 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:13:03 pioneer volumio[10018]: info: Loading plugin "wizard"... Nov 27 14:13:03 pioneer volumio[10018]: info: Loading plugin "networkfs"... Nov 27 14:13:03 pioneer volumio[10018]: info: Starting Udev Watcher for removable devices Nov 27 14:13:03 pioneer volumio[10018]: info: Ignoring mount for partition: boot Nov 27 14:13:03 pioneer volumio[10018]: info: Ignoring mount for partition: volumio Nov 27 14:13:03 pioneer volumio[10018]: info: Ignoring mount for partition: volumio_data Nov 27 14:13:03 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:13:03 pioneer volumio[10018]: info: Loading plugin "volumio_command_line_client"... Nov 27 14:13:03 pioneer volumio[10018]: info: Loading plugin "upnp"... Nov 27 14:13:03 pioneer volumio[10018]: info: [1732709583363] Starting Upmpd Daemon Nov 27 14:13:03 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:13:03 pioneer volumio[10018]: info: Loading plugin "my_music"... Nov 27 14:13:03 pioneer volumio[10018]: info: Loading plugin "mpd"... Nov 27 14:13:04 pioneer volumio[10018]: info: Loading plugin "upnp_browser"... Nov 27 14:13:04 pioneer volumio[10018]: info: Loading plugin "alarm-clock"... Nov 27 14:13:05 pioneer volumio[10018]: info: Loading plugin "airplay_emulation"... Nov 27 14:13:05 pioneer volumio[10018]: info: Starting Shairport Sync Nov 27 14:13:05 pioneer volumio[10018]: info: Loading plugin "last_100"... Nov 27 14:13:05 pioneer volumio[10018]: info: Loading plugin "webradio"... Nov 27 14:13:05 pioneer volumio[10018]: info: Loading plugin "i2s_dacs"... Nov 27 14:13:05 pioneer volumio[10018]: info: I2S DAC not set, start Auto-detection Nov 27 14:13:05 pioneer volumio[10018]: info: Loading plugin "volumiodiscovery"... Nov 27 14:13:05 pioneer volumio[10018]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 14:13:05 pioneer volumio[10018]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:13:05 pioneer volumio[10018]: *** WARNING *** For more information see Nov 27 14:13:05 pioneer node[10018]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 14:13:05 pioneer volumio[10018]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 14:13:05 pioneer volumio[10018]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:13:05 pioneer volumio[10018]: *** WARNING *** For more information see Nov 27 14:13:05 pioneer node[10018]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:13:05 pioneer node[10018]: *** WARNING *** For more information see Nov 27 14:13:05 pioneer node[10018]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 14:13:05 pioneer node[10018]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:13:05 pioneer node[10018]: *** WARNING *** For more information see Nov 27 14:13:05 pioneer volumio[10018]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 27 14:13:05 pioneer volumio[10018]: info: Discovery: Started advertising with name: Pioneer Nov 27 14:13:05 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:13:05 pioneer volumio[10018]: info: Loading plugin "squeezelite_mc"... Nov 27 14:13:07 pioneer volumio[10018]: info: Loading plugin "autostart"... Nov 27 14:13:07 pioneer volumio[10018]: info: Applying required configuration parameters for plugin autostart Nov 27 14:13:07 pioneer volumio[10018]: info: AutoStart - onVolumioStart - read config.json Nov 27 14:13:07 pioneer volumio[10018]: info: Loading plugin "outputs"... Nov 27 14:13:07 pioneer volumio[10018]: info: Loading plugin "albumart"... Nov 27 14:13:07 pioneer volumio[10018]: info: Plugin example_plugin is not enabled Nov 27 14:13:07 pioneer volumio[10018]: info: Loading plugin "inputs"... Nov 27 14:13:07 pioneer volumio[10018]: info: Loading plugin "updater_comm"... Nov 27 14:13:07 pioneer volumio[10018]: info: Plugin mpdemulation is not enabled Nov 27 14:13:07 pioneer volumio[10018]: info: Loading plugin "rest_api"... Nov 27 14:13:07 pioneer volumio[10018]: info: Loading plugin "websocket"... Nov 27 14:13:07 pioneer volumio[10018]: info: Starting Socket.io Server version 2.3.0 Nov 27 14:13:07 pioneer volumio[10018]: info: Loading plugin "lms"... Nov 27 14:13:08 pioneer volumio[10018]: info: Loading i18n strings for locale ru Nov 27 14:13:08 pioneer volumio[10018]: Forking 3 albumart workers Nov 27 14:13:08 pioneer volumio[10018]: Updating browse sources language Nov 27 14:13:08 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:13:08 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:13:08 pioneer volumio[10018]: info: CoreCommandRouter::initPlayerControls Nov 27 14:13:08 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:08 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:08 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:08 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:08 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:08 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:08 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:08 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:08 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:13:08 pioneer volumio[10018]: Express server listening on port 3000 Nov 27 14:13:08 pioneer volumio[10018]: [Metrics] WebUI: 10s 860.31ms Nov 27 14:13:08 pioneer volumio[10018]: info: CoreStateMachine::resetVolumioState Nov 27 14:13:08 pioneer volumio[10018]: info: CoreStateMachine::getcurrentVolume Nov 27 14:13:08 pioneer volumio[10018]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:13:08 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:13:08 pioneer volumio[10018]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 27 14:13:08 pioneer volumio[10018]: info: Completed loading Core Plugins Nov 27 14:13:08 pioneer volumio[10018]: info: Preparing to generate the ALSA configuration file Nov 27 14:13:09 pioneer volumio[10018]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 27 14:13:09 pioneer volumio[10018]: info: Reading ALSA contributions from plugins. Nov 27 14:13:09 pioneer kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Nov 27 14:13:09 pioneer dhcpcd[1064]: eth0: carrier acquired Nov 27 14:13:09 pioneer dhcpcd[1064]: eth0: IAID e5:44:e1:ba Nov 27 14:13:09 pioneer volumio[10018]: info: CoreStateMachine::pushState Nov 27 14:13:09 pioneer volumio[10018]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:09 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 14:13:09 pioneer volumio[10018]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:09 pioneer volumio[10018]: info: CoreStateMachine::updateTrackBlock Nov 27 14:13:09 pioneer volumio[10018]: info: CorePlayQueue::getTrackBlock Nov 27 14:13:09 pioneer volumio[10018]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:13:09 pioneer volumio[10018]: info: Reloading queue from file Nov 27 14:13:09 pioneer volumio[10018]: info: CoreStateMachine::setRepeat null single undefined Nov 27 14:13:09 pioneer volumio[10018]: info: CoreStateMachine::pushState Nov 27 14:13:09 pioneer volumio[10018]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:09 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 14:13:09 pioneer volumio[10018]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:09 pioneer volumio[10018]: info: CoreStateMachine::setRandom null Nov 27 14:13:09 pioneer volumio[10018]: info: CoreStateMachine::pushState Nov 27 14:13:09 pioneer volumio[10018]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:09 pioneer volumio[10018]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:09 pioneer volumio[10018]: info: CoreStateMachine::pushState Nov 27 14:13:09 pioneer volumio[10018]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:09 pioneer volumio[10018]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:09 pioneer volumio[10018]: info: Asound.conf file unchanged, so no further update is needed Nov 27 14:13:09 pioneer volumio[10018]: info: Output device has changed, restarting MPD Nov 27 14:13:09 pioneer volumio[10018]: info: Output device has changed, restarting Shairport Sync Nov 27 14:13:09 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:09 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:13:09 pioneer ifplugd(eth0)[970]: Link beat detected. Nov 27 14:13:09 pioneer sudo[10109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 14:13:09 pioneer sudo[10109]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:09 pioneer sudo[10111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 14:13:09 pioneer sudo[10111]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:09 pioneer sudo[10109]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:09 pioneer volumio[10018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:13:09 pioneer volumio[10018]: info: ___________ START PLUGINS ___________ Nov 27 14:13:09 pioneer ifplugd(eth0)[970]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Nov 27 14:13:09 pioneer dhcpcd[1064]: eth0: rebinding lease of 192.168.0.27 Nov 27 14:13:09 pioneer volumio[10018]: info: ControllerMpd::onStart: Initializing MPD Nov 27 14:13:09 pioneer volumio[10018]: info: Creating MPD Configuration file Nov 27 14:13:09 pioneer systemd[1]: Stopping Music Player Daemon... Nov 27 14:13:09 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:13:09 pioneer volumio[10018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:13:09 pioneer volumio[10018]: info: [1732709589890] CoreMusicLibrary::Adding element Медиа-серверы Nov 27 14:13:09 pioneer sudo[10123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 14:13:09 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:13:09 pioneer sudo[10123]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:09 pioneer sudo[10123]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:09 pioneer sudo[10132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 14:13:09 pioneer sudo[10132]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:10 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:10 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:13:10 pioneer systemd[1]: mpd.service: Succeeded. Nov 27 14:13:10 pioneer systemd[1]: Stopped Music Player Daemon. Nov 27 14:13:10 pioneer dhcpcd[1064]: eth0: soliciting an IPv6 router Nov 27 14:13:10 pioneer ifplugd(eth0)[970]: client: command failed: No such device (-19) Nov 27 14:13:10 pioneer systemd[1]: Starting Music Player Daemon... Nov 27 14:13:10 pioneer volumio[10018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:13:10 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:10 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:13:10 pioneer ifplugd(eth0)[970]: client: sending commands to master dhcpcd process Nov 27 14:13:10 pioneer dhcpcd[10151]: sending commands to master dhcpcd process Nov 27 14:13:10 pioneer dhcpcd[792]: control command: /sbin/dhcpcd eth0 Nov 27 14:13:10 pioneer dhcpcd[792]: eth0: carrier acquired Nov 27 14:13:10 pioneer volumio[10018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:13:10 pioneer volumio[10018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:13:10 pioneer volumio[10018]: info: [1732709590283] CoreMusicLibrary::Adding element Last_100 Nov 27 14:13:10 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:13:10 pioneer volumio[10018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:13:10 pioneer volumio[10018]: info: [1732709590301] CoreMusicLibrary::Adding element Webradio Nov 27 14:13:10 pioneer dhcpcd[792]: eth0: IAID e5:44:e1:ba Nov 27 14:13:10 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:13:10 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:13:10 pioneer volumio[10018]: info: Initializing BBC Radios Nov 27 14:13:10 pioneer sudo[10144]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 14:13:10 pioneer sudo[10144]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:10 pioneer sudo[10144]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:10 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:13:10 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:13:10 pioneer dhcpcd[792]: eth0: soliciting an IPv6 router Nov 27 14:13:10 pioneer volumio[10018]: info: [squeezelite_mc] Starting proxy server... Nov 27 14:13:10 pioneer volumio[10018]: info: AutoStart - onStart Nov 27 14:13:10 pioneer volumio[10018]: Starting albumart workers Nov 27 14:13:10 pioneer ifplugd(eth0)[970]: Program executed successfully. Nov 27 14:13:10 pioneer volumio[10018]: info: Volumio Calling Home Nov 27 14:13:10 pioneer sudo[10193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 27 14:13:10 pioneer sudo[10193]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:10 pioneer volumio[10018]: Starting albumart workers Nov 27 14:13:10 pioneer volumio[10018]: Starting albumart workers Nov 27 14:13:10 pioneer systemd[1]: Starting Logitech Media Server Daemon... Nov 27 14:13:11 pioneer systemd[1]: Started Logitech Media Server Daemon. Nov 27 14:13:11 pioneer sudo[10193]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:11 pioneer systemd[10200]: logitechmediaserver.service: Failed to execute command: No such file or directory Nov 27 14:13:11 pioneer systemd[10200]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Nov 27 14:13:11 pioneer systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Nov 27 14:13:11 pioneer systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Nov 27 14:13:11 pioneer dhcpcd[792]: eth0: rebinding lease of 192.168.0.27 Nov 27 14:13:11 pioneer volumio[10018]: info: [squeezelite_mc] Proxy server started on port 42959 Nov 27 14:13:11 pioneer volumio[10018]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:13:11 pioneer volumio[10018]: Unhandled rejection Error: No sockets available, cannot start. Nov 27 14:13:11 pioneer volumio[10018]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 27 14:13:11 pioneer volumio[10018]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 27 14:13:11 pioneer volumio[10018]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 27 14:13:11 pioneer volumio[10018]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 27 14:13:11 pioneer volumio[10018]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 27 14:13:11 pioneer volumio[10018]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 27 14:13:11 pioneer volumio[10018]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 27 14:13:11 pioneer volumio[10018]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 27 14:13:11 pioneer volumio[10018]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 27 14:13:11 pioneer volumio[10018]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 27 14:13:11 pioneer volumio[10018]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 27 14:13:11 pioneer volumio[10018]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 27 14:13:11 pioneer volumio[10018]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 27 14:13:11 pioneer volumio[10018]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 27 14:13:11 pioneer volumio[10018]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 27 14:13:11 pioneer volumio[10018]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 27 14:13:11 pioneer volumio[10018]: info: logitechmediaserver started Nov 27 14:13:11 pioneer volumio[10018]: info: MPD Permissions set Nov 27 14:13:11 pioneer volumio[10018]: info: MPD Permissions set Nov 27 14:13:11 pioneer volumio[10018]: info: CoreStateMachine::pushState Nov 27 14:13:11 pioneer volumio[10018]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:11 pioneer volumio[10018]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:11 pioneer volumio[10018]: info: [squeezelite_mc] Server discovery started Nov 27 14:13:12 pioneer volumio[10018]: info: [squeezelite_mc] Player finder started Nov 27 14:13:12 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:12 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:12 pioneer volumio[10018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:12 pioneer volumio[10018]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 27 14:13:12 pioneer volumio[10018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 14:13:12 pioneer volumio[10018]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 27 14:13:12 pioneer volumio[10018]: at doSend (dgram.js:714:16) Nov 27 14:13:12 pioneer volumio[10018]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Nov 27 14:13:12 pioneer volumio[10018]: at afterDns (dgram.js:660:5) Nov 27 14:13:12 pioneer volumio[10018]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Nov 27 14:13:12 pioneer volumio[10018]: errno: -101, Nov 27 14:13:12 pioneer volumio[10018]: code: 'ENETUNREACH', Nov 27 14:13:12 pioneer volumio[10018]: syscall: 'send', Nov 27 14:13:12 pioneer volumio[10018]: address: '255.255.255.255', Nov 27 14:13:12 pioneer volumio[10018]: port: 3483 Nov 27 14:13:12 pioneer volumio[10018]: } Nov 27 14:13:12 pioneer volumio[10018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 14:13:12 pioneer volumio-remote-updater[720]: [2024-11-27 14:13:12] [connect] Successful connection Nov 27 14:13:13 pioneer sudo[10242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 14:12 Nov 27 14:13:13 pioneer sudo[10242]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:13 pioneer sudo[10242]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:13 pioneer systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 27 14:13:13 pioneer volumio-remote-updater[720]: [2024-11-27 14:13:13] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Nov 27 14:13:13 pioneer volumio-remote-updater[720]: [2024-11-27 14:13:13] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Nov 27 14:13:13 pioneer volumio-remote-updater[720]: [2024-11-27 14:13:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 27 14:13:13 pioneer sudo[10111]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:13 pioneer sudo[10132]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:13 pioneer systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 27 14:13:13 pioneer systemd[1]: Started dynamicswap service. Nov 27 14:13:13 pioneer systemd[1]: dynamicswap.service: Succeeded. Nov 27 14:13:14 pioneer systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 27 14:13:14 pioneer systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Nov 27 14:13:14 pioneer systemd[1]: Stopped Volumio Backend Module. Nov 27 14:13:14 pioneer systemd[1]: Started Volumio Backend Module. Nov 27 14:13:14 pioneer systemd[1]: Started dynamicswap service. Nov 27 14:13:14 pioneer systemd[1]: dynamicswap.service: Succeeded. Nov 27 14:13:14 pioneer mpd[10181]: Nov 27 14:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 27 14:13:14 pioneer systemd[1]: Started Music Player Daemon. Nov 27 14:13:14 pioneer dhcpcd[1064]: eth0: probing address 192.168.0.27/24 Nov 27 14:13:15 pioneer dhcpcd[792]: eth0: probing address 192.168.0.27/24 Nov 27 14:13:17 pioneer volumio[10255]: info: ------------------------------------------- Nov 27 14:13:17 pioneer volumio[10255]: info: ----- Volumio3 ---- Nov 27 14:13:17 pioneer volumio[10255]: info: ------------------------------------------- Nov 27 14:13:17 pioneer volumio[10255]: info: ----- System startup ---- Nov 27 14:13:17 pioneer volumio[10255]: info: ------------------------------------------- Nov 27 14:13:18 pioneer volumio[10255]: info: MYVOLUMIO Environment detected Nov 27 14:13:18 pioneer volumio[10255]: info: Plugin folders cleanup Nov 27 14:13:18 pioneer volumio[10255]: info: Scanning into folder /volumio/app/plugins/ Nov 27 14:13:18 pioneer volumio[10255]: info: Scanning category audio_interface Nov 27 14:13:18 pioneer volumio[10255]: info: Scanning category miscellanea Nov 27 14:13:18 pioneer volumio[10255]: info: Scanning category music_service Nov 27 14:13:18 pioneer volumio[10255]: info: Scanning category plugins.json Nov 27 14:13:18 pioneer volumio[10255]: info: Scanning category system_controller Nov 27 14:13:18 pioneer volumio[10255]: info: Scanning category user_interface Nov 27 14:13:18 pioneer volumio[10255]: info: Scanning into folder /data/plugins/ Nov 27 14:13:18 pioneer volumio[10255]: info: Scanning category music_service Nov 27 14:13:18 pioneer volumio[10255]: info: Scanning category system_controller Nov 27 14:13:18 pioneer volumio[10255]: info: Plugin folders cleanup completed Nov 27 14:13:18 pioneer volumio[10255]: info: ------------------------------------------- Nov 27 14:13:18 pioneer volumio[10255]: info: ----- Core plugins startup ---- Nov 27 14:13:18 pioneer volumio[10255]: info: ------------------------------------------- Nov 27 14:13:18 pioneer volumio[10255]: info: Loading plugins from folder /volumio/app/plugins/ Nov 27 14:13:18 pioneer volumio[10255]: info: Adding plugin upnp to MyMusic Plugins Nov 27 14:13:18 pioneer volumio[10255]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 27 14:13:18 pioneer volumio[10255]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 27 14:13:18 pioneer volumio[10255]: info: Loading plugins from folder /data/plugins/ Nov 27 14:13:18 pioneer volumio[10255]: info: Loading plugin "system"... Nov 27 14:13:19 pioneer volumio[10255]: info: Loading plugin "appearance"... Nov 27 14:13:19 pioneer dhcpcd[1064]: eth0: leased 192.168.0.27 for 259200 seconds Nov 27 14:13:19 pioneer dhcpcd[1064]: eth0: adding route to 192.168.0.0/24 Nov 27 14:13:19 pioneer dhcpcd[1064]: eth0: adding default route via 192.168.0.1 Nov 27 14:13:19 pioneer avahi-daemon[733]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.27. Nov 27 14:13:19 pioneer avahi-daemon[733]: New relevant interface eth0.IPv4 for mDNS. Nov 27 14:13:19 pioneer avahi-daemon[733]: Registering new address record for 192.168.0.27 on eth0.IPv4. Nov 27 14:13:20 pioneer ntpd[26996]: ntpd exiting on signal 15 (Terminated) Nov 27 14:13:20 pioneer systemd[1]: Stopping Network Time Service... Nov 27 14:13:20 pioneer systemd[1]: ntp.service: Succeeded. Nov 27 14:13:20 pioneer systemd[1]: Stopped Network Time Service. Nov 27 14:13:20 pioneer systemd[1]: Starting Network Time Service... Nov 27 14:13:20 pioneer ntpd[10316]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 27 14:13:20 pioneer ntpd[10316]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 27 14:13:20 pioneer systemd[1]: Started Network Time Service. Nov 27 14:13:20 pioneer ntpd[10322]: proto: precision = 0.958 usec (-20) Nov 27 14:13:20 pioneer ntpd[10322]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 27 14:13:20 pioneer ntpd[10322]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 27 14:13:20 pioneer ntpd[10322]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 701 days ago Nov 27 14:13:20 pioneer ntpd[10322]: Listen and drop on 0 v6wildcard [::]:123 Nov 27 14:13:20 pioneer ntpd[10322]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 27 14:13:20 pioneer ntpd[10322]: Listen normally on 2 lo 127.0.0.1:123 Nov 27 14:13:20 pioneer ntpd[10322]: Listen normally on 3 eth0 192.168.0.27:123 Nov 27 14:13:20 pioneer ntpd[10322]: Listening on routing socket on fd #20 for interface updates Nov 27 14:13:20 pioneer ntpd[10322]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 27 14:13:20 pioneer ntpd[10322]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 27 14:13:20 pioneer dhcpcd[792]: eth0: leased 192.168.0.27 for 259200 seconds Nov 27 14:13:20 pioneer dhcpcd[792]: eth0: adding route to 192.168.0.0/24 Nov 27 14:13:20 pioneer dhcpcd[792]: eth0: adding default route via 192.168.0.1 Nov 27 14:13:20 pioneer ntpd[10322]: ntpd exiting on signal 15 (Terminated) Nov 27 14:13:20 pioneer systemd[1]: Stopping Network Time Service... Nov 27 14:13:20 pioneer systemd[1]: ntp.service: Succeeded. Nov 27 14:13:20 pioneer systemd[1]: Stopped Network Time Service. Nov 27 14:13:20 pioneer systemd[1]: Starting Network Time Service... Nov 27 14:13:20 pioneer ntpd[10355]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 27 14:13:20 pioneer ntpd[10355]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 27 14:13:20 pioneer systemd[1]: Started Network Time Service. Nov 27 14:13:20 pioneer ntpd[10362]: proto: precision = 1.042 usec (-20) Nov 27 14:13:20 pioneer ntpd[10362]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 27 14:13:20 pioneer ntpd[10362]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 27 14:13:20 pioneer ntpd[10362]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 701 days ago Nov 27 14:13:20 pioneer ntpd[10362]: Listen and drop on 0 v6wildcard [::]:123 Nov 27 14:13:20 pioneer ntpd[10362]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 27 14:13:20 pioneer ntpd[10362]: Listen normally on 2 lo 127.0.0.1:123 Nov 27 14:13:20 pioneer ntpd[10362]: Listen normally on 3 eth0 192.168.0.27:123 Nov 27 14:13:20 pioneer ntpd[10362]: Listening on routing socket on fd #20 for interface updates Nov 27 14:13:20 pioneer ntpd[10362]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 27 14:13:20 pioneer ntpd[10362]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 27 14:13:20 pioneer volumio[10255]: info: Loading plugin "network"... Nov 27 14:13:21 pioneer volumio[10255]: info: Refreshing Cached IP Addresses Nov 27 14:13:21 pioneer volumio[10255]: info: Loading plugin "services"... Nov 27 14:13:21 pioneer sudo[10365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 27 14:13:21 pioneer sudo[10365]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:21 pioneer volumio[10255]: info: Loading plugin "alsa_controller"... Nov 27 14:13:21 pioneer sudo[10365]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:21 pioneer sudo[10367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 27 14:13:21 pioneer sudo[10367]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:21 pioneer sudo[10367]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:21 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:13:21 pioneer volumio[10255]: info: Loading plugin "wizard"... Nov 27 14:13:21 pioneer volumio[10255]: info: Loading plugin "networkfs"... Nov 27 14:13:21 pioneer volumio[10255]: info: Starting Udev Watcher for removable devices Nov 27 14:13:21 pioneer volumio[10255]: info: Ignoring mount for partition: boot Nov 27 14:13:21 pioneer volumio[10255]: info: Ignoring mount for partition: volumio Nov 27 14:13:21 pioneer volumio[10255]: info: Ignoring mount for partition: volumio_data Nov 27 14:13:21 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:13:21 pioneer volumio[10255]: info: Loading plugin "volumio_command_line_client"... Nov 27 14:13:21 pioneer volumio[10255]: info: Loading plugin "upnp"... Nov 27 14:13:21 pioneer volumio[10255]: info: [1732709601309] Starting Upmpd Daemon Nov 27 14:13:21 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:13:21 pioneer volumio[10255]: info: Loading plugin "my_music"... Nov 27 14:13:21 pioneer volumio[10255]: info: Loading plugin "mpd"... Nov 27 14:13:21 pioneer ntpd[10362]: Soliciting pool server 193.106.144.13 Nov 27 14:13:21 pioneer volumio[10255]: info: Loading plugin "upnp_browser"... Nov 27 14:13:22 pioneer volumio[10255]: info: Loading plugin "alarm-clock"... Nov 27 14:13:22 pioneer volumio[10255]: info: Loading plugin "airplay_emulation"... Nov 27 14:13:22 pioneer volumio[10255]: info: Starting Shairport Sync Nov 27 14:13:22 pioneer volumio[10255]: info: Loading plugin "last_100"... Nov 27 14:13:22 pioneer volumio[10255]: info: Loading plugin "webradio"... Nov 27 14:13:22 pioneer ntpd[10362]: Soliciting pool server 162.159.200.1 Nov 27 14:13:22 pioneer ntpd[10362]: Soliciting pool server 193.34.155.4 Nov 27 14:13:23 pioneer volumio[10255]: info: Loading plugin "i2s_dacs"... Nov 27 14:13:23 pioneer volumio[10255]: info: I2S DAC not set, start Auto-detection Nov 27 14:13:23 pioneer volumio[10255]: info: Loading plugin "volumiodiscovery"... Nov 27 14:13:23 pioneer volumio[10255]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 14:13:23 pioneer volumio[10255]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:13:23 pioneer node[10255]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 14:13:23 pioneer volumio[10255]: *** WARNING *** For more information see Nov 27 14:13:23 pioneer volumio[10255]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 14:13:23 pioneer volumio[10255]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:13:23 pioneer volumio[10255]: *** WARNING *** For more information see Nov 27 14:13:23 pioneer node[10255]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:13:23 pioneer node[10255]: *** WARNING *** For more information see Nov 27 14:13:23 pioneer node[10255]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 14:13:23 pioneer node[10255]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:13:23 pioneer node[10255]: *** WARNING *** For more information see Nov 27 14:13:23 pioneer volumio[10255]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 27 14:13:23 pioneer volumio[10255]: info: Discovery: Started advertising with name: Pioneer Nov 27 14:13:23 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:13:23 pioneer volumio[10255]: info: Loading plugin "squeezelite_mc"... Nov 27 14:13:23 pioneer volumio-remote-updater[720]: [2024-11-27 14:13:23] [connect] Successful connection Nov 27 14:13:23 pioneer ntpd[10362]: Soliciting pool server 91.231.182.17 Nov 27 14:13:23 pioneer ntpd[10362]: Soliciting pool server 162.159.200.123 Nov 27 14:13:23 pioneer ntpd[10362]: Soliciting pool server 130.255.135.221 Nov 27 14:13:24 pioneer volumio[10255]: info: Loading plugin "autostart"... Nov 27 14:13:24 pioneer ntpd[10362]: Soliciting pool server 91.236.251.35 Nov 27 14:13:24 pioneer ntpd[10362]: Soliciting pool server 91.209.24.19 Nov 27 14:13:24 pioneer ntpd[10362]: Soliciting pool server 91.236.251.38 Nov 27 14:13:25 pioneer volumio[10255]: info: Applying required configuration parameters for plugin autostart Nov 27 14:13:25 pioneer volumio[10255]: info: AutoStart - onVolumioStart - read config.json Nov 27 14:13:25 pioneer volumio[10255]: info: Loading plugin "outputs"... Nov 27 14:13:25 pioneer volumio[10255]: info: Loading plugin "albumart"... Nov 27 14:13:25 pioneer volumio[10255]: info: Plugin example_plugin is not enabled Nov 27 14:13:25 pioneer volumio[10255]: info: Loading plugin "inputs"... Nov 27 14:13:25 pioneer volumio[10255]: info: Loading plugin "updater_comm"... Nov 27 14:13:25 pioneer volumio[10255]: info: Plugin mpdemulation is not enabled Nov 27 14:13:25 pioneer volumio[10255]: info: Loading plugin "rest_api"... Nov 27 14:13:25 pioneer volumio[10255]: info: Loading plugin "websocket"... Nov 27 14:13:25 pioneer volumio[10255]: info: Starting Socket.io Server version 2.3.0 Nov 27 14:13:25 pioneer volumio[10255]: info: Loading plugin "lms"... Nov 27 14:13:25 pioneer volumio[10255]: info: Loading i18n strings for locale ru Nov 27 14:13:25 pioneer ntpd[10362]: Soliciting pool server 91.236.251.34 Nov 27 14:13:25 pioneer volumio[10255]: Updating browse sources language Nov 27 14:13:25 pioneer volumio[10255]: Forking 3 albumart workers Nov 27 14:13:25 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::initPlayerControls Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:13:26 pioneer volumio[10255]: Express server listening on port 3000 Nov 27 14:13:26 pioneer volumio[10255]: [Metrics] WebUI: 9s 912.84ms Nov 27 14:13:26 pioneer volumio[10255]: info: CoreStateMachine::resetVolumioState Nov 27 14:13:26 pioneer volumio[10255]: info: CoreStateMachine::getcurrentVolume Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:13:26 pioneer volumio[10255]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 27 14:13:26 pioneer volumio[10255]: info: Completed loading Core Plugins Nov 27 14:13:26 pioneer volumio[10255]: info: Preparing to generate the ALSA configuration file Nov 27 14:13:26 pioneer volumio[10255]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 27 14:13:26 pioneer volumio[10255]: info: Reading ALSA contributions from plugins. Nov 27 14:13:26 pioneer volumio-remote-updater[720]: [2024-11-27 14:13:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732709603 101 Nov 27 14:13:26 pioneer volumio[10255]: 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: 1 Nov 27 14:13:26 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:26 pioneer volumio[10255]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:26 pioneer volumio[10255]: info: CoreStateMachine::updateTrackBlock Nov 27 14:13:26 pioneer volumio[10255]: info: CorePlayQueue::getTrackBlock Nov 27 14:13:26 pioneer volumio[10255]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:13:27 pioneer volumio[10255]: info: Reloading queue from file Nov 27 14:13:27 pioneer volumio[10255]: info: CoreStateMachine::setRepeat null single undefined Nov 27 14:13:27 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:27 pioneer volumio[10255]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:27 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 14:13:27 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:27 pioneer volumio[10255]: info: CoreStateMachine::setRandom null Nov 27 14:13:27 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:27 pioneer volumio[10255]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:27 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:27 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:27 pioneer volumio[10255]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:27 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:27 pioneer volumio[10255]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Nov 27 14:13:27 pioneer volumio[10255]: info: Asound.conf file unchanged, so no further update is needed Nov 27 14:13:27 pioneer volumio[10255]: info: Output device has changed, restarting MPD Nov 27 14:13:27 pioneer volumio[10255]: info: Output device has changed, restarting Shairport Sync Nov 27 14:13:27 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:27 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:13:27 pioneer sudo[10427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 14:13:27 pioneer sudo[10427]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:27 pioneer sudo[10427]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:27 pioneer sudo[10428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 14:13:27 pioneer sudo[10428]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:27 pioneer volumio[10255]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:13:27 pioneer volumio[10255]: info: ___________ START PLUGINS ___________ Nov 27 14:13:27 pioneer systemd[1]: Stopping Music Player Daemon... Nov 27 14:13:27 pioneer volumio[10255]: info: ControllerMpd::onStart: Initializing MPD Nov 27 14:13:27 pioneer volumio[10255]: info: Creating MPD Configuration file Nov 27 14:13:27 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:13:27 pioneer volumio[10255]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:13:27 pioneer systemd[1]: mpd.service: Succeeded. Nov 27 14:13:27 pioneer systemd[1]: Stopped Music Player Daemon. Nov 27 14:13:27 pioneer volumio[10255]: info: [1732709607529] CoreMusicLibrary::Adding element Медиа-серверы Nov 27 14:13:27 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:13:27 pioneer systemd[1]: Starting Music Player Daemon... Nov 27 14:13:27 pioneer sudo[10435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 14:13:27 pioneer sudo[10435]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:27 pioneer sudo[10435]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:27 pioneer sudo[10438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 14:13:27 pioneer sudo[10438]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:27 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:27 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:13:27 pioneer systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 27 14:13:27 pioneer systemd[1]: mpd.service: Succeeded. Nov 27 14:13:27 pioneer systemd[1]: Stopped Music Player Daemon. Nov 27 14:13:27 pioneer systemd[1]: Starting Music Player Daemon... Nov 27 14:13:27 pioneer volumio[10255]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:13:27 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:27 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:13:28 pioneer sudo[10442]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 14:13:28 pioneer sudo[10442]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:28 pioneer volumio[10255]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:13:28 pioneer volumio[10255]: Starting albumart workers Nov 27 14:13:28 pioneer volumio[10255]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:13:28 pioneer volumio[10255]: info: [1732709608087] CoreMusicLibrary::Adding element Last_100 Nov 27 14:13:28 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:13:28 pioneer volumio[10255]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:13:28 pioneer volumio[10255]: info: [1732709608096] CoreMusicLibrary::Adding element Webradio Nov 27 14:13:28 pioneer sudo[10442]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:28 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:13:28 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:13:28 pioneer volumio[10255]: info: Initializing BBC Radios Nov 27 14:13:28 pioneer volumio[10255]: Starting albumart workers Nov 27 14:13:28 pioneer volumio[10255]: Starting albumart workers Nov 27 14:13:28 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:13:28 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:13:28 pioneer volumio[10255]: info: [squeezelite_mc] Starting proxy server... Nov 27 14:13:28 pioneer volumio[10255]: info: AutoStart - onStart Nov 27 14:13:28 pioneer volumio[10255]: info: Volumio Calling Home Nov 27 14:13:28 pioneer sudo[10461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 27 14:13:28 pioneer sudo[10461]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:28 pioneer systemd[1]: Starting Logitech Media Server Daemon... Nov 27 14:13:28 pioneer systemd[1]: Started Logitech Media Server Daemon. Nov 27 14:13:29 pioneer sudo[10461]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:29 pioneer systemd[10468]: logitechmediaserver.service: Failed to execute command: No such file or directory Nov 27 14:13:29 pioneer systemd[10468]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Nov 27 14:13:29 pioneer systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Nov 27 14:13:29 pioneer systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Nov 27 14:13:29 pioneer volumio[10255]: info: [squeezelite_mc] Proxy server started on port 34965 Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:13:29 pioneer volumio[10255]: info: logitechmediaserver started Nov 27 14:13:29 pioneer volumio[10255]: info: MPD Permissions set Nov 27 14:13:29 pioneer volumio[10255]: info: MPD Permissions set Nov 27 14:13:29 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:29 pioneer volumio[10255]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:29 pioneer volumio[10255]: info: [squeezelite_mc] Server discovery started Nov 27 14:13:29 pioneer volumio[10255]: info: [squeezelite_mc] Player finder started Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:29 pioneer volumio[10255]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 27 14:13:29 pioneer volumio[10255]: info: Volumio called home Nov 27 14:13:29 pioneer volumio[10255]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:13:29 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 27 14:13:30 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:30 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:30 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:30 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:30 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:30 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:30 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:30 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:30 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:30 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:13:30 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 27 14:13:30 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:30 pioneer volumio[10255]: info: [squeezelite_mc] Card 5 supports the following ALSA formats: ["S16_LE"] Nov 27 14:13:30 pioneer volumio[10255]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"pioneer","dsdFormat":null,"card":"5","mixerType":"Software","mixer":"SoftMaster"} Nov 27 14:13:30 pioneer volumio[10255]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Nov 27 14:13:30 pioneer volumio[10255]: info: Starting Shairport Sync Nov 27 14:13:30 pioneer volumio[10255]: info: Starting Shairport Sync Nov 27 14:13:30 pioneer volumio[10255]: info: Starting Shairport Sync Nov 27 14:13:30 pioneer sudo[10518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 27 14:13:30 pioneer sudo[10518]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:30 pioneer volumio[10255]: info: CoreCommandRouter::volumioGetState Nov 27 14:13:30 pioneer volumio[10255]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:30 pioneer sudo[10520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 27 14:13:30 pioneer sudo[10520]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:30 pioneer sudo[10523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 27 14:13:30 pioneer sudo[10523]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:30 pioneer sudo[10516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Nov 27 14:13:30 pioneer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 27 14:13:30 pioneer sudo[10516]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:30 pioneer sudo[10516]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:30 pioneer systemd[1]: shairport-sync.service: Succeeded. Nov 27 14:13:30 pioneer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 27 14:13:30 pioneer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 27 14:13:30 pioneer sudo[10518]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:30 pioneer sudo[10523]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:30 pioneer sudo[10520]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:30 pioneer volumio[10255]: info: Shairport-Sync Started Nov 27 14:13:30 pioneer volumio[10255]: Error adding Membership: Error: addMembership EINVAL Nov 27 14:13:30 pioneer volumio[10255]: info: Shairport-Sync Started Nov 27 14:13:30 pioneer volumio[10255]: info: Shairport-Sync Started Nov 27 14:13:30 pioneer volumio[10255]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Nov 27 14:13:30 pioneer sudo[10533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Nov 27 14:13:30 pioneer sudo[10533]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:30 pioneer sudo[10533]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:30 pioneer volumio[10255]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Nov 27 14:13:31 pioneer sudo[10537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Nov 27 14:13:31 pioneer sudo[10537]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:31 pioneer sudo[10537]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:31 pioneer volumio[10255]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Nov 27 14:13:31 pioneer sudo[10540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 27 14:13:31 pioneer sudo[10540]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:31 pioneer systemd[1]: Reloading. Nov 27 14:13:31 pioneer sudo[10544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 27 14:13:31 pioneer sudo[10544]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:31 pioneer sudo[10545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 27 14:13:31 pioneer sudo[10544]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:31 pioneer sudo[10545]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:31 pioneer sudo[10545]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:31 pioneer sudo[10559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 27 14:13:31 pioneer sudo[10559]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:31 pioneer systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 27 14:13:32 pioneer mpd[10449]: Nov 27 14:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 27 14:13:32 pioneer systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Nov 27 14:13:32 pioneer systemd[1]: Started Music Player Daemon. Nov 27 14:13:32 pioneer sudo[10540]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:32 pioneer sudo[10428]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:32 pioneer sudo[10438]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:32 pioneer volumio[10255]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Nov 27 14:13:32 pioneer sudo[10569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Nov 27 14:13:32 pioneer sudo[10559]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:32 pioneer sudo[10569]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:32 pioneer volumio[10255]: info: Upmpdcli Daemon Started Nov 27 14:13:32 pioneer sudo[10569]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:32 pioneer volumio[10255]: error: MPD error: The expression evaluated to a falsy value: Nov 27 14:13:32 pioneer volumio[10255]: assert.ok(self.idling) Nov 27 14:13:32 pioneer volumio[10255]: error: The expression evaluated to a falsy value: Nov 27 14:13:32 pioneer volumio[10255]: assert.ok(self.idling) Nov 27 14:13:32 pioneer volumio[10255]: error: updateQueue error: null Nov 27 14:13:32 pioneer volumio[10255]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Nov 27 14:13:32 pioneer volumio[10255]: info: MPD running with PID10449 Nov 27 14:13:32 pioneer volumio[10255]: ,establishing connection Nov 27 14:13:32 pioneer volumio[10255]: error: updateQueue error: null Nov 27 14:13:32 pioneer sudo[10575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Nov 27 14:13:32 pioneer sudo[10575]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:32 pioneer sudo[10575]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:32 pioneer volumio[10255]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Nov 27 14:13:32 pioneer sudo[10578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Nov 27 14:13:32 pioneer sudo[10578]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:32 pioneer systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Nov 27 14:13:32 pioneer systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Nov 27 14:13:32 pioneer sudo[10578]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:33 pioneer volumio[10255]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Nov 27 14:13:33 pioneer sudo[10585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Nov 27 14:13:33 pioneer sudo[10585]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:33 pioneer sudo[10585]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:34 pioneer volumio[10255]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Nov 27 14:13:34 pioneer sudo[10588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Nov 27 14:13:34 pioneer sudo[10588]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:34 pioneer sudo[10588]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:34 pioneer volumio[10255]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Nov 27 14:13:34 pioneer sudo[10591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Nov 27 14:13:34 pioneer sudo[10591]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:34 pioneer sudo[10591]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:35 pioneer volumio[10255]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Nov 27 14:13:35 pioneer sudo[10594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Nov 27 14:13:35 pioneer sudo[10594]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:35 pioneer sudo[10594]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:35 pioneer volumio[10255]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Nov 27 14:13:35 pioneer sudo[10597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Nov 27 14:13:35 pioneer sudo[10597]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:13:35 pioneer sudo[10597]: pam_unix(sudo:session): session closed for user root Nov 27 14:13:36 pioneer volumio[10255]: info: Completed starting Core Plugins Nov 27 14:13:36 pioneer volumio[10255]: info: ------------------------------------------- Nov 27 14:13:36 pioneer volumio[10255]: info: ----- MyVolumio plugins startup ---- Nov 27 14:13:36 pioneer volumio[10255]: info: ------------------------------------------- Nov 27 14:13:36 pioneer volumio[10255]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 27 14:13:37 pioneer volumio[10255]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 27 14:13:39 pioneer dhcpcd[792]: eth0: carrier lost Nov 27 14:13:39 pioneer dhcpcd[1064]: eth0: carrier lost Nov 27 14:13:39 pioneer kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Down Nov 27 14:13:39 pioneer avahi-daemon[733]: Withdrawing address record for 192.168.0.27 on eth0. Nov 27 14:13:39 pioneer avahi-daemon[733]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.27. Nov 27 14:13:39 pioneer avahi-daemon[733]: Interface eth0.IPv4 no longer relevant for mDNS. Nov 27 14:13:39 pioneer volumio[10255]: info: Discovery: A device disappeared from network Nov 27 14:13:39 pioneer volumio[10255]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Nov 27 14:13:39 pioneer dhcpcd[792]: eth0: deleting route to 192.168.0.0/24 Nov 27 14:13:39 pioneer dhcpcd[1064]: eth0: deleting route to 192.168.0.0/24 Nov 27 14:13:39 pioneer dhcpcd[792]: eth0: deleting default route via 192.168.0.1 Nov 27 14:13:39 pioneer dhcpcd[1064]: eth0: deleting default route via 192.168.0.1 Nov 27 14:13:40 pioneer ifplugd(eth0)[970]: Link beat lost. Nov 27 14:13:41 pioneer ntpd[10362]: Deleting interface #3 eth0, 192.168.0.27#123, interface stats: received=41, sent=53, dropped=0, active_time=21 secs Nov 27 14:13:41 pioneer ntpd[10362]: 91.236.251.38 local addr 192.168.0.27 -> Nov 27 14:13:41 pioneer ntpd[10362]: 91.209.24.19 local addr 192.168.0.27 -> Nov 27 14:13:41 pioneer ntpd[10362]: 91.236.251.35 local addr 192.168.0.27 -> Nov 27 14:13:41 pioneer ntpd[10362]: 162.159.200.123 local addr 192.168.0.27 -> Nov 27 14:13:41 pioneer ntpd[10362]: 91.231.182.17 local addr 192.168.0.27 -> Nov 27 14:13:41 pioneer ntpd[10362]: 193.34.155.4 local addr 192.168.0.27 -> Nov 27 14:13:41 pioneer ntpd[10362]: 162.159.200.1 local addr 192.168.0.27 -> Nov 27 14:13:41 pioneer ntpd[10362]: 193.106.144.13 local addr 192.168.0.27 -> Nov 27 14:13:48 pioneer volumio[10255]: info: AutoStart - Plugin is starting Nov 27 14:13:48 pioneer volumio[10255]: info: CoreCommandRouter::volumioGetQueue Nov 27 14:13:48 pioneer volumio[10255]: info: CoreStateMachine::getQueue Nov 27 14:13:48 pioneer volumio[10255]: info: CorePlayQueue::getQueue Nov 27 14:13:48 pioneer volumio[10255]: info: AutoStart - start playing Nov 27 14:13:48 pioneer volumio[10255]: info: AutoStart - start playing with no specific position Nov 27 14:13:48 pioneer volumio[10255]: info: CoreCommandRouter::volumioPlay Nov 27 14:13:48 pioneer volumio[10255]: info: CoreStateMachine::play index 0 Nov 27 14:13:48 pioneer volumio[10255]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 14:13:48 pioneer volumio[10255]: info: CoreStateMachine::stop Nov 27 14:13:48 pioneer volumio[10255]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 14:13:48 pioneer volumio[10255]: info: CoreStateMachine::play index undefined Nov 27 14:13:48 pioneer volumio[10255]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 14:13:48 pioneer volumio[10255]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:48 pioneer volumio[10255]: info: CoreStateMachine::startPlaybackTimer Nov 27 14:13:48 pioneer volumio[10255]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:48 pioneer volumio[10255]: info: [1732709628560] ControllerWebradio::clearAddPlayTrack Nov 27 14:13:48 pioneer volumio[10255]: verbose: ControllerMpd::sendMpdCommand stop Nov 27 14:13:48 pioneer volumio[10255]: info: sendMpdCommand stop took 4 milliseconds Nov 27 14:13:48 pioneer volumio[10255]: verbose: ControllerMpd::sendMpdCommand clear Nov 27 14:13:48 pioneer volumio[10255]: info: Nov 27 14:13:48 pioneer volumio[10255]: ---------------------------- MPD announces system playlist update Nov 27 14:13:48 pioneer volumio[10255]: info: Ignoring MPD Status Update Nov 27 14:13:48 pioneer volumio[10255]: info: sendMpdCommand clear took 7 milliseconds Nov 27 14:13:48 pioneer volumio[10255]: verbose: ControllerMpd::sendMpdCommand load "https://electrolounge.stream.laut.fm/electrolounge?pl=m3u&t302=2024-08-05_20-36-05&uuid=9ca8b9c0-058b-46ac-a73b-1cec99366b49" Nov 27 14:13:48 pioneer volumio[10255]: info: Nov 27 14:13:48 pioneer volumio[10255]: ---------------------------- MPD announces system playlist update Nov 27 14:13:48 pioneer volumio[10255]: info: Ignoring MPD Status Update Nov 27 14:13:48 pioneer volumio[10255]: info: Nov 27 14:13:48 pioneer volumio[10255]: ---------------------------- MPD announces system playlist update Nov 27 14:13:48 pioneer volumio[10255]: info: Ignoring MPD Status Update Nov 27 14:13:48 pioneer volumio[10255]: error: updateQueue error: null Nov 27 14:13:48 pioneer volumio[10255]: info: ------------------------------ 22ms Nov 27 14:13:49 pioneer volumio[10255]: verbose: ControllerMpd::sendMpdCommand add "https://electrolounge.stream.laut.fm/electrolounge?pl=m3u&t302=2024-08-05_20-36-05&uuid=9ca8b9c0-058b-46ac-a73b-1cec99366b49" Nov 27 14:13:49 pioneer volumio[10255]: error: updateQueue error: null Nov 27 14:13:49 pioneer volumio[10255]: error: updateQueue error: null Nov 27 14:13:49 pioneer volumio[10255]: info: Nov 27 14:13:49 pioneer volumio[10255]: ---------------------------- MPD announces system playlist update Nov 27 14:13:49 pioneer volumio[10255]: info: Ignoring MPD Status Update Nov 27 14:13:49 pioneer volumio[10255]: info: ------------------------------ 1011ms Nov 27 14:13:49 pioneer volumio[10255]: info: ------------------------------ 1007ms Nov 27 14:13:49 pioneer volumio[10255]: info: sendMpdCommand add "https://electrolounge.stream.laut.fm/electrolounge?pl=m3u&t302=2024-08-05_20-36-05&uuid=9ca8b9c0-058b-46ac-a73b-1cec99366b49" took 8 milliseconds Nov 27 14:13:49 pioneer volumio[10255]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 27 14:13:49 pioneer volumio[10255]: verbose: ControllerMpd::sendMpdCommand play Nov 27 14:13:49 pioneer volumio[10255]: info: Nov 27 14:13:49 pioneer volumio[10255]: ---------------------------- MPD announces system playlist update Nov 27 14:13:49 pioneer volumio[10255]: info: Ignoring MPD Status Update Nov 27 14:13:49 pioneer volumio[10255]: info: Nov 27 14:13:49 pioneer volumio[10255]: ---------------------------- MPD announces system playlist update Nov 27 14:13:49 pioneer volumio[10255]: info: Ignoring MPD Status Update Nov 27 14:13:49 pioneer volumio[10255]: info: ------------------------------ 21ms Nov 27 14:13:49 pioneer volumio[10255]: info: sendMpdCommand play took 14 milliseconds Nov 27 14:13:49 pioneer volumio[10255]: info: ------------------------------ 12ms Nov 27 14:13:49 pioneer volumio[10255]: info: ------------------------------ 9ms Nov 27 14:13:50 pioneer volumio[10255]: info: Nov 27 14:13:50 pioneer volumio[10255]: ---------------------------- MPD announces state update: player Nov 27 14:13:50 pioneer volumio[10255]: info: ControllerMpd::getState Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::sendMpdCommand status Nov 27 14:13:50 pioneer volumio[10255]: info: Nov 27 14:13:50 pioneer volumio[10255]: ---------------------------- MPD announces state update: player Nov 27 14:13:50 pioneer volumio[10255]: info: ControllerMpd::getState Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::sendMpdCommand status Nov 27 14:13:50 pioneer volumio[10255]: info: Nov 27 14:13:50 pioneer volumio[10255]: ---------------------------- MPD announces state update: player Nov 27 14:13:50 pioneer volumio[10255]: info: ControllerMpd::getState Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::sendMpdCommand status Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 27 14:13:50 pioneer volumio[10255]: info: sendMpdCommand status took 35 milliseconds Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::parseState Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 27 14:13:50 pioneer volumio[10255]: info: sendMpdCommand status took 45 milliseconds Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 27 14:13:50 pioneer volumio[10255]: info: sendMpdCommand status took 43 milliseconds Nov 27 14:13:50 pioneer volumio[10255]: info: sendMpdCommand clearerror took 20 milliseconds Nov 27 14:13:50 pioneer volumio[10255]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::parseState Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::parseState Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::parseTrackInfo Nov 27 14:13:50 pioneer volumio[10255]: info: ControllerMpd::pushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreCommandRouter::servicePushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 14:13:50 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:50 pioneer volumio[10255]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:50 pioneer volumio[10255]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"electrolounge?pl=m3u&t302=2024-08-05_20-36-05&uuid=9ca8b9c0-058b-46ac-a73b-1cec99366b49","artist":null,"album":null,"uri":"https://electrolounge.stream.laut.fm/electrolounge?pl=m3u&t302=2024-08-05_20-36-05&uuid=9ca8b9c0-058b-46ac-a73b-1cec99366b49","trackType":""} Nov 27 14:13:50 pioneer volumio[10255]: verbose: CURRENT POSITION 0 Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::syncState stateService stop Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::syncState currentStatus stop Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:50 pioneer volumio[10255]: info: No code Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:50 pioneer volumio[10255]: info: ------------------------------ 121ms Nov 27 14:13:50 pioneer volumio[10255]: info: sendMpdCommand clearerror took 80 milliseconds Nov 27 14:13:50 pioneer volumio[10255]: info: sendMpdCommand clearerror took 78 milliseconds Nov 27 14:13:50 pioneer volumio[10255]: info: sendMpdCommand playlistinfo took 70 milliseconds Nov 27 14:13:50 pioneer volumio[10255]: info: sendMpdCommand playlistinfo took 69 milliseconds Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::parseTrackInfo Nov 27 14:13:50 pioneer volumio[10255]: verbose: ControllerMpd::parseTrackInfo Nov 27 14:13:50 pioneer volumio[10255]: info: ControllerMpd::pushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreCommandRouter::servicePushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:50 pioneer volumio[10255]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:50 pioneer volumio[10255]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"electrolounge?pl=m3u&t302=2024-08-05_20-36-05&uuid=9ca8b9c0-058b-46ac-a73b-1cec99366b49","artist":null,"album":null,"uri":"https://electrolounge.stream.laut.fm/electrolounge?pl=m3u&t302=2024-08-05_20-36-05&uuid=9ca8b9c0-058b-46ac-a73b-1cec99366b49","trackType":""} Nov 27 14:13:50 pioneer volumio[10255]: verbose: CURRENT POSITION 0 Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::syncState stateService stop Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::syncState currentStatus stop Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:50 pioneer volumio[10255]: info: No code Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:50 pioneer volumio[10255]: info: ControllerMpd::pushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreCommandRouter::servicePushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:50 pioneer volumio[10255]: info: CorePlayQueue::getTrack 0 Nov 27 14:13:50 pioneer volumio[10255]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"electrolounge?pl=m3u&t302=2024-08-05_20-36-05&uuid=9ca8b9c0-058b-46ac-a73b-1cec99366b49","artist":null,"album":null,"uri":"https://electrolounge.stream.laut.fm/electrolounge?pl=m3u&t302=2024-08-05_20-36-05&uuid=9ca8b9c0-058b-46ac-a73b-1cec99366b49","trackType":""} Nov 27 14:13:50 pioneer volumio[10255]: verbose: CURRENT POSITION 0 Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::syncState stateService stop Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::syncState currentStatus stop Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:50 pioneer volumio[10255]: info: No code Nov 27 14:13:50 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:50 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:50 pioneer volumio[10255]: info: ------------------------------ 206ms Nov 27 14:13:50 pioneer volumio[10255]: info: ------------------------------ 205ms Nov 27 14:13:50 pioneer ifplugd(eth0)[970]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Nov 27 14:13:50 pioneer ifplugd(eth0)[970]: client: sending commands to master dhcpcd process Nov 27 14:13:50 pioneer dhcpcd[10702]: sending commands to master dhcpcd process Nov 27 14:13:50 pioneer dhcpcd[792]: control command: /sbin/dhcpcd -k eth0 Nov 27 14:13:50 pioneer dhcpcd[792]: eth0: removing interface Nov 27 14:13:50 pioneer ifplugd(eth0)[970]: Program executed successfully. Nov 27 14:13:50 pioneer kernel: dwmac-sun8i 1c30000.ethernet eth0: PHY [0.1:01] driver [Generic PHY] (irq=POLL) Nov 27 14:13:50 pioneer kernel: dwmac-sun8i 1c30000.ethernet eth0: Register MEM_TYPE_PAGE_POOL RxQ-0 Nov 27 14:13:50 pioneer kernel: dwmac-sun8i 1c30000.ethernet eth0: No Safety Features support found Nov 27 14:13:50 pioneer kernel: dwmac-sun8i 1c30000.ethernet eth0: No MAC Management Counters available Nov 27 14:13:50 pioneer kernel: dwmac-sun8i 1c30000.ethernet eth0: PTP not supported by HW Nov 27 14:13:50 pioneer kernel: dwmac-sun8i 1c30000.ethernet eth0: configuring for phy/mii link mode Nov 27 14:13:56 pioneer volumio[10255]: error: MyVolumio Plugin failed to start in a timely fashion Nov 27 14:13:56 pioneer volumio[10255]: info: BOOT COMPLETED Nov 27 14:13:56 pioneer volumio[10255]: [Metrics] CommandRouter: 38s 555.44ms Nov 27 14:13:56 pioneer volumio[10255]: info: CoreCommandRouter::volumiosetStartupVolume Nov 27 14:13:56 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:13:56 pioneer volumio[10255]: info: VolumeController:: Setting startup Volume 50 Nov 27 14:13:56 pioneer volumio[10255]: info: VolumeController::SetAlsaVolume50 Nov 27 14:13:56 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:13:56 pioneer volumio[10255]: info: CoreCommandRouter::Close All Modals sent Nov 27 14:13:56 pioneer volumio[10255]: info: CoreCommandRouter::Close All Modals sent Nov 27 14:13:56 pioneer volumio[10255]: info: CoreStateMachine::pushState Nov 27 14:13:56 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 14:13:56 pioneer volumio[10255]: info: CoreCommandRouter::volumioPushState Nov 27 14:13:57 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 27 14:13:57 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 14:13:57 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 27 14:13:58 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:13:58 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:13:58 pioneer volumio[10255]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 27 14:13:59 pioneer volumio[10255]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 14:13:59 pioneer volumio[10255]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 27 14:13:59 pioneer volumio[10255]: at doSend (dgram.js:714:16) Nov 27 14:13:59 pioneer volumio[10255]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Nov 27 14:13:59 pioneer volumio[10255]: at afterDns (dgram.js:660:5) Nov 27 14:13:59 pioneer volumio[10255]: at processTicksAndRejections (internal/process/task_queues.js:83:21) Nov 27 14:13:59 pioneer volumio[10255]: at runNextTicks (internal/process/task_queues.js:64:3) Nov 27 14:13:59 pioneer volumio[10255]: at processTimers (internal/timers.js:497:9) { Nov 27 14:13:59 pioneer volumio[10255]: errno: -101, Nov 27 14:13:59 pioneer volumio[10255]: code: 'ENETUNREACH', Nov 27 14:13:59 pioneer volumio[10255]: syscall: 'send', Nov 27 14:13:59 pioneer volumio[10255]: address: '255.255.255.255', Nov 27 14:13:59 pioneer volumio[10255]: port: 3483 Nov 27 14:13:59 pioneer volumio[10255]: } Nov 27 14:13:59 pioneer volumio[10255]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 14:14:00 pioneer sudo[10740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 14:13 Nov 27 14:14:00 pioneer sudo[10740]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:00 pioneer sudo[10740]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:01 pioneer volumio-remote-updater[720]: [2024-11-27 14:14:01] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 27 14:14:01 pioneer volumio-remote-updater[720]: [2024-11-27 14:14:01] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 27 14:14:01 pioneer systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 27 14:14:01 pioneer systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 27 14:14:01 pioneer systemd[1]: Started dynamicswap service. Nov 27 14:14:01 pioneer systemd[1]: dynamicswap.service: Succeeded. Nov 27 14:14:01 pioneer systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 27 14:14:01 pioneer systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Nov 27 14:14:01 pioneer systemd[1]: Stopped Volumio Backend Module. Nov 27 14:14:01 pioneer systemd[1]: Started Volumio Backend Module. Nov 27 14:14:01 pioneer systemd[1]: Started dynamicswap service. Nov 27 14:14:01 pioneer systemd[1]: dynamicswap.service: Succeeded. Nov 27 14:14:04 pioneer volumio[10753]: info: ------------------------------------------- Nov 27 14:14:04 pioneer volumio[10753]: info: ----- Volumio3 ---- Nov 27 14:14:04 pioneer volumio[10753]: info: ------------------------------------------- Nov 27 14:14:04 pioneer volumio[10753]: info: ----- System startup ---- Nov 27 14:14:04 pioneer volumio[10753]: info: ------------------------------------------- Nov 27 14:14:06 pioneer volumio[10753]: info: MYVOLUMIO Environment detected Nov 27 14:14:06 pioneer volumio-remote-updater[720]: [2024-11-27 14:14:06] [connect] Successful connection Nov 27 14:14:06 pioneer volumio[10753]: info: Plugin folders cleanup Nov 27 14:14:06 pioneer volumio[10753]: info: Scanning into folder /volumio/app/plugins/ Nov 27 14:14:06 pioneer volumio[10753]: info: Scanning category audio_interface Nov 27 14:14:06 pioneer volumio[10753]: info: Scanning category miscellanea Nov 27 14:14:06 pioneer volumio[10753]: info: Scanning category music_service Nov 27 14:14:06 pioneer volumio[10753]: info: Scanning category plugins.json Nov 27 14:14:06 pioneer volumio[10753]: info: Scanning category system_controller Nov 27 14:14:06 pioneer volumio[10753]: info: Scanning category user_interface Nov 27 14:14:06 pioneer volumio[10753]: info: Scanning into folder /data/plugins/ Nov 27 14:14:06 pioneer volumio[10753]: info: Scanning category music_service Nov 27 14:14:06 pioneer volumio[10753]: info: Scanning category system_controller Nov 27 14:14:06 pioneer volumio[10753]: info: Plugin folders cleanup completed Nov 27 14:14:06 pioneer volumio[10753]: info: ------------------------------------------- Nov 27 14:14:06 pioneer volumio[10753]: info: ----- Core plugins startup ---- Nov 27 14:14:06 pioneer volumio[10753]: info: ------------------------------------------- Nov 27 14:14:06 pioneer volumio[10753]: info: Loading plugins from folder /volumio/app/plugins/ Nov 27 14:14:06 pioneer volumio[10753]: info: Adding plugin upnp to MyMusic Plugins Nov 27 14:14:06 pioneer volumio[10753]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 27 14:14:06 pioneer volumio[10753]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 27 14:14:06 pioneer volumio[10753]: info: Loading plugins from folder /data/plugins/ Nov 27 14:14:06 pioneer volumio[10753]: info: Loading plugin "system"... Nov 27 14:14:06 pioneer volumio[10753]: info: Loading plugin "appearance"... Nov 27 14:14:08 pioneer volumio[10753]: info: Loading plugin "network"... Nov 27 14:14:08 pioneer volumio[10753]: info: Refreshing Cached IP Addresses Nov 27 14:14:08 pioneer volumio[10753]: info: Loading plugin "services"... Nov 27 14:14:08 pioneer sudo[10780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 27 14:14:08 pioneer sudo[10780]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:08 pioneer volumio[10753]: info: Loading plugin "alsa_controller"... Nov 27 14:14:08 pioneer sudo[10780]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:08 pioneer sudo[10782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 27 14:14:08 pioneer sudo[10782]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:08 pioneer sudo[10782]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:08 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:14:08 pioneer volumio[10753]: info: Loading plugin "wizard"... Nov 27 14:14:08 pioneer volumio[10753]: info: Loading plugin "networkfs"... Nov 27 14:14:08 pioneer volumio[10753]: info: Starting Udev Watcher for removable devices Nov 27 14:14:08 pioneer volumio[10753]: info: Ignoring mount for partition: boot Nov 27 14:14:08 pioneer volumio[10753]: info: Ignoring mount for partition: volumio Nov 27 14:14:08 pioneer volumio[10753]: info: Ignoring mount for partition: volumio_data Nov 27 14:14:08 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:14:08 pioneer volumio[10753]: info: Loading plugin "volumio_command_line_client"... Nov 27 14:14:08 pioneer volumio[10753]: info: Loading plugin "upnp"... Nov 27 14:14:08 pioneer volumio[10753]: info: [1732709648467] Starting Upmpd Daemon Nov 27 14:14:08 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:14:08 pioneer volumio[10753]: info: Loading plugin "my_music"... Nov 27 14:14:08 pioneer volumio[10753]: info: Loading plugin "mpd"... Nov 27 14:14:09 pioneer volumio[10753]: info: Loading plugin "upnp_browser"... Nov 27 14:14:09 pioneer volumio[10753]: info: Loading plugin "alarm-clock"... Nov 27 14:14:09 pioneer volumio[10753]: info: Loading plugin "airplay_emulation"... Nov 27 14:14:10 pioneer volumio[10753]: info: Starting Shairport Sync Nov 27 14:14:10 pioneer volumio[10753]: info: Loading plugin "last_100"... Nov 27 14:14:10 pioneer volumio[10753]: info: Loading plugin "webradio"... Nov 27 14:14:10 pioneer volumio[10753]: info: Loading plugin "i2s_dacs"... Nov 27 14:14:10 pioneer volumio[10753]: info: I2S DAC not set, start Auto-detection Nov 27 14:14:10 pioneer volumio[10753]: info: Loading plugin "volumiodiscovery"... Nov 27 14:14:10 pioneer volumio[10753]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 14:14:10 pioneer volumio[10753]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:14:10 pioneer volumio[10753]: *** WARNING *** For more information see Nov 27 14:14:10 pioneer node[10753]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 14:14:10 pioneer volumio[10753]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 14:14:10 pioneer volumio[10753]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:14:10 pioneer volumio[10753]: *** WARNING *** For more information see Nov 27 14:14:10 pioneer node[10753]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:14:10 pioneer node[10753]: *** WARNING *** For more information see Nov 27 14:14:10 pioneer node[10753]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 14:14:10 pioneer node[10753]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:14:10 pioneer node[10753]: *** WARNING *** For more information see Nov 27 14:14:10 pioneer volumio[10753]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 27 14:14:10 pioneer volumio[10753]: info: Discovery: Started advertising with name: Pioneer Nov 27 14:14:10 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:14:10 pioneer volumio[10753]: info: Loading plugin "squeezelite_mc"... Nov 27 14:14:11 pioneer volumio[10753]: info: Loading plugin "autostart"... Nov 27 14:14:12 pioneer volumio[10753]: info: Applying required configuration parameters for plugin autostart Nov 27 14:14:12 pioneer volumio[10753]: info: AutoStart - onVolumioStart - read config.json Nov 27 14:14:12 pioneer volumio[10753]: info: Loading plugin "outputs"... Nov 27 14:14:12 pioneer volumio[10753]: info: Loading plugin "albumart"... Nov 27 14:14:12 pioneer volumio[10753]: info: Plugin example_plugin is not enabled Nov 27 14:14:12 pioneer volumio[10753]: info: Loading plugin "inputs"... Nov 27 14:14:12 pioneer volumio[10753]: info: Loading plugin "updater_comm"... Nov 27 14:14:12 pioneer volumio[10753]: info: Plugin mpdemulation is not enabled Nov 27 14:14:12 pioneer volumio[10753]: info: Loading plugin "rest_api"... Nov 27 14:14:12 pioneer volumio[10753]: info: Loading plugin "websocket"... Nov 27 14:14:12 pioneer volumio[10753]: info: Starting Socket.io Server version 2.3.0 Nov 27 14:14:12 pioneer volumio[10753]: info: Loading plugin "lms"... Nov 27 14:14:13 pioneer volumio[10753]: Forking 3 albumart workers Nov 27 14:14:13 pioneer volumio[10753]: info: Loading i18n strings for locale ru Nov 27 14:14:13 pioneer volumio[10753]: Updating browse sources language Nov 27 14:14:13 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:14:13 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:14:13 pioneer volumio[10753]: info: CoreCommandRouter::initPlayerControls Nov 27 14:14:13 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:13 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:13 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:13 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:13 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:13 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:13 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:13 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:13 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:14:13 pioneer volumio[10753]: Express server listening on port 3000 Nov 27 14:14:13 pioneer volumio[10753]: [Metrics] WebUI: 9s 763.53ms Nov 27 14:14:13 pioneer volumio[10753]: info: CoreStateMachine::resetVolumioState Nov 27 14:14:13 pioneer volumio[10753]: info: CoreStateMachine::getcurrentVolume Nov 27 14:14:13 pioneer volumio[10753]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:14:13 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:13 pioneer volumio[10753]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 27 14:14:13 pioneer volumio[10753]: info: Completed loading Core Plugins Nov 27 14:14:13 pioneer volumio[10753]: info: Preparing to generate the ALSA configuration file Nov 27 14:14:13 pioneer volumio[10753]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 27 14:14:13 pioneer volumio[10753]: info: Reading ALSA contributions from plugins. Nov 27 14:14:14 pioneer volumio[10753]: info: CoreStateMachine::pushState Nov 27 14:14:14 pioneer volumio[10753]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:14 pioneer volumio[10753]: info: CoreStateMachine::updateTrackBlock Nov 27 14:14:14 pioneer volumio[10753]: info: CorePlayQueue::getTrackBlock Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:14:14 pioneer volumio[10753]: info: Reloading queue from file Nov 27 14:14:14 pioneer volumio[10753]: info: CoreStateMachine::setRepeat null single undefined Nov 27 14:14:14 pioneer volumio[10753]: info: CoreStateMachine::pushState Nov 27 14:14:14 pioneer volumio[10753]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:14 pioneer volumio[10753]: info: CoreStateMachine::setRandom null Nov 27 14:14:14 pioneer volumio[10753]: info: CoreStateMachine::pushState Nov 27 14:14:14 pioneer volumio[10753]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:14 pioneer volumio[10753]: info: CoreStateMachine::pushState Nov 27 14:14:14 pioneer volumio[10753]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:14 pioneer volumio[10753]: info: Asound.conf file unchanged, so no further update is needed Nov 27 14:14:14 pioneer volumio[10753]: info: Output device has changed, restarting MPD Nov 27 14:14:14 pioneer volumio[10753]: info: Output device has changed, restarting Shairport Sync Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:14 pioneer sudo[10839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 14:14:14 pioneer sudo[10839]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:14 pioneer sudo[10841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 14:14:14 pioneer sudo[10841]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:14 pioneer sudo[10839]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:14 pioneer volumio[10753]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:14:14 pioneer volumio[10753]: info: ___________ START PLUGINS ___________ Nov 27 14:14:14 pioneer volumio[10753]: info: ControllerMpd::onStart: Initializing MPD Nov 27 14:14:14 pioneer volumio[10753]: info: Creating MPD Configuration file Nov 27 14:14:14 pioneer systemd[1]: Stopping Music Player Daemon... Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:14:14 pioneer volumio[10753]: info: [1732709654615] CoreMusicLibrary::Adding element Медиа-серверы Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:14:14 pioneer systemd[1]: mpd.service: Succeeded. Nov 27 14:14:14 pioneer systemd[1]: Stopped Music Player Daemon. Nov 27 14:14:14 pioneer sudo[10848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 14:14:14 pioneer sudo[10848]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:14 pioneer sudo[10848]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:14 pioneer systemd[1]: Starting Music Player Daemon... Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:14 pioneer sudo[10850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 14:14:14 pioneer sudo[10850]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:14 pioneer systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 27 14:14:14 pioneer systemd[1]: mpd.service: Succeeded. Nov 27 14:14:14 pioneer systemd[1]: Stopped Music Player Daemon. Nov 27 14:14:14 pioneer systemd[1]: Starting Music Player Daemon... Nov 27 14:14:14 pioneer volumio[10753]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:14 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:15 pioneer volumio[10753]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:14:15 pioneer volumio[10753]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:14:15 pioneer volumio[10753]: info: [1732709655071] CoreMusicLibrary::Adding element Last_100 Nov 27 14:14:15 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:14:15 pioneer volumio[10753]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:14:15 pioneer volumio[10753]: info: [1732709655093] CoreMusicLibrary::Adding element Webradio Nov 27 14:14:15 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:14:15 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:14:15 pioneer volumio[10753]: info: Initializing BBC Radios Nov 27 14:14:15 pioneer sudo[10857]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 14:14:15 pioneer sudo[10857]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:15 pioneer sudo[10857]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:15 pioneer volumio[10753]: Starting albumart workers Nov 27 14:14:15 pioneer volumio[10753]: Starting albumart workers Nov 27 14:14:15 pioneer volumio[10753]: Starting albumart workers Nov 27 14:14:15 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:14:15 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:15 pioneer volumio[10753]: info: [squeezelite_mc] Starting proxy server... Nov 27 14:14:15 pioneer volumio[10753]: info: AutoStart - onStart Nov 27 14:14:15 pioneer volumio[10753]: info: Volumio Calling Home Nov 27 14:14:15 pioneer sudo[10874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 27 14:14:15 pioneer sudo[10874]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:15 pioneer systemd[1]: Starting Logitech Media Server Daemon... Nov 27 14:14:15 pioneer systemd[1]: Started Logitech Media Server Daemon. Nov 27 14:14:15 pioneer sudo[10874]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:15 pioneer systemd[10881]: logitechmediaserver.service: Failed to execute command: No such file or directory Nov 27 14:14:15 pioneer systemd[10881]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Nov 27 14:14:15 pioneer volumio[10753]: info: [squeezelite_mc] Proxy server started on port 46115 Nov 27 14:14:15 pioneer systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Nov 27 14:14:15 pioneer systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Nov 27 14:14:16 pioneer volumio[10753]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:14:16 pioneer volumio[10753]: Unhandled rejection Error: No sockets available, cannot start. Nov 27 14:14:16 pioneer volumio[10753]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 27 14:14:16 pioneer volumio[10753]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 27 14:14:16 pioneer volumio[10753]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 27 14:14:16 pioneer volumio[10753]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 27 14:14:16 pioneer volumio[10753]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 27 14:14:16 pioneer volumio[10753]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 27 14:14:16 pioneer volumio[10753]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 27 14:14:16 pioneer volumio[10753]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 27 14:14:16 pioneer volumio[10753]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 27 14:14:16 pioneer volumio[10753]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 27 14:14:16 pioneer volumio[10753]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 27 14:14:16 pioneer volumio[10753]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 27 14:14:16 pioneer volumio[10753]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 27 14:14:16 pioneer volumio[10753]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 27 14:14:16 pioneer volumio[10753]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 27 14:14:16 pioneer volumio[10753]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 27 14:14:16 pioneer volumio[10753]: info: logitechmediaserver started Nov 27 14:14:16 pioneer volumio[10753]: info: MPD Permissions set Nov 27 14:14:16 pioneer volumio[10753]: info: MPD Permissions set Nov 27 14:14:16 pioneer volumio[10753]: info: CoreStateMachine::pushState Nov 27 14:14:16 pioneer volumio[10753]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:16 pioneer volumio[10753]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:16 pioneer volumio[10753]: info: [squeezelite_mc] Server discovery started Nov 27 14:14:16 pioneer volumio[10753]: info: [squeezelite_mc] Player finder started Nov 27 14:14:16 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:16 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:16 pioneer volumio[10753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:16 pioneer volumio[10753]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 27 14:14:16 pioneer volumio[10753]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 14:14:16 pioneer volumio[10753]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 27 14:14:16 pioneer volumio[10753]: at doSend (dgram.js:714:16) Nov 27 14:14:16 pioneer volumio[10753]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Nov 27 14:14:16 pioneer volumio[10753]: at afterDns (dgram.js:660:5) Nov 27 14:14:16 pioneer volumio[10753]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Nov 27 14:14:16 pioneer volumio[10753]: errno: -101, Nov 27 14:14:16 pioneer volumio[10753]: code: 'ENETUNREACH', Nov 27 14:14:16 pioneer volumio[10753]: syscall: 'send', Nov 27 14:14:16 pioneer volumio[10753]: address: '255.255.255.255', Nov 27 14:14:16 pioneer volumio[10753]: port: 3483 Nov 27 14:14:16 pioneer volumio[10753]: } Nov 27 14:14:16 pioneer volumio[10753]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 14:14:17 pioneer sudo[10924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 14:13 Nov 27 14:14:17 pioneer sudo[10924]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:18 pioneer sudo[10924]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:18 pioneer systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 27 14:14:18 pioneer sudo[10841]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:18 pioneer sudo[10850]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:18 pioneer systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 27 14:14:18 pioneer systemd[1]: Started dynamicswap service. Nov 27 14:14:18 pioneer systemd[1]: dynamicswap.service: Succeeded. Nov 27 14:14:18 pioneer systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 27 14:14:18 pioneer systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Nov 27 14:14:18 pioneer systemd[1]: Stopped Volumio Backend Module. Nov 27 14:14:18 pioneer systemd[1]: Started Volumio Backend Module. Nov 27 14:14:18 pioneer systemd[1]: Started dynamicswap service. Nov 27 14:14:18 pioneer volumio-remote-updater[720]: [2024-11-27 14:14:18] [info] asio async_connect error: system:111 (Connection refused) Nov 27 14:14:18 pioneer volumio-remote-updater[720]: [2024-11-27 14:14:18] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 27 14:14:18 pioneer volumio-remote-updater[720]: [2024-11-27 14:14:18] [error] handle_connect error: Underlying Transport Error Nov 27 14:14:18 pioneer systemd[1]: dynamicswap.service: Succeeded. Nov 27 14:14:18 pioneer mpd[10863]: Nov 27 14:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 27 14:14:18 pioneer systemd[1]: Started Music Player Daemon. Nov 27 14:14:21 pioneer volumio[10937]: info: ------------------------------------------- Nov 27 14:14:21 pioneer volumio[10937]: info: ----- Volumio3 ---- Nov 27 14:14:21 pioneer volumio[10937]: info: ------------------------------------------- Nov 27 14:14:21 pioneer volumio[10937]: info: ----- System startup ---- Nov 27 14:14:21 pioneer volumio[10937]: info: ------------------------------------------- Nov 27 14:14:23 pioneer volumio[10937]: info: MYVOLUMIO Environment detected Nov 27 14:14:23 pioneer volumio[10937]: info: Plugin folders cleanup Nov 27 14:14:23 pioneer volumio[10937]: info: Scanning into folder /volumio/app/plugins/ Nov 27 14:14:23 pioneer volumio[10937]: info: Scanning category audio_interface Nov 27 14:14:23 pioneer volumio[10937]: info: Scanning category miscellanea Nov 27 14:14:23 pioneer volumio[10937]: info: Scanning category music_service Nov 27 14:14:23 pioneer volumio[10937]: info: Scanning category plugins.json Nov 27 14:14:23 pioneer volumio[10937]: info: Scanning category system_controller Nov 27 14:14:23 pioneer volumio[10937]: info: Scanning category user_interface Nov 27 14:14:23 pioneer volumio[10937]: info: Scanning into folder /data/plugins/ Nov 27 14:14:23 pioneer volumio[10937]: info: Scanning category music_service Nov 27 14:14:23 pioneer volumio[10937]: info: Scanning category system_controller Nov 27 14:14:23 pioneer volumio[10937]: info: Plugin folders cleanup completed Nov 27 14:14:23 pioneer volumio[10937]: info: ------------------------------------------- Nov 27 14:14:23 pioneer volumio[10937]: info: ----- Core plugins startup ---- Nov 27 14:14:23 pioneer volumio[10937]: info: ------------------------------------------- Nov 27 14:14:23 pioneer volumio[10937]: info: Loading plugins from folder /volumio/app/plugins/ Nov 27 14:14:23 pioneer volumio[10937]: info: Adding plugin upnp to MyMusic Plugins Nov 27 14:14:23 pioneer volumio[10937]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 27 14:14:23 pioneer volumio[10937]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 27 14:14:23 pioneer volumio[10937]: info: Loading plugins from folder /data/plugins/ Nov 27 14:14:23 pioneer volumio[10937]: info: Loading plugin "system"... Nov 27 14:14:23 pioneer volumio[10937]: info: Loading plugin "appearance"... Nov 27 14:14:25 pioneer volumio[10937]: info: Loading plugin "network"... Nov 27 14:14:25 pioneer volumio[10937]: info: Refreshing Cached IP Addresses Nov 27 14:14:25 pioneer volumio[10937]: info: Loading plugin "services"... Nov 27 14:14:25 pioneer sudo[10966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 27 14:14:25 pioneer sudo[10966]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:25 pioneer volumio[10937]: info: Loading plugin "alsa_controller"... Nov 27 14:14:25 pioneer sudo[10966]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:25 pioneer sudo[10968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 27 14:14:25 pioneer sudo[10968]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:25 pioneer sudo[10968]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:25 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:14:25 pioneer volumio[10937]: info: Loading plugin "wizard"... Nov 27 14:14:25 pioneer volumio[10937]: info: Loading plugin "networkfs"... Nov 27 14:14:25 pioneer volumio[10937]: info: Starting Udev Watcher for removable devices Nov 27 14:14:25 pioneer volumio[10937]: info: Ignoring mount for partition: boot Nov 27 14:14:25 pioneer volumio[10937]: info: Ignoring mount for partition: volumio Nov 27 14:14:25 pioneer volumio[10937]: info: Ignoring mount for partition: volumio_data Nov 27 14:14:25 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:14:25 pioneer volumio[10937]: info: Loading plugin "volumio_command_line_client"... Nov 27 14:14:25 pioneer volumio[10937]: info: Loading plugin "upnp"... Nov 27 14:14:25 pioneer volumio[10937]: info: [1732709665646] Starting Upmpd Daemon Nov 27 14:14:25 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:14:25 pioneer volumio[10937]: info: Loading plugin "my_music"... Nov 27 14:14:25 pioneer volumio[10937]: info: Loading plugin "mpd"... Nov 27 14:14:26 pioneer volumio[10937]: info: Loading plugin "upnp_browser"... Nov 27 14:14:26 pioneer volumio[10937]: info: Loading plugin "alarm-clock"... Nov 27 14:14:27 pioneer volumio[10937]: info: Loading plugin "airplay_emulation"... Nov 27 14:14:27 pioneer volumio[10937]: info: Starting Shairport Sync Nov 27 14:14:27 pioneer volumio[10937]: info: Loading plugin "last_100"... Nov 27 14:14:27 pioneer volumio[10937]: info: Loading plugin "webradio"... Nov 27 14:14:27 pioneer volumio[10937]: info: Loading plugin "i2s_dacs"... Nov 27 14:14:27 pioneer volumio[10937]: info: I2S DAC not set, start Auto-detection Nov 27 14:14:27 pioneer volumio[10937]: info: Loading plugin "volumiodiscovery"... Nov 27 14:14:27 pioneer volumio[10937]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 14:14:27 pioneer volumio[10937]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:14:27 pioneer volumio[10937]: *** WARNING *** For more information see Nov 27 14:14:27 pioneer node[10937]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 14:14:27 pioneer volumio[10937]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 14:14:27 pioneer volumio[10937]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:14:27 pioneer volumio[10937]: *** WARNING *** For more information see Nov 27 14:14:27 pioneer node[10937]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:14:27 pioneer node[10937]: *** WARNING *** For more information see Nov 27 14:14:27 pioneer node[10937]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 14:14:27 pioneer node[10937]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:14:27 pioneer node[10937]: *** WARNING *** For more information see Nov 27 14:14:27 pioneer volumio[10937]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 27 14:14:27 pioneer volumio[10937]: info: Discovery: Started advertising with name: Pioneer Nov 27 14:14:27 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:14:27 pioneer volumio[10937]: info: Loading plugin "squeezelite_mc"... Nov 27 14:14:28 pioneer volumio-remote-updater[720]: [2024-11-27 14:14:28] [connect] Successful connection Nov 27 14:14:28 pioneer volumio[10937]: info: Loading plugin "autostart"... Nov 27 14:14:29 pioneer volumio[10937]: info: Applying required configuration parameters for plugin autostart Nov 27 14:14:29 pioneer volumio[10937]: info: AutoStart - onVolumioStart - read config.json Nov 27 14:14:29 pioneer volumio[10937]: info: Loading plugin "outputs"... Nov 27 14:14:29 pioneer volumio[10937]: info: Loading plugin "albumart"... Nov 27 14:14:29 pioneer volumio[10937]: info: Plugin example_plugin is not enabled Nov 27 14:14:29 pioneer volumio[10937]: info: Loading plugin "inputs"... Nov 27 14:14:29 pioneer volumio[10937]: info: Loading plugin "updater_comm"... Nov 27 14:14:29 pioneer volumio[10937]: info: Plugin mpdemulation is not enabled Nov 27 14:14:29 pioneer volumio[10937]: info: Loading plugin "rest_api"... Nov 27 14:14:29 pioneer volumio[10937]: info: Loading plugin "websocket"... Nov 27 14:14:29 pioneer volumio[10937]: info: Starting Socket.io Server version 2.3.0 Nov 27 14:14:29 pioneer volumio[10937]: info: Loading plugin "lms"... Nov 27 14:14:30 pioneer volumio[10937]: info: Loading i18n strings for locale ru Nov 27 14:14:30 pioneer volumio[10937]: Forking 3 albumart workers Nov 27 14:14:30 pioneer volumio[10937]: Updating browse sources language Nov 27 14:14:30 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:14:30 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:14:30 pioneer volumio[10937]: info: CoreCommandRouter::initPlayerControls Nov 27 14:14:30 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:30 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:30 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:30 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:30 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:30 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:30 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:30 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:30 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:14:30 pioneer volumio[10937]: Express server listening on port 3000 Nov 27 14:14:30 pioneer volumio[10937]: [Metrics] WebUI: 9s 747.27ms Nov 27 14:14:30 pioneer volumio[10937]: info: CoreStateMachine::resetVolumioState Nov 27 14:14:30 pioneer volumio[10937]: info: CoreStateMachine::getcurrentVolume Nov 27 14:14:30 pioneer volumio[10937]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:14:30 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:30 pioneer volumio[10937]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 27 14:14:30 pioneer volumio[10937]: info: Completed loading Core Plugins Nov 27 14:14:30 pioneer volumio[10937]: info: Preparing to generate the ALSA configuration file Nov 27 14:14:30 pioneer ntpd[10362]: Soliciting pool server 91.198.10.4 Nov 27 14:14:31 pioneer volumio[10937]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 27 14:14:31 pioneer volumio[10937]: info: Reading ALSA contributions from plugins. Nov 27 14:14:31 pioneer volumio-remote-updater[720]: [2024-11-27 14:14:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732709668 101 Nov 27 14:14:31 pioneer volumio[10937]: 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: 1 Nov 27 14:14:31 pioneer volumio[10937]: info: CoreStateMachine::pushState Nov 27 14:14:31 pioneer volumio[10937]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:31 pioneer volumio[10937]: info: CoreStateMachine::updateTrackBlock Nov 27 14:14:31 pioneer volumio[10937]: info: CorePlayQueue::getTrackBlock Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:14:31 pioneer volumio[10937]: info: Reloading queue from file Nov 27 14:14:31 pioneer volumio[10937]: info: CoreStateMachine::setRepeat null single undefined Nov 27 14:14:31 pioneer volumio[10937]: info: CoreStateMachine::pushState Nov 27 14:14:31 pioneer volumio[10937]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:31 pioneer volumio[10937]: info: CoreStateMachine::setRandom null Nov 27 14:14:31 pioneer volumio[10937]: info: CoreStateMachine::pushState Nov 27 14:14:31 pioneer volumio[10937]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:31 pioneer volumio[10937]: info: CoreStateMachine::pushState Nov 27 14:14:31 pioneer volumio[10937]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:31 pioneer volumio[10937]: info: Asound.conf file unchanged, so no further update is needed Nov 27 14:14:31 pioneer volumio[10937]: info: Output device has changed, restarting MPD Nov 27 14:14:31 pioneer volumio[10937]: info: Output device has changed, restarting Shairport Sync Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:31 pioneer sudo[11026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 14:14:31 pioneer sudo[11027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 14:14:31 pioneer sudo[11027]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:31 pioneer sudo[11026]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:31 pioneer sudo[11026]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:31 pioneer systemd[1]: Stopping Music Player Daemon... Nov 27 14:14:31 pioneer volumio[10937]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:14:31 pioneer volumio[10937]: info: ___________ START PLUGINS ___________ Nov 27 14:14:31 pioneer volumio[10937]: info: ControllerMpd::onStart: Initializing MPD Nov 27 14:14:31 pioneer volumio[10937]: info: Creating MPD Configuration file Nov 27 14:14:31 pioneer systemd[1]: mpd.service: Succeeded. Nov 27 14:14:31 pioneer systemd[1]: Stopped Music Player Daemon. Nov 27 14:14:31 pioneer systemd[1]: Starting Music Player Daemon... Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:14:31 pioneer volumio[10937]: info: [1732709671793] CoreMusicLibrary::Adding element Медиа-серверы Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:31 pioneer sudo[11035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 14:14:31 pioneer sudo[11035]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:31 pioneer sudo[11037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 14:14:31 pioneer sudo[11037]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:31 pioneer sudo[11035]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:31 pioneer systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 27 14:14:31 pioneer systemd[1]: mpd.service: Succeeded. Nov 27 14:14:31 pioneer systemd[1]: Stopped Music Player Daemon. Nov 27 14:14:31 pioneer volumio[10937]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:31 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:31 pioneer systemd[1]: Starting Music Player Daemon... Nov 27 14:14:32 pioneer volumio[10937]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:14:32 pioneer volumio[10937]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:14:32 pioneer volumio[10937]: info: [1732709672171] CoreMusicLibrary::Adding element Last_100 Nov 27 14:14:32 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:14:32 pioneer volumio[10937]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:14:32 pioneer volumio[10937]: info: [1732709672179] CoreMusicLibrary::Adding element Webradio Nov 27 14:14:32 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:14:32 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:14:32 pioneer volumio[10937]: info: Initializing BBC Radios Nov 27 14:14:32 pioneer sudo[11043]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 14:14:32 pioneer sudo[11043]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:32 pioneer sudo[11043]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:32 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:14:32 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:32 pioneer volumio[10937]: Starting albumart workers Nov 27 14:14:32 pioneer volumio[10937]: Starting albumart workers Nov 27 14:14:32 pioneer volumio[10937]: info: [squeezelite_mc] Starting proxy server... Nov 27 14:14:32 pioneer volumio[10937]: info: AutoStart - onStart Nov 27 14:14:32 pioneer volumio[10937]: info: Volumio Calling Home Nov 27 14:14:32 pioneer volumio[10937]: Starting albumart workers Nov 27 14:14:32 pioneer sudo[11060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 27 14:14:32 pioneer sudo[11060]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:32 pioneer systemd[1]: Starting Logitech Media Server Daemon... Nov 27 14:14:32 pioneer volumio[10937]: info: [squeezelite_mc] Proxy server started on port 32895 Nov 27 14:14:32 pioneer systemd[1]: Started Logitech Media Server Daemon. Nov 27 14:14:32 pioneer sudo[11060]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:33 pioneer systemd[11067]: logitechmediaserver.service: Failed to execute command: No such file or directory Nov 27 14:14:33 pioneer systemd[11067]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Nov 27 14:14:33 pioneer systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Nov 27 14:14:33 pioneer systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Nov 27 14:14:33 pioneer volumio[10937]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:14:33 pioneer volumio[10937]: Unhandled rejection Error: No sockets available, cannot start. Nov 27 14:14:33 pioneer volumio[10937]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 27 14:14:33 pioneer volumio[10937]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 27 14:14:33 pioneer volumio[10937]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 27 14:14:33 pioneer volumio[10937]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 27 14:14:33 pioneer volumio[10937]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 27 14:14:33 pioneer volumio[10937]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 27 14:14:33 pioneer volumio[10937]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 27 14:14:33 pioneer volumio[10937]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 27 14:14:33 pioneer volumio[10937]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 27 14:14:33 pioneer volumio[10937]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 27 14:14:33 pioneer volumio[10937]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 27 14:14:33 pioneer volumio[10937]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 27 14:14:33 pioneer volumio[10937]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 27 14:14:33 pioneer volumio[10937]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 27 14:14:33 pioneer volumio[10937]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 27 14:14:33 pioneer volumio[10937]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 27 14:14:33 pioneer volumio[10937]: info: logitechmediaserver started Nov 27 14:14:33 pioneer volumio[10937]: info: MPD Permissions set Nov 27 14:14:33 pioneer volumio[10937]: info: MPD Permissions set Nov 27 14:14:33 pioneer volumio[10937]: info: CoreStateMachine::pushState Nov 27 14:14:33 pioneer volumio[10937]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:33 pioneer volumio[10937]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:33 pioneer volumio[10937]: info: [squeezelite_mc] Server discovery started Nov 27 14:14:33 pioneer volumio[10937]: info: [squeezelite_mc] Player finder started Nov 27 14:14:33 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:33 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:33 pioneer volumio[10937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:33 pioneer volumio[10937]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 27 14:14:33 pioneer volumio[10937]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 14:14:33 pioneer volumio[10937]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 27 14:14:33 pioneer volumio[10937]: at doSend (dgram.js:714:16) Nov 27 14:14:33 pioneer volumio[10937]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Nov 27 14:14:33 pioneer volumio[10937]: at afterDns (dgram.js:660:5) Nov 27 14:14:33 pioneer volumio[10937]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Nov 27 14:14:33 pioneer volumio[10937]: errno: -101, Nov 27 14:14:33 pioneer volumio[10937]: code: 'ENETUNREACH', Nov 27 14:14:33 pioneer volumio[10937]: syscall: 'send', Nov 27 14:14:33 pioneer volumio[10937]: address: '255.255.255.255', Nov 27 14:14:33 pioneer volumio[10937]: port: 3483 Nov 27 14:14:33 pioneer volumio[10937]: } Nov 27 14:14:33 pioneer volumio[10937]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 14:14:35 pioneer sudo[11109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 14:13 Nov 27 14:14:35 pioneer sudo[11109]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:35 pioneer sudo[11109]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:35 pioneer volumio-remote-updater[720]: [2024-11-27 14:14:35] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 27 14:14:35 pioneer volumio-remote-updater[720]: [2024-11-27 14:14:35] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 27 14:14:35 pioneer systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 27 14:14:35 pioneer sudo[11027]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:35 pioneer sudo[11037]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:35 pioneer systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 27 14:14:35 pioneer systemd[1]: Started dynamicswap service. Nov 27 14:14:35 pioneer systemd[1]: dynamicswap.service: Succeeded. Nov 27 14:14:35 pioneer systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 27 14:14:35 pioneer systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Nov 27 14:14:35 pioneer systemd[1]: Started dynamicswap service. Nov 27 14:14:35 pioneer systemd[1]: Stopped Volumio Backend Module. Nov 27 14:14:35 pioneer systemd[1]: Started Volumio Backend Module. Nov 27 14:14:35 pioneer systemd[1]: dynamicswap.service: Succeeded. Nov 27 14:14:36 pioneer mpd[11050]: Nov 27 14:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 27 14:14:36 pioneer systemd[1]: Started Music Player Daemon. Nov 27 14:14:37 pioneer nmbd[952]: [2024/11/27 14:14:37.916109, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Nov 27 14:14:37 pioneer nmbd[952]: reload_interfaces: No subnets to listen to. Waiting.. Nov 27 14:14:39 pioneer volumio[11123]: info: ------------------------------------------- Nov 27 14:14:39 pioneer volumio[11123]: info: ----- Volumio3 ---- Nov 27 14:14:39 pioneer volumio[11123]: info: ------------------------------------------- Nov 27 14:14:39 pioneer volumio[11123]: info: ----- System startup ---- Nov 27 14:14:39 pioneer volumio[11123]: info: ------------------------------------------- Nov 27 14:14:40 pioneer volumio[11123]: info: MYVOLUMIO Environment detected Nov 27 14:14:40 pioneer volumio-remote-updater[720]: [2024-11-27 14:14:40] [connect] Successful connection Nov 27 14:14:40 pioneer volumio[11123]: info: Plugin folders cleanup Nov 27 14:14:40 pioneer volumio[11123]: info: Scanning into folder /volumio/app/plugins/ Nov 27 14:14:40 pioneer volumio[11123]: info: Scanning category audio_interface Nov 27 14:14:40 pioneer volumio[11123]: info: Scanning category miscellanea Nov 27 14:14:40 pioneer volumio[11123]: info: Scanning category music_service Nov 27 14:14:40 pioneer volumio[11123]: info: Scanning category plugins.json Nov 27 14:14:40 pioneer volumio[11123]: info: Scanning category system_controller Nov 27 14:14:40 pioneer volumio[11123]: info: Scanning category user_interface Nov 27 14:14:40 pioneer volumio[11123]: info: Scanning into folder /data/plugins/ Nov 27 14:14:40 pioneer volumio[11123]: info: Scanning category music_service Nov 27 14:14:40 pioneer volumio[11123]: info: Scanning category system_controller Nov 27 14:14:40 pioneer volumio[11123]: info: Plugin folders cleanup completed Nov 27 14:14:40 pioneer volumio[11123]: info: ------------------------------------------- Nov 27 14:14:40 pioneer volumio[11123]: info: ----- Core plugins startup ---- Nov 27 14:14:40 pioneer volumio[11123]: info: ------------------------------------------- Nov 27 14:14:40 pioneer volumio[11123]: info: Loading plugins from folder /volumio/app/plugins/ Nov 27 14:14:40 pioneer volumio[11123]: info: Adding plugin upnp to MyMusic Plugins Nov 27 14:14:40 pioneer volumio[11123]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 27 14:14:40 pioneer volumio[11123]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 27 14:14:40 pioneer volumio[11123]: info: Loading plugins from folder /data/plugins/ Nov 27 14:14:40 pioneer volumio[11123]: info: Loading plugin "system"... Nov 27 14:14:40 pioneer volumio[11123]: info: Loading plugin "appearance"... Nov 27 14:14:42 pioneer volumio[11123]: info: Loading plugin "network"... Nov 27 14:14:42 pioneer volumio[11123]: info: Refreshing Cached IP Addresses Nov 27 14:14:42 pioneer volumio[11123]: info: Loading plugin "services"... Nov 27 14:14:42 pioneer sudo[11190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 27 14:14:42 pioneer sudo[11190]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:42 pioneer volumio[11123]: info: Loading plugin "alsa_controller"... Nov 27 14:14:42 pioneer sudo[11190]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:42 pioneer sudo[11192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 27 14:14:42 pioneer sudo[11192]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:42 pioneer sudo[11192]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:42 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:14:42 pioneer volumio[11123]: info: Loading plugin "wizard"... Nov 27 14:14:42 pioneer volumio[11123]: info: Loading plugin "networkfs"... Nov 27 14:14:42 pioneer volumio[11123]: info: Starting Udev Watcher for removable devices Nov 27 14:14:42 pioneer volumio[11123]: info: Ignoring mount for partition: boot Nov 27 14:14:42 pioneer volumio[11123]: info: Ignoring mount for partition: volumio Nov 27 14:14:42 pioneer volumio[11123]: info: Ignoring mount for partition: volumio_data Nov 27 14:14:42 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:14:42 pioneer volumio[11123]: info: Loading plugin "volumio_command_line_client"... Nov 27 14:14:42 pioneer volumio[11123]: info: Loading plugin "upnp"... Nov 27 14:14:42 pioneer volumio[11123]: info: [1732709682962] Starting Upmpd Daemon Nov 27 14:14:42 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:14:42 pioneer volumio[11123]: info: Loading plugin "my_music"... Nov 27 14:14:42 pioneer volumio[11123]: info: Loading plugin "mpd"... Nov 27 14:14:43 pioneer dhcpcd[1064]: eth0: carrier acquired Nov 27 14:14:43 pioneer kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Nov 27 14:14:43 pioneer dhcpcd[1064]: eth0: IAID e5:44:e1:ba Nov 27 14:14:43 pioneer volumio[11123]: info: Loading plugin "upnp_browser"... Nov 27 14:14:43 pioneer dhcpcd[1064]: eth0: rebinding lease of 192.168.0.27 Nov 27 14:14:44 pioneer ifplugd(eth0)[970]: Link beat detected. Nov 27 14:14:44 pioneer ifplugd(eth0)[970]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Nov 27 14:14:44 pioneer dhcpcd[1064]: eth0: soliciting an IPv6 router Nov 27 14:14:44 pioneer ifplugd(eth0)[970]: client: command failed: No such device (-19) Nov 27 14:14:44 pioneer volumio[11123]: info: Loading plugin "alarm-clock"... Nov 27 14:14:44 pioneer ifplugd(eth0)[970]: client: sending commands to master dhcpcd process Nov 27 14:14:44 pioneer dhcpcd[11221]: sending commands to master dhcpcd process Nov 27 14:14:44 pioneer dhcpcd[792]: control command: /sbin/dhcpcd eth0 Nov 27 14:14:44 pioneer dhcpcd[792]: eth0: carrier acquired Nov 27 14:14:44 pioneer dhcpcd[792]: eth0: IAID e5:44:e1:ba Nov 27 14:14:44 pioneer dhcpcd[792]: eth0: soliciting an IPv6 router Nov 27 14:14:44 pioneer ifplugd(eth0)[970]: Program executed successfully. Nov 27 14:14:44 pioneer volumio[11123]: info: Loading plugin "airplay_emulation"... Nov 27 14:14:44 pioneer volumio[11123]: info: Starting Shairport Sync Nov 27 14:14:44 pioneer volumio[11123]: info: Loading plugin "last_100"... Nov 27 14:14:44 pioneer volumio[11123]: info: Loading plugin "webradio"... Nov 27 14:14:44 pioneer volumio[11123]: info: Loading plugin "i2s_dacs"... Nov 27 14:14:44 pioneer volumio[11123]: info: I2S DAC not set, start Auto-detection Nov 27 14:14:44 pioneer dhcpcd[792]: eth0: rebinding lease of 192.168.0.27 Nov 27 14:14:44 pioneer volumio[11123]: info: Loading plugin "volumiodiscovery"... Nov 27 14:14:44 pioneer volumio[11123]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 14:14:44 pioneer volumio[11123]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:14:44 pioneer node[11123]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 14:14:44 pioneer volumio[11123]: *** WARNING *** For more information see Nov 27 14:14:44 pioneer volumio[11123]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 14:14:44 pioneer volumio[11123]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:14:44 pioneer volumio[11123]: *** WARNING *** For more information see Nov 27 14:14:44 pioneer node[11123]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:14:44 pioneer node[11123]: *** WARNING *** For more information see Nov 27 14:14:44 pioneer node[11123]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 14:14:44 pioneer node[11123]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 14:14:44 pioneer node[11123]: *** WARNING *** For more information see Nov 27 14:14:44 pioneer volumio[11123]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 27 14:14:44 pioneer volumio[11123]: info: Discovery: Started advertising with name: Pioneer Nov 27 14:14:44 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 14:14:44 pioneer volumio[11123]: info: Loading plugin "squeezelite_mc"... Nov 27 14:14:46 pioneer volumio[11123]: info: Loading plugin "autostart"... Nov 27 14:14:46 pioneer volumio[11123]: info: Applying required configuration parameters for plugin autostart Nov 27 14:14:46 pioneer volumio[11123]: info: AutoStart - onVolumioStart - read config.json Nov 27 14:14:46 pioneer volumio[11123]: info: Loading plugin "outputs"... Nov 27 14:14:46 pioneer volumio[11123]: info: Loading plugin "albumart"... Nov 27 14:14:46 pioneer volumio[11123]: info: Plugin example_plugin is not enabled Nov 27 14:14:46 pioneer volumio[11123]: info: Loading plugin "inputs"... Nov 27 14:14:46 pioneer volumio[11123]: info: Loading plugin "updater_comm"... Nov 27 14:14:47 pioneer volumio[11123]: info: Plugin mpdemulation is not enabled Nov 27 14:14:47 pioneer volumio[11123]: info: Loading plugin "rest_api"... Nov 27 14:14:47 pioneer volumio[11123]: info: Loading plugin "websocket"... Nov 27 14:14:47 pioneer volumio[11123]: info: Starting Socket.io Server version 2.3.0 Nov 27 14:14:47 pioneer volumio[11123]: info: Loading plugin "lms"... Nov 27 14:14:47 pioneer volumio[11123]: info: Loading i18n strings for locale ru Nov 27 14:14:47 pioneer volumio[11123]: Forking 3 albumart workers Nov 27 14:14:47 pioneer volumio[11123]: Updating browse sources language Nov 27 14:14:47 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:14:47 pioneer dhcpcd[1064]: eth0: probing address 192.168.0.27/24 Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::initPlayerControls Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:14:48 pioneer volumio[11123]: Express server listening on port 3000 Nov 27 14:14:48 pioneer volumio[11123]: [Metrics] WebUI: 9s 796.58ms Nov 27 14:14:48 pioneer volumio[11123]: info: CoreStateMachine::resetVolumioState Nov 27 14:14:48 pioneer volumio[11123]: info: CoreStateMachine::getcurrentVolume Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:48 pioneer volumio[11123]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 27 14:14:48 pioneer volumio[11123]: info: Completed loading Core Plugins Nov 27 14:14:48 pioneer volumio[11123]: info: Preparing to generate the ALSA configuration file Nov 27 14:14:48 pioneer volumio[11123]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 27 14:14:48 pioneer volumio[11123]: info: Reading ALSA contributions from plugins. Nov 27 14:14:48 pioneer volumio[11123]: info: CoreStateMachine::pushState Nov 27 14:14:48 pioneer volumio[11123]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:48 pioneer volumio[11123]: info: CoreStateMachine::updateTrackBlock Nov 27 14:14:48 pioneer volumio[11123]: info: CorePlayQueue::getTrackBlock Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:14:48 pioneer volumio[11123]: info: Reloading queue from file Nov 27 14:14:48 pioneer volumio[11123]: info: CoreStateMachine::setRepeat null single undefined Nov 27 14:14:48 pioneer volumio[11123]: info: CoreStateMachine::pushState Nov 27 14:14:48 pioneer volumio[11123]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:48 pioneer volumio[11123]: info: CoreStateMachine::setRandom null Nov 27 14:14:48 pioneer volumio[11123]: info: CoreStateMachine::pushState Nov 27 14:14:48 pioneer volumio[11123]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:48 pioneer volumio[11123]: info: CoreStateMachine::pushState Nov 27 14:14:48 pioneer volumio[11123]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:48 pioneer volumio[11123]: info: Asound.conf file unchanged, so no further update is needed Nov 27 14:14:48 pioneer volumio[11123]: info: Output device has changed, restarting MPD Nov 27 14:14:48 pioneer volumio[11123]: info: Output device has changed, restarting Shairport Sync Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:48 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:48 pioneer sudo[11298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 14:14:48 pioneer sudo[11298]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:48 pioneer sudo[11300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 14:14:48 pioneer sudo[11300]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:48 pioneer sudo[11298]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:49 pioneer volumio[11123]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:14:49 pioneer volumio[11123]: info: ___________ START PLUGINS ___________ Nov 27 14:14:49 pioneer volumio[11123]: info: ControllerMpd::onStart: Initializing MPD Nov 27 14:14:49 pioneer volumio[11123]: info: Creating MPD Configuration file Nov 27 14:14:49 pioneer systemd[1]: Stopping Music Player Daemon... Nov 27 14:14:49 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 14:14:49 pioneer volumio[11123]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:14:49 pioneer volumio[11123]: info: [1732709689125] CoreMusicLibrary::Adding element Медиа-серверы Nov 27 14:14:49 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:14:49 pioneer systemd[1]: mpd.service: Succeeded. Nov 27 14:14:49 pioneer systemd[1]: Stopped Music Player Daemon. Nov 27 14:14:49 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:49 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:49 pioneer sudo[11307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 14:14:49 pioneer sudo[11307]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:49 pioneer systemd[1]: Starting Music Player Daemon... Nov 27 14:14:49 pioneer sudo[11309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 14:14:49 pioneer sudo[11309]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:49 pioneer sudo[11307]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:49 pioneer systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 27 14:14:49 pioneer systemd[1]: mpd.service: Succeeded. Nov 27 14:14:49 pioneer systemd[1]: Stopped Music Player Daemon. Nov 27 14:14:49 pioneer systemd[1]: Starting Music Player Daemon... Nov 27 14:14:49 pioneer volumio[11123]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:14:49 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:49 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:49 pioneer volumio[11123]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 14:14:49 pioneer volumio[11123]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:14:49 pioneer volumio[11123]: info: [1732709689559] CoreMusicLibrary::Adding element Last_100 Nov 27 14:14:49 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:14:49 pioneer volumio[11123]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 14:14:49 pioneer volumio[11123]: info: [1732709689575] CoreMusicLibrary::Adding element Webradio Nov 27 14:14:49 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 14:14:49 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:14:49 pioneer volumio[11123]: info: Initializing BBC Radios Nov 27 14:14:49 pioneer dhcpcd[792]: eth0: probing address 192.168.0.27/24 Nov 27 14:14:49 pioneer sudo[11316]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 14:14:49 pioneer sudo[11316]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:49 pioneer sudo[11316]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:49 pioneer volumio[11123]: Starting albumart workers Nov 27 14:14:49 pioneer volumio[11123]: Starting albumart workers Nov 27 14:14:49 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 14:14:49 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:49 pioneer volumio[11123]: info: [squeezelite_mc] Starting proxy server... Nov 27 14:14:49 pioneer volumio[11123]: info: AutoStart - onStart Nov 27 14:14:50 pioneer volumio[11123]: Starting albumart workers Nov 27 14:14:50 pioneer volumio[11123]: info: Volumio Calling Home Nov 27 14:14:50 pioneer sudo[11333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 27 14:14:50 pioneer sudo[11333]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:50 pioneer systemd[1]: Starting Logitech Media Server Daemon... Nov 27 14:14:50 pioneer systemd[1]: Started Logitech Media Server Daemon. Nov 27 14:14:50 pioneer sudo[11333]: pam_unix(sudo:session): session closed for user root Nov 27 14:14:50 pioneer systemd[11340]: logitechmediaserver.service: Failed to execute command: No such file or directory Nov 27 14:14:50 pioneer systemd[11340]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Nov 27 14:14:50 pioneer systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Nov 27 14:14:50 pioneer systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Nov 27 14:14:50 pioneer volumio[11123]: info: [squeezelite_mc] Proxy server started on port 46367 Nov 27 14:14:50 pioneer volumio[11123]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 14:14:50 pioneer volumio[11123]: Unhandled rejection Error: No sockets available, cannot start. Nov 27 14:14:50 pioneer volumio[11123]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 27 14:14:50 pioneer volumio[11123]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 27 14:14:50 pioneer volumio[11123]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 27 14:14:50 pioneer volumio[11123]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 27 14:14:50 pioneer volumio[11123]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 27 14:14:50 pioneer volumio[11123]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 27 14:14:50 pioneer volumio[11123]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 27 14:14:50 pioneer volumio[11123]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 27 14:14:50 pioneer volumio[11123]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 27 14:14:50 pioneer volumio[11123]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 27 14:14:50 pioneer volumio[11123]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 27 14:14:50 pioneer volumio[11123]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 27 14:14:50 pioneer volumio[11123]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 27 14:14:50 pioneer volumio[11123]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 27 14:14:50 pioneer volumio[11123]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 27 14:14:50 pioneer volumio[11123]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 27 14:14:50 pioneer volumio[11123]: info: logitechmediaserver started Nov 27 14:14:50 pioneer volumio[11123]: info: MPD Permissions set Nov 27 14:14:50 pioneer volumio[11123]: info: MPD Permissions set Nov 27 14:14:51 pioneer volumio[11123]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreStateMachine::pushState Nov 27 14:14:51 pioneer volumio[11123]: info: CorePlayQueue::getTrack 0 Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::volumioPushState Nov 27 14:14:51 pioneer volumio[11123]: info: [squeezelite_mc] Server discovery started Nov 27 14:14:51 pioneer volumio[11123]: info: [squeezelite_mc] Player finder started Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 14:14:51 pioneer volumio[11123]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 27 14:14:51 pioneer volumio[11123]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 14:14:51 pioneer volumio[11123]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 27 14:14:51 pioneer volumio[11123]: at doSend (dgram.js:714:16) Nov 27 14:14:51 pioneer volumio[11123]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Nov 27 14:14:51 pioneer volumio[11123]: at afterDns (dgram.js:660:5) Nov 27 14:14:51 pioneer volumio[11123]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Nov 27 14:14:51 pioneer volumio[11123]: errno: -101, Nov 27 14:14:51 pioneer volumio[11123]: code: 'ENETUNREACH', Nov 27 14:14:51 pioneer volumio[11123]: syscall: 'send', Nov 27 14:14:51 pioneer volumio[11123]: address: '255.255.255.255', Nov 27 14:14:51 pioneer volumio[11123]: port: 3483 Nov 27 14:14:51 pioneer volumio[11123]: } Nov 27 14:14:51 pioneer volumio[11123]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 14:14:52 pioneer dhcpcd[1064]: eth0: leased 192.168.0.27 for 259200 seconds Nov 27 14:14:52 pioneer dhcpcd[1064]: eth0: adding route to 192.168.0.0/24 Nov 27 14:14:52 pioneer dhcpcd[1064]: eth0: adding default route via 192.168.0.1 Nov 27 14:14:52 pioneer avahi-daemon[733]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.27. Nov 27 14:14:52 pioneer avahi-daemon[733]: New relevant interface eth0.IPv4 for mDNS. Nov 27 14:14:52 pioneer avahi-daemon[733]: Registering new address record for 192.168.0.27 on eth0.IPv4. Nov 27 14:14:52 pioneer ntpd[10362]: ntpd exiting on signal 15 (Terminated) Nov 27 14:14:52 pioneer systemd[1]: Stopping Network Time Service... Nov 27 14:14:52 pioneer systemd[1]: ntp.service: Succeeded. Nov 27 14:14:52 pioneer systemd[1]: Stopped Network Time Service. Nov 27 14:14:52 pioneer systemd[1]: Starting Network Time Service... Nov 27 14:14:52 pioneer ntpd[11425]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 27 14:14:52 pioneer ntpd[11425]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 27 14:14:52 pioneer systemd[1]: Started Network Time Service. Nov 27 14:14:52 pioneer ntpd[11431]: proto: precision = 1.041 usec (-20) Nov 27 14:14:52 pioneer ntpd[11431]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 27 14:14:52 pioneer ntpd[11431]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 27 14:14:52 pioneer ntpd[11431]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 701 days ago Nov 27 14:14:52 pioneer ntpd[11431]: Listen and drop on 0 v6wildcard [::]:123 Nov 27 14:14:52 pioneer ntpd[11431]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 27 14:14:52 pioneer ntpd[11431]: Listen normally on 2 lo 127.0.0.1:123 Nov 27 14:14:52 pioneer ntpd[11431]: Listen normally on 3 eth0 192.168.0.27:123 Nov 27 14:14:52 pioneer ntpd[11431]: Listening on routing socket on fd #20 for interface updates Nov 27 14:14:52 pioneer ntpd[11431]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 27 14:14:52 pioneer ntpd[11431]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 27 14:14:53 pioneer sudo[11435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 14:13 Nov 27 14:14:53 pioneer sudo[11435]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 14:14:53 pioneer volumio-remote-updater[720]: [2024-11-27 14:14:53] [connect] Successful connection PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Sep 2024 10:02:06 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="orangepione" VOLUMIO_DEVICENAME="Orange Pi One" VOLUMIO_HASH="196dbf07455fd638bf5080f981aa4211"