-- Logs begin at Thu 2019-02-14 12:11:58 IST, end at Wed 2025-06-18 18:53:03 IDT. --
Jun 18 18:52:01 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jun 18 18:52:01 volumio volumio[1256]: info: CURURI: music-library
Jun 18 18:52:01 volumio volumio[1256]: info: Preload queue cleared
Jun 18 18:52:01 volumio volumio[1256]: info: MYVOLUMIO: Adding device
Jun 18 18:52:01 volumio volumio[1256]: info: MYVOLUMIO: Evaluating Server
Jun 18 18:52:02 volumio volumio[1505]: ....................................................................................................................++++
Jun 18 18:52:02 volumio volumio[1505]: e is 65537 (0x010001)
Jun 18 18:52:02 volumio volumio[1505]: writing RSA key
Jun 18 18:52:03 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jun 18 18:52:03 volumio volumio[1256]: info: CURURI: music-library/NAS
Jun 18 18:52:03 volumio volumio[1256]: info: Preload queue cleared
Jun 18 18:52:03 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jun 18 18:52:03 volumio volumio[1256]: info: CURURI: music-library/NAS/Music
Jun 18 18:52:04 volumio volumio[1256]: info: Preload queue cleared
Jun 18 18:52:04 volumio volumio[1256]: info: Preloading song: music-library/NAS/Music/VID-20181210-WA0030.mp4
Jun 18 18:52:04 volumio volumio[1256]: info: Exploding uri music-library/NAS/Music/VID-20181210-WA0030.mp4 in service mpd
Jun 18 18:52:04 volumio volumio[1256]: info: ALBUMART /albumart?cacheid=114&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic%2FVID-20181210-WA0030.mp4&metadata=false
Jun 18 18:52:04 volumio volumio[1256]: info: URI /mnt/NAS/Music/VID-20181210-WA0030.mp4
Jun 18 18:52:04 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 18 18:52:04 volumio volumio[1256]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Jun 18 18:52:04 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState
Jun 18 18:52:04 volumio volumio[1256]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:07 volumio volumio[1256]: info: Setting Geolocation for MyVolumio to eu4
Jun 18 18:52:07 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:07 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:07 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:08 volumio volumio[1256]: info: Updating MyVolumio device info
Jun 18 18:52:08 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:08 volumio volumio[1256]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jun 18 18:52:09 volumio volumio-remote-updater[588]: No test mode
Jun 18 18:52:09 volumio volumio-remote-updater[588]: No alpha test mode
Jun 18 18:52:09 volumio volumio[1256]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::volumioGetBrowseSources
Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 18 18:52:10 volumio volumio[1256]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jun 18 18:52:10 volumio volumio[1256]: info: Completed starting MyVolumio Plugin
Jun 18 18:52:10 volumio volumio[1256]: [Metrics] CommandRouter: 35s 785.99ms
Jun 18 18:52:10 volumio volumio[1256]: info: CoreCommandRouter::volumiosetStartupVolume
Jun 18 18:52:10 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:10 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:10 volumio volumio[1256]: info: CoreCommandRouter::Close All Modals sent
Jun 18 18:52:10 volumio volumio[1256]: info: CoreCommandRouter::Close All Modals sent
Jun 18 18:52:10 volumio kernel: SQUASHFS error: zlib decompression failed, data probably corrupt
Jun 18 18:52:10 volumio kernel: SQUASHFS error: Failed to read block 0x1c315fe8: -5
Jun 18 18:52:11 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV
Jun 18 18:52:11 volumio systemd[1]: volumio.service: Failed with result 'signal'.
Jun 18 18:52:11 volumio systemd[1]: Started dynamicswap service.
Jun 18 18:52:11 volumio systemd[1]: dynamicswap.service: Succeeded.
Jun 18 18:52:11 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Jun 18 18:52:11 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1.
Jun 18 18:52:11 volumio systemd[1]: Started dynamicswap service.
Jun 18 18:52:11 volumio systemd[1]: Stopped Volumio Backend Module.
Jun 18 18:52:11 volumio systemd[1]: Started Volumio Backend Module.
Jun 18 18:52:11 volumio systemd[1]: dynamicswap.service: Succeeded.
Jun 18 18:52:12 volumio volumio[1680]: info: -------------------------------------------
Jun 18 18:52:12 volumio volumio[1680]: info: ----- Volumio3 ----
Jun 18 18:52:12 volumio volumio[1680]: info: -------------------------------------------
Jun 18 18:52:12 volumio volumio[1680]: info: ----- System startup ----
Jun 18 18:52:12 volumio volumio[1680]: info: -------------------------------------------
Jun 18 18:52:12 volumio volumio[1680]: info: MYVOLUMIO Environment detected
Jun 18 18:52:12 volumio volumio[1680]: info: Plugin folders cleanup
Jun 18 18:52:12 volumio volumio[1680]: info: Scanning into folder /volumio/app/plugins/
Jun 18 18:52:12 volumio volumio[1680]: info: Scanning category audio_interface
Jun 18 18:52:12 volumio volumio[1680]: info: Scanning category miscellanea
Jun 18 18:52:12 volumio volumio[1680]: info: Scanning category music_service
Jun 18 18:52:12 volumio volumio[1680]: info: Scanning category plugins.json
Jun 18 18:52:12 volumio volumio[1680]: info: Scanning category system_controller
Jun 18 18:52:12 volumio volumio[1680]: info: Scanning category user_interface
Jun 18 18:52:12 volumio volumio[1680]: info: Scanning into folder /data/plugins/
Jun 18 18:52:12 volumio volumio[1680]: info: Plugin folders cleanup completed
Jun 18 18:52:12 volumio volumio[1680]: info: -------------------------------------------
Jun 18 18:52:12 volumio volumio[1680]: info: ----- Core plugins startup ----
Jun 18 18:52:12 volumio volumio[1680]: info: -------------------------------------------
Jun 18 18:52:12 volumio volumio[1680]: info: Loading plugins from folder /volumio/app/plugins/
Jun 18 18:52:12 volumio volumio[1680]: info: Adding plugin upnp to MyMusic Plugins
Jun 18 18:52:12 volumio volumio[1680]: info: Adding plugin airplay_emulation to MyMusic Plugins
Jun 18 18:52:12 volumio volumio[1680]: info: Adding plugin upnp_browser to MyMusic Plugins
Jun 18 18:52:12 volumio volumio[1680]: info: Loading plugins from folder /data/plugins/
Jun 18 18:52:12 volumio volumio[1680]: info: Loading plugin "system"...
Jun 18 18:52:12 volumio volumio[1680]: info: Loading plugin "appearance"...
Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "network"...
Jun 18 18:52:13 volumio volumio[1680]: info: Refreshing Cached IP Addresses
Jun 18 18:52:13 volumio sudo[1706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 18 18:52:13 volumio sudo[1706]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:13 volumio sudo[1706]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:13 volumio sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 18 18:52:13 volumio sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:13 volumio sudo[1708]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "services"...
Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "alsa_controller"...
Jun 18 18:52:13 volumio sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jun 18 18:52:13 volumio sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:13 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "wizard"...
Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "networkfs"...
Jun 18 18:52:13 volumio volumio[1680]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds
Jun 18 18:52:13 volumio volumio[1680]: info: Starting Udev Watcher for removable devices
Jun 18 18:52:13 volumio volumio[1680]: info: Ignoring mount for partition: boot
Jun 18 18:52:13 volumio volumio[1680]: info: Ignoring mount for partition: volumio
Jun 18 18:52:13 volumio volumio[1680]: info: Ignoring mount for partition: volumio_data
Jun 18 18:52:13 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "volumio_command_line_client"...
Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "upnp"...
Jun 18 18:52:13 volumio volumio[1680]: info: [1750261933500] Starting Upmpd Daemon
Jun 18 18:52:13 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "my_music"...
Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "mpd"...
Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "upnp_browser"...
Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "alarm-clock"...
Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "airplay_emulation"...
Jun 18 18:52:13 volumio volumio[1680]: info: Starting Shairport Sync
Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "last_100"...
Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "webradio"...
Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "i2s_dacs"...
Jun 18 18:52:14 volumio volumio[1680]: info: I2S DAC not set, start Auto-detection
Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "volumiodiscovery"...
Jun 18 18:52:14 volumio volumio[1680]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jun 18 18:52:14 volumio volumio[1680]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 18 18:52:14 volumio node[1680]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jun 18 18:52:14 volumio volumio[1680]: *** WARNING *** For more information see
Jun 18 18:52:14 volumio volumio[1680]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jun 18 18:52:14 volumio volumio[1680]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 18 18:52:14 volumio volumio[1680]: *** WARNING *** For more information see
Jun 18 18:52:14 volumio node[1680]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 18 18:52:14 volumio node[1680]: *** WARNING *** For more information see
Jun 18 18:52:14 volumio node[1680]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jun 18 18:52:14 volumio node[1680]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 18 18:52:14 volumio node[1680]: *** WARNING *** For more information see
Jun 18 18:52:14 volumio volumio[1680]: info: Applying required configuration parameters for plugin volumiodiscovery
Jun 18 18:52:14 volumio volumio[1680]: info: Discovery: Started advertising with name: Volumio
Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "outputs"...
Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "albumart"...
Jun 18 18:52:14 volumio volumio[1680]: info: Plugin example_plugin is not enabled
Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "inputs"...
Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "updater_comm"...
Jun 18 18:52:14 volumio volumio[1680]: info: Plugin mpdemulation is not enabled
Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "rest_api"...
Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "websocket"...
Jun 18 18:52:14 volumio volumio[1680]: info: Starting Socket.io Server version 2.3.0
Jun 18 18:52:14 volumio volumio[1680]: info: Loading i18n strings for locale ru
Jun 18 18:52:14 volumio volumio[1680]: Updating browse sources language
Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 18 18:52:14 volumio volumio[1680]: Forking 3 albumart workers
Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::initPlayerControls
Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 18 18:52:14 volumio volumio[1680]: Express server listening on port 3000
Jun 18 18:52:14 volumio volumio[1680]: [Metrics] WebUI: 2s 453.72ms
Jun 18 18:52:14 volumio volumio[1680]: info: CoreStateMachine::resetVolumioState
Jun 18 18:52:14 volumio volumio[1680]: info: CoreStateMachine::getcurrentVolume
Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::volumioRetrievevolume
Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:14 volumio volumio[1680]: info: Volumio Network Manager: Network status updated: 3
Jun 18 18:52:14 volumio volumio[1680]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1
Jun 18 18:52:14 volumio volumio[1680]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2
Jun 18 18:52:14 volumio volumio-remote-updater[588]: [2025-06-18 18:52:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Jun 18 18:52:14 volumio volumio-remote-updater[588]: [2025-06-18 18:52:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Jun 18 18:52:14 volumio volumio[1680]: Starting albumart workers
Jun 18 18:52:14 volumio volumio[1680]: Starting albumart workers
Jun 18 18:52:14 volumio volumio[1680]: Starting albumart workers
Jun 18 18:52:14 volumio volumio[1680]: info: Reloading queue from file
Jun 18 18:52:14 volumio volumio[1680]: info: VolumeController:: Volume=100 Mute =false
Jun 18 18:52:14 volumio volumio[1680]: info: CoreStateMachine::pushState
Jun 18 18:52:14 volumio volumio[1680]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState
Jun 18 18:52:14 volumio volumio[1680]: info: CoreStateMachine::updateTrackBlock
Jun 18 18:52:14 volumio volumio[1680]: info: CorePlayQueue::getTrackBlock
Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::volumioRetrievevolume
Jun 18 18:52:15 volumio volumio[1680]: info: CoreStateMachine::setRepeat false single undefined
Jun 18 18:52:15 volumio volumio[1680]: info: CoreStateMachine::pushState
Jun 18 18:52:15 volumio volumio[1680]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState
Jun 18 18:52:15 volumio volumio[1680]: info: CoreStateMachine::setRandom null
Jun 18 18:52:15 volumio volumio[1680]: info: CoreStateMachine::pushState
Jun 18 18:52:15 volumio volumio[1680]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState
Jun 18 18:52:15 volumio volumio[1680]: info: Setting Device type: Raspberry PI
Jun 18 18:52:15 volumio volumio[1680]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3
Jun 18 18:52:15 volumio volumio[1680]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb
Jun 18 18:52:15 volumio volumio[1680]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115
Jun 18 18:52:15 volumio volumio[1680]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI
Jun 18 18:52:15 volumio volumio[1680]: info: Completed loading Core Plugins
Jun 18 18:52:15 volumio volumio[1680]: info: Preparing to generate the ALSA configuration file
Jun 18 18:52:15 volumio volumio[1680]: info: Discovery: adding 4b1566d7-20e6-43e4-bff2-ffac3d93a612
Jun 18 18:52:15 volumio volumio[1680]: info: Discovery: Found device Volumio
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioGetState
Jun 18 18:52:15 volumio volumio[1680]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:15 volumio volumio[1680]: info: Discovery: this is already registered, 4b1566d7-20e6-43e4-bff2-ffac3d93a612
Jun 18 18:52:15 volumio volumio[1680]: info: Discovery: Found device Volumio
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioGetState
Jun 18 18:52:15 volumio volumio[1680]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:15 volumio volumio[1680]: info: VolumeController:: Volume=100 Mute =false
Jun 18 18:52:15 volumio volumio[1680]: info: CoreStateMachine::pushState
Jun 18 18:52:15 volumio volumio[1680]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState
Jun 18 18:52:15 volumio volumio[1680]: info: Asound.conf file unchanged, so no further update is needed
Jun 18 18:52:15 volumio volumio[1680]: info: Output device has changed, restarting MPD
Jun 18 18:52:15 volumio volumio[1680]: info: Output device has changed, restarting Shairport Sync
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:15 volumio sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 18 18:52:15 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:15 volumio sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 18 18:52:15 volumio sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:15 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:15 volumio systemd[1]: Stopping Music Player Daemon...
Jun 18 18:52:15 volumio volumio[1680]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 18 18:52:15 volumio volumio[1680]: info: ___________ START PLUGINS ___________
Jun 18 18:52:15 volumio volumio[1680]: info: ControllerMpd::onStart: Initializing MPD
Jun 18 18:52:15 volumio volumio[1680]: info: Creating MPD Configuration file
Jun 18 18:52:15 volumio systemd[1]: mpd.service: Succeeded.
Jun 18 18:52:15 volumio systemd[1]: Stopped Music Player Daemon.
Jun 18 18:52:15 volumio sudo[1807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 18 18:52:15 volumio sudo[1807]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 18 18:52:15 volumio volumio[1680]: info: [1750261935425] CoreMusicLibrary::Adding element Медиа-серверы
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:15 volumio sudo[1807]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:15 volumio systemd[1]: Starting Music Player Daemon...
Jun 18 18:52:15 volumio sudo[1814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 18 18:52:15 volumio sudo[1814]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:15 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Jun 18 18:52:15 volumio systemd[1]: mpd.service: Succeeded.
Jun 18 18:52:15 volumio volumio[1680]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio systemd[1]: Stopped Music Player Daemon.
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:15 volumio systemd[1]: Starting Music Player Daemon...
Jun 18 18:52:15 volumio volumio[1680]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 18 18:52:15 volumio volumio[1680]: info: [1750261935525] CoreMusicLibrary::Adding element Last_100
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 18 18:52:15 volumio volumio[1680]: info: [1750261935527] CoreMusicLibrary::Adding element Webradio
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 18 18:52:15 volumio volumio[1680]: info: Initializing BBC Radios
Jun 18 18:52:15 volumio sudo[1819]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jun 18 18:52:15 volumio sudo[1819]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:15 volumio sudo[1819]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: Volumio Calling Home
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioGetVisibleSources
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioGetState
Jun 18 18:52:15 volumio volumio[1680]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioGetQueue
Jun 18 18:52:15 volumio volumio[1680]: info: CoreStateMachine::getQueue
Jun 18 18:52:15 volumio volumio[1680]: info: CorePlayQueue::getQueue
Jun 18 18:52:15 volumio volumio[1680]: info: Listing playlists
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jun 18 18:52:15 volumio volumio[1680]: info: MPD Permissions set
Jun 18 18:52:15 volumio volumio[1680]: info: MPD Permissions set
Jun 18 18:52:15 volumio volumio[1680]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:15 volumio volumio[1680]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Jun 18 18:52:15 volumio volumio[1680]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Jun 18 18:52:15 volumio volumio[1680]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
Jun 18 18:52:16 volumio volumio[1680]: info: Starting Shairport Sync
Jun 18 18:52:16 volumio volumio[1680]: info: Starting Shairport Sync
Jun 18 18:52:16 volumio sudo[1847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 18 18:52:16 volumio sudo[1847]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:16 volumio volumio[1680]: info: Starting Shairport Sync
Jun 18 18:52:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jun 18 18:52:16 volumio systemd[1]: shairport-sync.service: Succeeded.
Jun 18 18:52:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jun 18 18:52:16 volumio sudo[1849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 18 18:52:16 volumio sudo[1849]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:16 volumio sudo[1852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 18 18:52:16 volumio sudo[1852]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 18 18:52:16 volumio sudo[1847]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jun 18 18:52:16 volumio systemd[1]: shairport-sync.service: Succeeded.
Jun 18 18:52:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jun 18 18:52:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 18 18:52:16 volumio sudo[1852]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:16 volumio sudo[1849]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:16 volumio volumio[1680]: info: CoreCommandRouter::volumioGetState
Jun 18 18:52:16 volumio volumio[1680]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:16 volumio volumio[1680]: info: Shairport-Sync Started
Jun 18 18:52:16 volumio volumio[1680]: Error adding Membership: Error: addMembership EINVAL
Jun 18 18:52:16 volumio volumio[1680]: info: Shairport-Sync Started
Jun 18 18:52:16 volumio volumio[1680]: info: Shairport-Sync Started
Jun 18 18:52:16 volumio mpd[1831]: Jun 18 18:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jun 18 18:52:16 volumio systemd[1]: Started Music Player Daemon.
Jun 18 18:52:16 volumio sudo[1814]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:16 volumio sudo[1779]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:16 volumio volumio[1680]: info: Completed starting Core Plugins
Jun 18 18:52:16 volumio volumio[1680]: info: -------------------------------------------
Jun 18 18:52:16 volumio volumio[1680]: info: ----- MyVolumio plugins startup ----
Jun 18 18:52:16 volumio volumio[1680]: info: -------------------------------------------
Jun 18 18:52:16 volumio volumio[1680]: info: [MyVolumio PluginManager] Fetching plans data....
Jun 18 18:52:16 volumio volumio[1680]: error: MPD error: The expression evaluated to a falsy value:
Jun 18 18:52:16 volumio volumio[1680]: assert.ok(self.idling)
Jun 18 18:52:16 volumio volumio[1680]: error: The expression evaluated to a falsy value:
Jun 18 18:52:16 volumio volumio[1680]: assert.ok(self.idling)
Jun 18 18:52:16 volumio volumio[1680]: info: MPD running with PID1831
Jun 18 18:52:16 volumio volumio[1680]: ,establishing connection
Jun 18 18:52:16 volumio volumio[1680]: error: updateQueue error: null
Jun 18 18:52:16 volumio volumio[1680]: error: updateQueue error: null
Jun 18 18:52:17 volumio sudo[1721]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:18 volumio volumio[1680]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds
Jun 18 18:52:19 volumio volumio-remote-updater[588]: [2025-06-18 18:52:19] [connect] Successful connection
Jun 18 18:52:19 volumio volumio-remote-updater[588]: [2025-06-18 18:52:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750261939 101
Jun 18 18:52:19 volumio volumio[1680]: 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: 8
Jun 18 18:52:20 volumio volumio[1680]: info: Volumio called home
Jun 18 18:52:21 volumio volumio[1680]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Jun 18 18:52:23 volumio volumio[1680]: info: Cannot mount NAS Music at system boot, trial number 3 ,retrying in 5 seconds
Jun 18 18:52:23 volumio sudo[1882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 18 18:52:23 volumio sudo[1882]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:23 volumio sudo[1882]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:23 volumio volumio[1680]: Worker 1 died :(
Jun 18 18:52:23 volumio sudo[1885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jun 18 18:52:23 volumio sudo[1885]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:23 volumio volumio-remote-updater[588]: [2025-06-18 18:52:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Jun 18 18:52:23 volumio volumio-remote-updater[588]: [2025-06-18 18:52:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Jun 18 18:52:23 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV
Jun 18 18:52:23 volumio sudo[1885]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:23 volumio systemd[1]: volumio.service: Failed with result 'signal'.
Jun 18 18:52:23 volumio systemd[1]: Started dynamicswap service.
Jun 18 18:52:23 volumio systemd[1]: dynamicswap.service: Succeeded.
Jun 18 18:52:23 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Jun 18 18:52:23 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2.
Jun 18 18:52:23 volumio systemd[1]: Stopped Volumio Backend Module.
Jun 18 18:52:23 volumio systemd[1]: Started Volumio Backend Module.
Jun 18 18:52:23 volumio systemd[1]: Started dynamicswap service.
Jun 18 18:52:23 volumio systemd[1]: dynamicswap.service: Succeeded.
Jun 18 18:52:24 volumio volumio[1898]: info: -------------------------------------------
Jun 18 18:52:24 volumio volumio[1898]: info: ----- Volumio3 ----
Jun 18 18:52:24 volumio volumio[1898]: info: -------------------------------------------
Jun 18 18:52:24 volumio volumio[1898]: info: ----- System startup ----
Jun 18 18:52:24 volumio volumio[1898]: info: -------------------------------------------
Jun 18 18:52:25 volumio volumio[1898]: info: MYVOLUMIO Environment detected
Jun 18 18:52:25 volumio volumio[1898]: info: Plugin folders cleanup
Jun 18 18:52:25 volumio volumio[1898]: info: Scanning into folder /volumio/app/plugins/
Jun 18 18:52:25 volumio volumio[1898]: info: Scanning category audio_interface
Jun 18 18:52:25 volumio volumio[1898]: info: Scanning category miscellanea
Jun 18 18:52:25 volumio volumio[1898]: info: Scanning category music_service
Jun 18 18:52:25 volumio volumio[1898]: info: Scanning category plugins.json
Jun 18 18:52:25 volumio volumio[1898]: info: Scanning category system_controller
Jun 18 18:52:25 volumio volumio[1898]: info: Scanning category user_interface
Jun 18 18:52:25 volumio volumio[1898]: info: Scanning into folder /data/plugins/
Jun 18 18:52:25 volumio volumio[1898]: info: Plugin folders cleanup completed
Jun 18 18:52:25 volumio volumio[1898]: info: -------------------------------------------
Jun 18 18:52:25 volumio volumio[1898]: info: ----- Core plugins startup ----
Jun 18 18:52:25 volumio volumio[1898]: info: -------------------------------------------
Jun 18 18:52:25 volumio volumio[1898]: info: Loading plugins from folder /volumio/app/plugins/
Jun 18 18:52:25 volumio volumio[1898]: info: Adding plugin upnp to MyMusic Plugins
Jun 18 18:52:25 volumio volumio[1898]: info: Adding plugin airplay_emulation to MyMusic Plugins
Jun 18 18:52:25 volumio volumio[1898]: info: Adding plugin upnp_browser to MyMusic Plugins
Jun 18 18:52:25 volumio volumio[1898]: info: Loading plugins from folder /data/plugins/
Jun 18 18:52:25 volumio volumio[1898]: info: Loading plugin "system"...
Jun 18 18:52:25 volumio volumio[1898]: info: Loading plugin "appearance"...
Jun 18 18:52:25 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV
Jun 18 18:52:25 volumio systemd[1]: volumio.service: Failed with result 'signal'.
Jun 18 18:52:25 volumio systemd[1]: Started dynamicswap service.
Jun 18 18:52:25 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Jun 18 18:52:25 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3.
Jun 18 18:52:25 volumio systemd[1]: Stopped Volumio Backend Module.
Jun 18 18:52:25 volumio systemd[1]: Started Volumio Backend Module.
Jun 18 18:52:25 volumio systemd[1]: dynamicswap.service: Succeeded.
Jun 18 18:52:26 volumio volumio[1925]: info: -------------------------------------------
Jun 18 18:52:26 volumio volumio[1925]: info: ----- Volumio3 ----
Jun 18 18:52:26 volumio volumio[1925]: info: -------------------------------------------
Jun 18 18:52:26 volumio volumio[1925]: info: ----- System startup ----
Jun 18 18:52:26 volumio volumio[1925]: info: -------------------------------------------
Jun 18 18:52:26 volumio volumio[1925]: info: MYVOLUMIO Environment detected
Jun 18 18:52:26 volumio volumio[1925]: info: Plugin folders cleanup
Jun 18 18:52:26 volumio volumio[1925]: info: Scanning into folder /volumio/app/plugins/
Jun 18 18:52:26 volumio volumio[1925]: info: Scanning category audio_interface
Jun 18 18:52:26 volumio volumio[1925]: info: Scanning category miscellanea
Jun 18 18:52:26 volumio volumio[1925]: info: Scanning category music_service
Jun 18 18:52:26 volumio volumio[1925]: info: Scanning category plugins.json
Jun 18 18:52:26 volumio volumio[1925]: info: Scanning category system_controller
Jun 18 18:52:26 volumio volumio[1925]: info: Scanning category user_interface
Jun 18 18:52:26 volumio volumio[1925]: info: Scanning into folder /data/plugins/
Jun 18 18:52:26 volumio volumio[1925]: info: Plugin folders cleanup completed
Jun 18 18:52:26 volumio volumio[1925]: info: -------------------------------------------
Jun 18 18:52:26 volumio volumio[1925]: info: ----- Core plugins startup ----
Jun 18 18:52:26 volumio volumio[1925]: info: -------------------------------------------
Jun 18 18:52:26 volumio volumio[1925]: info: Loading plugins from folder /volumio/app/plugins/
Jun 18 18:52:26 volumio volumio[1925]: info: Adding plugin upnp to MyMusic Plugins
Jun 18 18:52:26 volumio volumio[1925]: info: Adding plugin airplay_emulation to MyMusic Plugins
Jun 18 18:52:26 volumio volumio[1925]: info: Adding plugin upnp_browser to MyMusic Plugins
Jun 18 18:52:26 volumio volumio[1925]: info: Loading plugins from folder /data/plugins/
Jun 18 18:52:26 volumio volumio[1925]: info: Loading plugin "system"...
Jun 18 18:52:26 volumio volumio[1925]: info: Loading plugin "appearance"...
Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "network"...
Jun 18 18:52:27 volumio volumio[1925]: info: Refreshing Cached IP Addresses
Jun 18 18:52:27 volumio sudo[1951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 18 18:52:27 volumio sudo[1951]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:27 volumio sudo[1951]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:27 volumio sudo[1953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 18 18:52:27 volumio sudo[1953]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:27 volumio sudo[1953]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "services"...
Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "alsa_controller"...
Jun 18 18:52:27 volumio sudo[1966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jun 18 18:52:27 volumio sudo[1966]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:27 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "wizard"...
Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "networkfs"...
Jun 18 18:52:27 volumio volumio[1925]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds
Jun 18 18:52:27 volumio volumio[1925]: info: Starting Udev Watcher for removable devices
Jun 18 18:52:27 volumio volumio[1925]: info: Ignoring mount for partition: boot
Jun 18 18:52:27 volumio volumio[1925]: info: Ignoring mount for partition: volumio
Jun 18 18:52:27 volumio volumio[1925]: info: Ignoring mount for partition: volumio_data
Jun 18 18:52:27 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "volumio_command_line_client"...
Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "upnp"...
Jun 18 18:52:27 volumio volumio[1925]: info: [1750261947694] Starting Upmpd Daemon
Jun 18 18:52:27 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "my_music"...
Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "mpd"...
Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "upnp_browser"...
Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "alarm-clock"...
Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "airplay_emulation"...
Jun 18 18:52:28 volumio volumio[1925]: info: Starting Shairport Sync
Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "last_100"...
Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "webradio"...
Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "i2s_dacs"...
Jun 18 18:52:28 volumio volumio[1925]: info: I2S DAC not set, start Auto-detection
Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "volumiodiscovery"...
Jun 18 18:52:28 volumio volumio[1925]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jun 18 18:52:28 volumio volumio[1925]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 18 18:52:28 volumio node[1925]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jun 18 18:52:28 volumio volumio[1925]: *** WARNING *** For more information see
Jun 18 18:52:28 volumio volumio[1925]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jun 18 18:52:28 volumio volumio[1925]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 18 18:52:28 volumio volumio[1925]: *** WARNING *** For more information see
Jun 18 18:52:28 volumio node[1925]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 18 18:52:28 volumio node[1925]: *** WARNING *** For more information see
Jun 18 18:52:28 volumio node[1925]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jun 18 18:52:28 volumio node[1925]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 18 18:52:28 volumio node[1925]: *** WARNING *** For more information see
Jun 18 18:52:28 volumio volumio[1925]: info: Applying required configuration parameters for plugin volumiodiscovery
Jun 18 18:52:28 volumio volumio[1925]: info: Discovery: Started advertising with name: Volumio
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "outputs"...
Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "albumart"...
Jun 18 18:52:28 volumio volumio[1925]: info: Plugin example_plugin is not enabled
Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "inputs"...
Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "updater_comm"...
Jun 18 18:52:28 volumio volumio[1925]: info: Plugin mpdemulation is not enabled
Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "rest_api"...
Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "websocket"...
Jun 18 18:52:28 volumio volumio[1925]: info: Starting Socket.io Server version 2.3.0
Jun 18 18:52:28 volumio volumio[1925]: info: Loading i18n strings for locale ru
Jun 18 18:52:28 volumio volumio[1925]: Updating browse sources language
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 18 18:52:28 volumio volumio[1925]: Forking 3 albumart workers
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::initPlayerControls
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 18 18:52:28 volumio volumio[1925]: Express server listening on port 3000
Jun 18 18:52:28 volumio volumio[1925]: [Metrics] WebUI: 2s 426.60ms
Jun 18 18:52:28 volumio volumio-remote-updater[588]: [2025-06-18 18:52:28] [connect] Successful connection
Jun 18 18:52:28 volumio volumio[1925]: info: CoreStateMachine::resetVolumioState
Jun 18 18:52:28 volumio volumio[1925]: info: CoreStateMachine::getcurrentVolume
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::volumioRetrievevolume
Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:28 volumio volumio[1925]: info: Volumio Network Manager: Network status updated: 3
Jun 18 18:52:28 volumio volumio[1925]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1
Jun 18 18:52:28 volumio volumio[1925]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2
Jun 18 18:52:28 volumio volumio[1925]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3
Jun 18 18:52:28 volumio volumio[1925]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4
Jun 18 18:52:28 volumio volumio[1925]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Jun 18 18:52:28 volumio volumio[1925]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Jun 18 18:52:28 volumio volumio-remote-updater[588]: [2025-06-18 18:52:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750261948 101
Jun 18 18:52:28 volumio volumio[1925]: 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: 7
Jun 18 18:52:29 volumio volumio[1925]: info: Reloading queue from file
Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::setRepeat false single undefined
Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::pushState
Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioPushState
Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::setRandom null
Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::pushState
Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioPushState
Jun 18 18:52:29 volumio volumio[1925]: info: Setting Device type: Raspberry PI
Jun 18 18:52:29 volumio volumio[1925]: info: VolumeController:: Volume=100 Mute =false
Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::pushState
Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioPushState
Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::updateTrackBlock
Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrackBlock
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioRetrievevolume
Jun 18 18:52:29 volumio volumio[1925]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb
Jun 18 18:52:29 volumio volumio[1925]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115
Jun 18 18:52:29 volumio volumio[1925]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI
Jun 18 18:52:29 volumio volumio[1925]: Starting albumart workers
Jun 18 18:52:29 volumio volumio[1925]: Starting albumart workers
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioGetVisibleSources
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioGetState
Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:29 volumio volumio[1925]: Starting albumart workers
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioGetQueue
Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::getQueue
Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getQueue
Jun 18 18:52:29 volumio volumio[1925]: info: Listing playlists
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jun 18 18:52:29 volumio volumio[1925]: info: Completed loading Core Plugins
Jun 18 18:52:29 volumio volumio[1925]: info: Preparing to generate the ALSA configuration file
Jun 18 18:52:29 volumio volumio[1925]: info: Discovery: adding 4b1566d7-20e6-43e4-bff2-ffac3d93a612
Jun 18 18:52:29 volumio volumio[1925]: info: Discovery: Found device Volumio
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioGetState
Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:29 volumio volumio[1925]: info: VolumeController:: Volume=100 Mute =false
Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::pushState
Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioPushState
Jun 18 18:52:29 volumio volumio[1925]: info: Asound.conf file unchanged, so no further update is needed
Jun 18 18:52:29 volumio volumio[1925]: info: Output device has changed, restarting MPD
Jun 18 18:52:29 volumio sudo[2022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 18 18:52:29 volumio sudo[2022]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:29 volumio volumio[1925]: info: Output device has changed, restarting Shairport Sync
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:29 volumio volumio[1925]: Worker 2 died :(
Jun 18 18:52:29 volumio sudo[2022]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:29 volumio sudo[2024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 18 18:52:29 volumio sudo[2024]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:29 volumio systemd[1]: Stopping Music Player Daemon...
Jun 18 18:52:29 volumio systemd[1]: mpd.service: Succeeded.
Jun 18 18:52:29 volumio systemd[1]: Stopped Music Player Daemon.
Jun 18 18:52:29 volumio volumio[1925]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 18 18:52:29 volumio volumio[1925]: info: ___________ START PLUGINS ___________
Jun 18 18:52:29 volumio volumio[1925]: info: ControllerMpd::onStart: Initializing MPD
Jun 18 18:52:29 volumio volumio[1925]: info: Creating MPD Configuration file
Jun 18 18:52:29 volumio volumio[1925]: Worker 3 died :(
Jun 18 18:52:29 volumio systemd[1]: Starting Music Player Daemon...
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 18 18:52:29 volumio volumio[1925]: info: [1750261949501] CoreMusicLibrary::Adding element Медиа-серверы
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 18 18:52:29 volumio sudo[2040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 18 18:52:29 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:29 volumio sudo[2047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 18 18:52:29 volumio sudo[2047]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:29 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:29 volumio sudo[2037]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jun 18 18:52:29 volumio sudo[2037]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:29 volumio sudo[2037]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:29 volumio volumio[1925]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:29 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Jun 18 18:52:29 volumio systemd[1]: mpd.service: Succeeded.
Jun 18 18:52:29 volumio systemd[1]: Stopped Music Player Daemon.
Jun 18 18:52:29 volumio systemd[1]: Starting Music Player Daemon...
Jun 18 18:52:29 volumio volumio[1925]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 18 18:52:29 volumio volumio[1925]: info: [1750261949677] CoreMusicLibrary::Adding element Last_100
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 18 18:52:29 volumio volumio[1925]: info: [1750261949680] CoreMusicLibrary::Adding element Webradio
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 18 18:52:29 volumio volumio[1925]: info: Initializing BBC Radios
Jun 18 18:52:29 volumio sudo[2067]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jun 18 18:52:29 volumio sudo[2067]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:29 volumio sudo[2067]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:29 volumio volumio[1925]: info: Volumio Calling Home
Jun 18 18:52:29 volumio volumio[1925]: info: Discovery: this is already registered, 4b1566d7-20e6-43e4-bff2-ffac3d93a612
Jun 18 18:52:29 volumio volumio[1925]: info: Discovery: Found device Volumio
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioGetState
Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:29 volumio volumio[1925]: info: MPD Permissions set
Jun 18 18:52:29 volumio volumio[1925]: info: MPD Permissions set
Jun 18 18:52:29 volumio volumio[1925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 18 18:52:30 volumio volumio[1925]: Starting albumart workers
Jun 18 18:52:30 volumio volumio[1925]: Starting albumart workers
Jun 18 18:52:30 volumio volumio[1925]: info: Volumio called home
Jun 18 18:52:30 volumio volumio[1925]: info: Starting Shairport Sync
Jun 18 18:52:30 volumio volumio[1925]: info: Starting Shairport Sync
Jun 18 18:52:30 volumio sudo[2092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 18 18:52:30 volumio volumio[1925]: info: Starting Shairport Sync
Jun 18 18:52:30 volumio sudo[2092]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:30 volumio sudo[2094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 18 18:52:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jun 18 18:52:30 volumio sudo[2094]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:30 volumio systemd[1]: shairport-sync.service: Succeeded.
Jun 18 18:52:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::volumioGetState
Jun 18 18:52:30 volumio volumio[1925]: info: CorePlayQueue::getTrack 0
Jun 18 18:52:30 volumio sudo[2097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 18 18:52:30 volumio sudo[2097]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 18 18:52:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 18 18:52:30 volumio sudo[2092]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jun 18 18:52:30 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Jun 18 18:52:30 volumio systemd[1]: shairport-sync.service: Succeeded.
Jun 18 18:52:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jun 18 18:52:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 18 18:52:30 volumio sudo[2097]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:30 volumio sudo[2094]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:30 volumio volumio[1925]: info: Shairport-Sync Started
Jun 18 18:52:30 volumio volumio[1925]: Error adding Membership: Error: addMembership EINVAL
Jun 18 18:52:30 volumio volumio[1925]: info: Shairport-Sync Started
Jun 18 18:52:30 volumio volumio[1925]: info: Shairport-Sync Started
Jun 18 18:52:30 volumio mpd[2076]: Jun 18 18:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jun 18 18:52:30 volumio systemd[1]: Started Music Player Daemon.
Jun 18 18:52:30 volumio sudo[2047]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:30 volumio sudo[2024]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:30 volumio volumio[1925]: info: Completed starting Core Plugins
Jun 18 18:52:30 volumio volumio[1925]: info: -------------------------------------------
Jun 18 18:52:30 volumio volumio[1925]: info: ----- MyVolumio plugins startup ----
Jun 18 18:52:30 volumio volumio[1925]: info: -------------------------------------------
Jun 18 18:52:30 volumio volumio[1925]: info: [MyVolumio PluginManager] Fetching plans data....
Jun 18 18:52:30 volumio volumio[1925]: error: MPD error: The expression evaluated to a falsy value:
Jun 18 18:52:30 volumio volumio[1925]: assert.ok(self.idling)
Jun 18 18:52:30 volumio volumio[1925]: error: The expression evaluated to a falsy value:
Jun 18 18:52:30 volumio volumio[1925]: assert.ok(self.idling)
Jun 18 18:52:30 volumio volumio[1925]: info: MPD running with PID2076
Jun 18 18:52:30 volumio volumio[1925]: ,establishing connection
Jun 18 18:52:30 volumio volumio[1925]: error: updateQueue error: null
Jun 18 18:52:30 volumio volumio[1925]: error: updateQueue error: null
Jun 18 18:52:31 volumio sudo[1966]: pam_unix(sudo:session): session closed for user root
Jun 18 18:52:32 volumio volumio[1925]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds
Jun 18 18:52:35 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jun 18 18:52:35 volumio volumio[1925]: info: CURURI: music-library/NAS/Music/Phil Collins
Jun 18 18:52:35 volumio volumio[1925]: info: Preload queue cleared
Jun 18 18:52:36 volumio volumio[1925]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Jun 18 18:52:37 volumio volumio[1925]: Stacktrace:
Jun 18 18:52:37 volumio volumio[1925]: ptr1=0x29b8e80d
Jun 18 18:52:37 volumio volumio[1925]: ptr2=(nil)
Jun 18 18:52:37 volumio volumio[1925]: ptr3=(nil)
Jun 18 18:52:37 volumio volumio[1925]: ptr4=(nil)
Jun 18 18:52:37 volumio volumio[1925]: failure_message_object=0xbe87d3e4
Jun 18 18:52:37 volumio volumio[1925]: ==== JS stack trace =========================================
Jun 18 18:52:37 volumio volumio[1925]: 0: ExitFrame [pc: 0xf38000]
Jun 18 18:52:37 volumio volumio[1925]: 1: StubFrame [pc: 0xfaf400]
Jun 18 18:52:37 volumio volumio[1925]: Security context: 0x29b8e80d
Jun 18 18:52:37 volumio volumio[1925]: 2: /* anonymous */(aka /* anonymous */) [0x40363ebd] [/volumio/app/plugins/system_controller/networkfs/index.js:254] [bytecode=0x53d3d121 offset=205](this=0x344c027d ,0x40366405