-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Thu 2025-12-18 15:12:28 EST. --
Dec 18 15:11:00 pifystrm volumio[19280]: info: [squeezelite_mc] Server discovery started
Dec 18 15:11:00 pifystrm volumio[19280]: info: [squeezelite_mc] Player finder started
Dec 18 15:11:00 pifystrm volumio[19280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:00 pifystrm volumio[19280]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 18 15:11:00 pifystrm volumio[19280]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true
Dec 18 15:11:00 pifystrm volumio[19280]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 18 15:11:00 pifystrm volumio[19280]: Error: send ENETUNREACH 255.255.255.255:3483
Dec 18 15:11:00 pifystrm volumio[19280]: at doSend (dgram.js:692:16)
Dec 18 15:11:00 pifystrm volumio[19280]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12)
Dec 18 15:11:00 pifystrm volumio[19280]: at afterDns (dgram.js:638:5)
Dec 18 15:11:00 pifystrm volumio[19280]: at processTicksAndRejections (internal/process/task_queues.js:81:21) {
Dec 18 15:11:00 pifystrm volumio[19280]: errno: -101,
Dec 18 15:11:00 pifystrm volumio[19280]: code: 'ENETUNREACH',
Dec 18 15:11:00 pifystrm volumio[19280]: syscall: 'send',
Dec 18 15:11:00 pifystrm volumio[19280]: address: '255.255.255.255',
Dec 18 15:11:00 pifystrm volumio[19280]: port: 3483
Dec 18 15:11:00 pifystrm volumio[19280]: }
Dec 18 15:11:00 pifystrm volumio[19280]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 18 15:11:00 pifystrm 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.
Dec 18 15:11:00 pifystrm systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly.
Dec 18 15:11:00 pifystrm mpd[19579]: Dec 18 15:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 18 15:11:00 pifystrm sudo[19624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-18 15:10
Dec 18 15:11:00 pifystrm sudo[19624]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:00 pifystrm sudo[19624]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:00 pifystrm systemd[1]: Started Music Player Daemon.
Dec 18 15:11:00 pifystrm sudo[19557]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:00 pifystrm sudo[19596]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:00 pifystrm sudo[19568]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:00 pifystrm volumio-remote-updater[16014]: [2025-12-18 15:11:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Dec 18 15:11:00 pifystrm volumio-remote-updater[16014]: [2025-12-18 15:11:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Dec 18 15:11:00 pifystrm systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Dec 18 15:11:00 pifystrm systemd[1]: volumio.service: Failed with result 'exit-code'.
Dec 18 15:11:00 pifystrm systemd[1]: Started dynamicswap service.
Dec 18 15:11:00 pifystrm systemd[1]: dynamicswap.service: Succeeded.
Dec 18 15:11:00 pifystrm systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Dec 18 15:11:00 pifystrm systemd[1]: volumio.service: Scheduled restart job, restart counter is at 21.
Dec 18 15:11:00 pifystrm systemd[1]: Stopped Volumio Backend Module.
Dec 18 15:11:00 pifystrm systemd[1]: Started Volumio Backend Module.
Dec 18 15:11:00 pifystrm systemd[1]: Started dynamicswap service.
Dec 18 15:11:00 pifystrm systemd[1]: dynamicswap.service: Succeeded.
Dec 18 15:11:01 pifystrm volumio[19637]: info: -------------------------------------------
Dec 18 15:11:01 pifystrm volumio[19637]: info: ----- Volumio3 ----
Dec 18 15:11:01 pifystrm volumio[19637]: info: -------------------------------------------
Dec 18 15:11:01 pifystrm volumio[19637]: info: ----- System startup ----
Dec 18 15:11:01 pifystrm volumio[19637]: info: -------------------------------------------
Dec 18 15:11:02 pifystrm volumio[19637]: info: MYVOLUMIO Environment detected
Dec 18 15:11:02 pifystrm volumio[19637]: info: Plugin folders cleanup
Dec 18 15:11:02 pifystrm volumio[19637]: info: Scanning into folder /volumio/app/plugins/
Dec 18 15:11:02 pifystrm volumio[19637]: info: Scanning category audio_interface
Dec 18 15:11:02 pifystrm volumio[19637]: info: Scanning category miscellanea
Dec 18 15:11:02 pifystrm volumio[19637]: info: Scanning category music_service
Dec 18 15:11:02 pifystrm volumio[19637]: info: Scanning category plugins.json
Dec 18 15:11:02 pifystrm volumio[19637]: info: Scanning category system_controller
Dec 18 15:11:02 pifystrm volumio[19637]: info: Scanning category user_interface
Dec 18 15:11:02 pifystrm volumio[19637]: info: Scanning into folder /data/plugins/
Dec 18 15:11:02 pifystrm volumio[19637]: info: Scanning category music_service
Dec 18 15:11:02 pifystrm volumio[19637]: info: Scanning category user_interface
Dec 18 15:11:02 pifystrm volumio[19637]: info: Plugin folders cleanup completed
Dec 18 15:11:02 pifystrm volumio[19637]: info: -------------------------------------------
Dec 18 15:11:02 pifystrm volumio[19637]: info: ----- Core plugins startup ----
Dec 18 15:11:02 pifystrm volumio[19637]: info: -------------------------------------------
Dec 18 15:11:02 pifystrm volumio[19637]: info: Loading plugins from folder /volumio/app/plugins/
Dec 18 15:11:02 pifystrm volumio[19637]: info: Adding plugin upnp to MyMusic Plugins
Dec 18 15:11:02 pifystrm volumio[19637]: info: Adding plugin airplay_emulation to MyMusic Plugins
Dec 18 15:11:02 pifystrm volumio[19637]: info: Adding plugin upnp_browser to MyMusic Plugins
Dec 18 15:11:02 pifystrm volumio[19637]: info: Loading plugins from folder /data/plugins/
Dec 18 15:11:02 pifystrm volumio[19637]: info: Loading plugin "system"...
Dec 18 15:11:02 pifystrm volumio[19637]: info: Loading plugin "appearance"...
Dec 18 15:11:02 pifystrm dhcpcd[941]: wlan0: leased 10.1.12.92 for 604800 seconds
Dec 18 15:11:02 pifystrm avahi-daemon[649]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92.
Dec 18 15:11:02 pifystrm dhcpcd[941]: wlan0: adding route to 10.1.8.0/21
Dec 18 15:11:02 pifystrm dhcpcd[941]: wlan0: adding default route via 10.1.10.1
Dec 18 15:11:02 pifystrm avahi-daemon[649]: New relevant interface wlan0.IPv4 for mDNS.
Dec 18 15:11:02 pifystrm avahi-daemon[649]: Registering new address record for 10.1.12.92 on wlan0.IPv4.
Dec 18 15:11:02 pifystrm volumio[19637]: info: Loading plugin "network"...
Dec 18 15:11:02 pifystrm volumio[19637]: info: Refreshing Cached IP Addresses
Dec 18 15:11:02 pifystrm sudo[19688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 18 15:11:02 pifystrm sudo[19688]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:02 pifystrm sudo[19688]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:02 pifystrm sudo[19690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 18 15:11:02 pifystrm sudo[19690]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:02 pifystrm sudo[19690]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:02 pifystrm volumio[19637]: info: Loading plugin "services"...
Dec 18 15:11:02 pifystrm volumio[19637]: info: Loading plugin "alsa_controller"...
Dec 18 15:11:03 pifystrm sudo[19699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Dec 18 15:11:03 pifystrm sudo[19699]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:03 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 18 15:11:03 pifystrm volumio[19637]: info: Loading plugin "wizard"...
Dec 18 15:11:03 pifystrm volumio[19637]: info: Loading plugin "networkfs"...
Dec 18 15:11:03 pifystrm volumio[19637]: info: Starting Udev Watcher for removable devices
Dec 18 15:11:03 pifystrm sudo[19714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio
Dec 18 15:11:03 pifystrm sudo[19714]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:03 pifystrm volumio[19637]: info: Ignoring mount for partition: boot
Dec 18 15:11:03 pifystrm volumio[19637]: info: Ignoring mount for partition: volumio
Dec 18 15:11:03 pifystrm volumio[19637]: info: Ignoring mount for partition: volumio_data
Dec 18 15:11:03 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 18 15:11:03 pifystrm volumio[19637]: info: Loading plugin "volumio_command_line_client"...
Dec 18 15:11:03 pifystrm volumio[19637]: info: Loading plugin "upnp"...
Dec 18 15:11:03 pifystrm volumio[19637]: info: [1766088663104] Starting Upmpd Daemon
Dec 18 15:11:03 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 18 15:11:03 pifystrm volumio[19637]: info: Loading plugin "my_music"...
Dec 18 15:11:03 pifystrm volumio[19637]: info: Loading plugin "mpd"...
Dec 18 15:11:03 pifystrm volumio[19637]: info: Loading plugin "upnp_browser"...
Dec 18 15:11:03 pifystrm volumio[19637]: info: Loading plugin "alarm-clock"...
Dec 18 15:11:03 pifystrm volumio[19637]: info: Loading plugin "airplay_emulation"...
Dec 18 15:11:03 pifystrm volumio[19637]: info: Starting Shairport Sync
Dec 18 15:11:03 pifystrm volumio[19637]: info: Loading plugin "last_100"...
Dec 18 15:11:03 pifystrm volumio[19637]: info: Loading plugin "webradio"...
Dec 18 15:11:03 pifystrm volumio[19637]: info: Loading plugin "i2s_dacs"...
Dec 18 15:11:03 pifystrm volumio[19637]: info: Loading plugin "volumiodiscovery"...
Dec 18 15:11:03 pifystrm volumio[19637]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Dec 18 15:11:03 pifystrm volumio[19637]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 18 15:11:03 pifystrm volumio[19637]: *** WARNING *** For more information see
Dec 18 15:11:03 pifystrm node[19637]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Dec 18 15:11:03 pifystrm volumio[19637]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Dec 18 15:11:03 pifystrm volumio[19637]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 18 15:11:03 pifystrm volumio[19637]: *** WARNING *** For more information see
Dec 18 15:11:03 pifystrm node[19637]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 18 15:11:03 pifystrm node[19637]: *** WARNING *** For more information see
Dec 18 15:11:03 pifystrm node[19637]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Dec 18 15:11:03 pifystrm node[19637]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 18 15:11:03 pifystrm node[19637]: *** WARNING *** For more information see
Dec 18 15:11:03 pifystrm volumio[19637]: info: Applying required configuration parameters for plugin volumiodiscovery
Dec 18 15:11:03 pifystrm volumio[19637]: info: Discovery: Started advertising with name: PiFyStrm
Dec 18 15:11:03 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 18 15:11:03 pifystrm volumio[19637]: info: Loading plugin "spop"...
Dec 18 15:11:04 pifystrm volumio[19637]: info: Loading plugin "squeezelite_mc"...
Dec 18 15:11:04 pifystrm ntpd[814]: Listen normally on 48 wlan0 10.1.12.92:123
Dec 18 15:11:04 pifystrm ntpd[814]: new interface(s) found: waking up resolver
Dec 18 15:11:04 pifystrm volumio[19637]: info: Loading plugin "now_playing"...
Dec 18 15:11:05 pifystrm volumio[19637]: info: Loading plugin "outputs"...
Dec 18 15:11:05 pifystrm volumio[19637]: info: Loading plugin "albumart"...
Dec 18 15:11:05 pifystrm volumio[19637]: info: Plugin example_plugin is not enabled
Dec 18 15:11:05 pifystrm volumio[19637]: info: Loading plugin "inputs"...
Dec 18 15:11:05 pifystrm volumio[19637]: info: Loading plugin "updater_comm"...
Dec 18 15:11:05 pifystrm volumio[19637]: info: Plugin mpdemulation is not enabled
Dec 18 15:11:05 pifystrm volumio[19637]: info: Loading plugin "rest_api"...
Dec 18 15:11:05 pifystrm volumio[19637]: info: Loading plugin "websocket"...
Dec 18 15:11:05 pifystrm volumio[19637]: info: Starting Socket.io Server version 2.3.0
Dec 18 15:11:05 pifystrm volumio[19637]: info: Loading plugin "touch_display"...
Dec 18 15:11:05 pifystrm volumio[19637]: Forking 3 albumart workers
Dec 18 15:11:05 pifystrm volumio-remote-updater[16014]: [2025-12-18 15:11:05] [connect] Successful connection
Dec 18 15:11:05 pifystrm volumio[19637]: info: Applying required configuration parameters for plugin touch_display
Dec 18 15:11:05 pifystrm volumio[19637]: info: Loading i18n strings for locale en
Dec 18 15:11:05 pifystrm volumio[19637]: Updating browse sources language
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreCommandRouter::initPlayerControls
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 18 15:11:05 pifystrm volumio[19637]: Express server listening on port 3000
Dec 18 15:11:05 pifystrm volumio[19637]: [Metrics] WebUI: 4s 439.98ms
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreStateMachine::resetVolumioState
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreStateMachine::getcurrentVolume
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreCommandRouter::volumioRetrievevolume
Dec 18 15:11:05 pifystrm volumio[19637]: info: CoreStateMachine::pushState
Dec 18 15:11:06 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:06 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 18 15:11:06 pifystrm volumio[19637]: info: CoreCommandRouter::volumioPushState
Dec 18 15:11:06 pifystrm volumio[19637]: info: Volumio Network Manager: Network status updated: 2
Dec 18 15:11:06 pifystrm volumio[19637]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1
Dec 18 15:11:06 pifystrm volumio[19637]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2
Dec 18 15:11:06 pifystrm volumio-remote-updater[16014]: [2025-12-18 15:11:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766088665 101
Dec 18 15:11:06 pifystrm volumio[19637]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3
Dec 18 15:11:06 pifystrm volumio[19637]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Dec 18 15:11:06 pifystrm volumio[19637]: Starting albumart workers
Dec 18 15:11:06 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:06 pifystrm volumio[19637]: info: Reloading queue from file
Dec 18 15:11:06 pifystrm volumio[19637]: Starting albumart workers
Dec 18 15:11:06 pifystrm volumio[19637]: Starting albumart workers
Dec 18 15:11:06 pifystrm volumio[19637]: info: CoreStateMachine::setRepeat false single undefined
Dec 18 15:11:06 pifystrm volumio[19637]: info: CoreStateMachine::pushState
Dec 18 15:11:06 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:06 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 18 15:11:06 pifystrm volumio[19637]: info: CoreCommandRouter::volumioPushState
Dec 18 15:11:06 pifystrm volumio[19637]: info: CoreStateMachine::setRandom false
Dec 18 15:11:06 pifystrm volumio[19637]: info: CoreStateMachine::pushState
Dec 18 15:11:06 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:06 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 18 15:11:06 pifystrm volumio[19637]: info: CoreCommandRouter::volumioPushState
Dec 18 15:11:06 pifystrm volumio[19637]: info: Setting Device type: Raspberry PI
Dec 18 15:11:06 pifystrm volumio[19637]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb
Dec 18 15:11:06 pifystrm volumio[19637]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115
Dec 18 15:11:06 pifystrm volumio[19637]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI
Dec 18 15:11:06 pifystrm volumio[19637]: info: Discovery: adding 8a7efacc-4a4c-45e8-b169-57dc6a52f98c
Dec 18 15:11:06 pifystrm volumio[19637]: info: Discovery: Found device PiFyStrm
Dec 18 15:11:06 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:11:06 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:07 pifystrm sudo[19699]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:07 pifystrm volumio[19637]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Dec 18 15:11:07 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 18 15:11:07 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetVisibleSources
Dec 18 15:11:07 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 18 15:11:07 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:11:07 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:07 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Dec 18 15:11:07 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Dec 18 15:11:07 pifystrm volumio[19637]: info: Received Get System Info
Dec 18 15:11:07 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 18 15:11:07 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 18 15:11:07 pifystrm volumio[19637]: info: Discovery: Getting this device information
Dec 18 15:11:07 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:11:07 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:07 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 18 15:11:07 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:11:07 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:07 pifystrm volumio[19637]: info: Listing playlists
Dec 18 15:11:07 pifystrm ntpd[814]: Soliciting pool server 23.186.168.128
Dec 18 15:11:08 pifystrm dhcpcd[941]: wlan0: carrier lost
Dec 18 15:11:08 pifystrm wpa_supplicant[929]: wlan0: Associated with 9c:c9:eb:9c:ee:61
Dec 18 15:11:08 pifystrm wpa_supplicant[929]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=]
Dec 18 15:11:08 pifystrm wpa_supplicant[929]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Dec 18 15:11:08 pifystrm avahi-daemon[649]: Withdrawing address record for 10.1.12.92 on wlan0.
Dec 18 15:11:08 pifystrm avahi-daemon[649]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92.
Dec 18 15:11:08 pifystrm dhcpcd[941]: wlan0: deleting route to 10.1.8.0/21
Dec 18 15:11:08 pifystrm dhcpcd[941]: wlan0: deleting default route via 10.1.10.1
Dec 18 15:11:08 pifystrm avahi-daemon[649]: Interface wlan0.IPv4 no longer relevant for mDNS.
Dec 18 15:11:08 pifystrm volumio[19637]: info: Discovery: A device disappeared from network
Dec 18 15:11:08 pifystrm volumio[19637]: info: Discovery: Device pifystrm disappeared from network
Dec 18 15:11:08 pifystrm dhcpcd[941]: wlan0: carrier acquired
Dec 18 15:11:08 pifystrm dhcpcd[941]: wlan0: IAID dd:0d:07:df
Dec 18 15:11:08 pifystrm dhcpcd[941]: wlan0: rebinding lease of 10.1.12.92
Dec 18 15:11:08 pifystrm dhcpcd[941]: wlan0: probing address 10.1.12.92/21
Dec 18 15:11:08 pifystrm dhcpcd[941]: wlan0: soliciting an IPv6 router
Dec 18 15:11:09 pifystrm ntpd[814]: Deleting interface #48 wlan0, 10.1.12.92#123, interface stats: received=5, sent=5, dropped=2, active_time=5 secs
Dec 18 15:11:09 pifystrm ntpd[814]: 12.205.28.193 local addr 10.1.12.92 ->
Dec 18 15:11:09 pifystrm ntpd[814]: 162.159.200.123 local addr 10.1.12.92 ->
Dec 18 15:11:09 pifystrm ntpd[814]: 166.88.142.52 local addr 10.1.12.92 ->
Dec 18 15:11:09 pifystrm ntpd[814]: 23.142.248.9 local addr 10.1.12.92 ->
Dec 18 15:11:09 pifystrm ntpd[814]: 137.110.222.27 local addr 10.1.12.92 ->
Dec 18 15:11:09 pifystrm ntpd[814]: 172.233.155.39 local addr 10.1.12.92 ->
Dec 18 15:11:13 pifystrm volumio[19637]: info: Volumio Network Manager: Network status updated: 0
Dec 18 15:11:13 pifystrm sudo[19847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 18 15:11:13 pifystrm sudo[19847]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:13 pifystrm sudo[19847]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:13 pifystrm sudo[19850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 18 15:11:13 pifystrm sudo[19850]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:13 pifystrm sudo[19850]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:13 pifystrm sudo[19853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Dec 18 15:11:13 pifystrm sudo[19853]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:13 pifystrm sudo[19714]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:13 pifystrm volumio[19637]: info: Cannot mount NAS Curaudio at system boot, trial number 1 ,retrying in 5 seconds
Dec 18 15:11:13 pifystrm volumio[19637]: info: Completed loading Core Plugins
Dec 18 15:11:13 pifystrm volumio[19637]: info: Preparing to generate the ALSA configuration file
Dec 18 15:11:13 pifystrm sudo[19853]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:13 pifystrm volumio[19637]: info: Upmpdcli Daemon Started
Dec 18 15:11:13 pifystrm volumio[19637]: info: Asound.conf file unchanged, so no further update is needed
Dec 18 15:11:13 pifystrm volumio[19637]: info: Output device has changed, restarting MPD
Dec 18 15:11:13 pifystrm sudo[19856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 18 15:11:13 pifystrm sudo[19856]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:13 pifystrm volumio[19637]: info: Output device has changed, restarting Shairport Sync
Dec 18 15:11:13 pifystrm sudo[19856]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:13 pifystrm sudo[19859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 18 15:11:13 pifystrm sudo[19859]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:13 pifystrm systemd[1]: Stopping Music Player Daemon...
Dec 18 15:11:13 pifystrm systemd[1]: mpd.service: Succeeded.
Dec 18 15:11:13 pifystrm volumio[19637]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 18 15:11:13 pifystrm systemd[1]: Stopped Music Player Daemon.
Dec 18 15:11:13 pifystrm volumio[19637]: info: ___________ START PLUGINS ___________
Dec 18 15:11:13 pifystrm volumio[19637]: info: ControllerMpd::onStart: Initializing MPD
Dec 18 15:11:13 pifystrm volumio[19637]: info: Creating MPD Configuration file
Dec 18 15:11:13 pifystrm sudo[19866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 18 15:11:13 pifystrm sudo[19866]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 18 15:11:13 pifystrm volumio[19637]: info: [1766088673318] CoreMusicLibrary::Adding element Media Servers
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 18 15:11:13 pifystrm sudo[19866]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:13 pifystrm sudo[19869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 18 15:11:13 pifystrm sudo[19869]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:13 pifystrm systemd[1]: Starting Music Player Daemon...
Dec 18 15:11:13 pifystrm systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Dec 18 15:11:13 pifystrm systemd[1]: mpd.service: Succeeded.
Dec 18 15:11:13 pifystrm systemd[1]: Stopped Music Player Daemon.
Dec 18 15:11:13 pifystrm systemd[1]: Starting Music Player Daemon...
Dec 18 15:11:13 pifystrm volumio[19637]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:13 pifystrm volumio[19637]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 18 15:11:13 pifystrm volumio[19637]: info: [1766088673416] CoreMusicLibrary::Adding element Last_100
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 18 15:11:13 pifystrm sudo[19874]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Dec 18 15:11:13 pifystrm sudo[19874]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:13 pifystrm volumio[19637]: info: [1766088673420] CoreMusicLibrary::Adding element Webradio
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 18 15:11:13 pifystrm sudo[19874]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:13 pifystrm volumio[19637]: info: Initializing BBC Radios
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:13 pifystrm volumio[19637]: info: Checking for old spotify connect plugin installed
Dec 18 15:11:13 pifystrm volumio[19637]: info: [squeezelite_mc] Starting proxy server...
Dec 18 15:11:13 pifystrm dhcpcd[941]: wlan0: leased 10.1.12.92 for 604800 seconds
Dec 18 15:11:13 pifystrm avahi-daemon[649]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92.
Dec 18 15:11:13 pifystrm dhcpcd[941]: wlan0: adding route to 10.1.8.0/21
Dec 18 15:11:13 pifystrm dhcpcd[941]: wlan0: adding default route via 10.1.10.1
Dec 18 15:11:13 pifystrm avahi-daemon[649]: New relevant interface wlan0.IPv4 for mDNS.
Dec 18 15:11:13 pifystrm avahi-daemon[649]: Registering new address record for 10.1.12.92 on wlan0.IPv4.
Dec 18 15:11:13 pifystrm volumio[19637]: info: [now-playing-config] Config is up to date.
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 18 15:11:13 pifystrm volumio[19637]: info: Discovery: Getting this device information
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:11:13 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 18 15:11:13 pifystrm volumio[19637]: info: Loading i18n strings for locale en
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 18 15:11:13 pifystrm volumio[19637]: info: Volumio Calling Home
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 18 15:11:13 pifystrm volumio[19637]: [SpotifyConnect] Creating VLS config file
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:13 pifystrm volumio[19637]: [SpotifyConnect] Starting metadata listener
Dec 18 15:11:13 pifystrm volumio[19637]: info: [squeezelite_mc] Proxy server started on port 34751
Dec 18 15:11:13 pifystrm volumio[19637]: info: [now-playing-app] App is listening on port 4004.
Dec 18 15:11:13 pifystrm sudo[19916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service
Dec 18 15:11:13 pifystrm sudo[19916]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:13 pifystrm sudo[19919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Dec 18 15:11:13 pifystrm sudo[19919]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:13 pifystrm sudo[19916]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:13 pifystrm systemd[1]: Reloading.
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::volumioRetrievevolume
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreStateMachine::pushState
Dec 18 15:11:13 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::volumioPushState
Dec 18 15:11:13 pifystrm volumio[19637]: info: [squeezelite_mc] Server discovery started
Dec 18 15:11:13 pifystrm volumio[19637]: info: [squeezelite_mc] Player finder started
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 18 15:11:13 pifystrm volumio[19637]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true
Dec 18 15:11:13 pifystrm volumio[19637]: Unhandled rejection Error: No sockets available, cannot start.
Dec 18 15:11:13 pifystrm volumio[19637]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11)
Dec 18 15:11:13 pifystrm volumio[19637]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10)
Dec 18 15:11:13 pifystrm volumio[19637]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10
Dec 18 15:11:13 pifystrm volumio[19637]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9)
Dec 18 15:11:13 pifystrm volumio[19637]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18)
Dec 18 15:11:13 pifystrm volumio[19637]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10)
Dec 18 15:11:13 pifystrm volumio[19637]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10)
Dec 18 15:11:13 pifystrm volumio[19637]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17)
Dec 18 15:11:13 pifystrm volumio[19637]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12)
Dec 18 15:11:13 pifystrm volumio[19637]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24)
Dec 18 15:11:13 pifystrm volumio[19637]: at HashMap. (/volumio/app/pluginmanager.js:496:31)
Dec 18 15:11:13 pifystrm volumio[19637]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10)
Dec 18 15:11:13 pifystrm volumio[19637]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7)
Dec 18 15:11:13 pifystrm volumio[19637]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20)
Dec 18 15:11:13 pifystrm volumio[19637]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29)
Dec 18 15:11:13 pifystrm volumio[19637]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
Dec 18 15:11:13 pifystrm volumio[19637]: [SpotifyConnect] Vollibrespot Daemon service started!
Dec 18 15:11:13 pifystrm volumio[19637]: [Metrics] SpotifyConnect: 0s 290.18ms
Dec 18 15:11:13 pifystrm volumio[19637]: info: MPD Permissions set
Dec 18 15:11:13 pifystrm volumio[19637]: info: MPD Permissions set
Dec 18 15:11:13 pifystrm volumio[19637]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["S16_LE","S24_LE"]
Dec 18 15:11:13 pifystrm volumio[19637]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"pifystrm","dsdFormat":"dop","card":"1","mixerType":"None","mixer":null}
Dec 18 15:11:13 pifystrm volumio[19637]: 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
Dec 18 15:11:13 pifystrm volumio[19637]: info: Volumio called home
Dec 18 15:11:13 pifystrm volumio[19637]: info: [squeezelite_mc] Server discovered: {"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}
Dec 18 15:11:14 pifystrm volumio[19637]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Dec 18 15:11:14 pifystrm volumio[19637]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm sudo[19939]: 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
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm sudo[19939]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:14 pifystrm sudo[19939]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:14 pifystrm 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.
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:14 pifystrm volumio[19637]: info: [squeezelite_mc] Executing alsactl -L -R nrestore
Dec 18 15:11:14 pifystrm volumio[19637]: info: touch_display: Raspberry Pi Foundation touch screen detected.
Dec 18 15:11:14 pifystrm volumio[19637]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected.
Dec 18 15:11:14 pifystrm sudo[19951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore
Dec 18 15:11:14 pifystrm sudo[19951]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:14 pifystrm volumio[19637]: info: Starting Shairport Sync
Dec 18 15:11:14 pifystrm sudo[19951]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:14 pifystrm volumio[19637]: info: Starting Shairport Sync
Dec 18 15:11:14 pifystrm sudo[19954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 18 15:11:14 pifystrm sudo[19954]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:14 pifystrm volumio[19637]: info: Starting Shairport Sync
Dec 18 15:11:14 pifystrm sudo[19957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 18 15:11:14 pifystrm sudo[19957]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:14 pifystrm systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly.
Dec 18 15:11:14 pifystrm sudo[19959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 18 15:11:14 pifystrm sudo[19959]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:11:14 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:14 pifystrm volumio[19637]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service
Dec 18 15:11:14 pifystrm sudo[19966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness
Dec 18 15:11:14 pifystrm sudo[19966]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:14 pifystrm sudo[19964]: 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
Dec 18 15:11:14 pifystrm sudo[19964]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:14 pifystrm sudo[19966]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:14 pifystrm volumio[19637]: info: touch_display: File permissions for backlight brightness control set.
Dec 18 15:11:14 pifystrm sudo[19964]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:14 pifystrm volumio[19637]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true
Dec 18 15:11:14 pifystrm mpd[19879]: Dec 18 15:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 18 15:11:14 pifystrm volumio[19637]: info: Discovery: adding 8a7efacc-4a4c-45e8-b169-57dc6a52f98c
Dec 18 15:11:14 pifystrm volumio[19637]: info: Discovery: Found device PiFyStrm
Dec 18 15:11:14 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:11:14 pifystrm sudo[19919]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:14 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:14 pifystrm systemd[1]: Started Music Player Daemon.
Dec 18 15:11:14 pifystrm sudo[19869]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:14 pifystrm sudo[19859]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:14 pifystrm volumio[19637]: info: touch_display: systemctl daemon-reload succeeded.
Dec 18 15:11:14 pifystrm sudo[19972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Dec 18 15:11:14 pifystrm sudo[19972]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:14 pifystrm systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Dec 18 15:11:14 pifystrm systemd[1]: shairport-sync.service: Succeeded.
Dec 18 15:11:14 pifystrm systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Dec 18 15:11:14 pifystrm ntpd[814]: Listen normally on 49 wlan0 10.1.12.92:123
Dec 18 15:11:14 pifystrm ntpd[814]: new interface(s) found: waking up resolver
Dec 18 15:11:14 pifystrm systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Dec 18 15:11:14 pifystrm sudo[19977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service
Dec 18 15:11:14 pifystrm sudo[19957]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:14 pifystrm sudo[19954]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:14 pifystrm sudo[19959]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:14 pifystrm systemd[1]: Reloading.
Dec 18 15:11:14 pifystrm sudo[19977]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:14 pifystrm volumio[19637]: info: Shairport-Sync Started
Dec 18 15:11:14 pifystrm volumio[19637]: Error adding Membership: Error: addMembership EINVAL
Dec 18 15:11:14 pifystrm volumio[19637]: info: Shairport-Sync Started
Dec 18 15:11:14 pifystrm volumio[19637]: error: MPD error: The expression evaluated to a falsy value:
Dec 18 15:11:14 pifystrm volumio[19637]: assert.ok(self.idling)
Dec 18 15:11:14 pifystrm volumio[19637]: error: The expression evaluated to a falsy value:
Dec 18 15:11:14 pifystrm volumio[19637]: assert.ok(self.idling)
Dec 18 15:11:14 pifystrm volumio[19637]: info: Shairport-Sync Started
Dec 18 15:11:14 pifystrm volumio[19637]: error: updateQueue error: null
Dec 18 15:11:14 pifystrm volumio[19637]: info: MPD running with PID19879
Dec 18 15:11:14 pifystrm volumio[19637]: ,establishing connection
Dec 18 15:11:14 pifystrm volumio[19637]: error: updateQueue error: null
Dec 18 15:11:14 pifystrm 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.
Dec 18 15:11:15 pifystrm systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly.
Dec 18 15:11:15 pifystrm volumio[19637]: info: [squeezelite_mc] Notification listener started
Dec 18 15:11:15 pifystrm volumio[19637]: info: [squeezelite_mc] Getting players connected to Gamez-PD (10.1.14.16)
Dec 18 15:11:15 pifystrm sudo[19972]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:15 pifystrm volumio[19637]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Dec 18 15:11:15 pifystrm sudo[19977]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:15 pifystrm volumio[19637]: info: touch_display: systemctl start volumio-kiosk.service succeeded.
Dec 18 15:11:15 pifystrm volumio[19637]: info: touch_display: Volumio Kiosk started
Dec 18 15:11:15 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:11:15 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:15 pifystrm sudo[20011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Dec 18 15:11:15 pifystrm sudo[20011]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:15 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:11:15 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:15 pifystrm volumio[19637]: info: [squeezelite_mc] Players connected to Gamez-PD (10.1.14.16): [{"id":"bb:bb:17:34:c5:ca","uuid":null,"ip":"10.1.14.16","name":"GoogleTV5315","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:de:b5:4a:65","uuid":null,"ip":"10.1.14.16","name":"YamahaAVR","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"d8:3a:dd:0d:07:df","uuid":null,"ip":"10.1.12.92","name":"pifystrm","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:33:fc:1a:20","uuid":null,"ip":"10.1.14.16","name":"Training Room 9","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:86:4c:c5:16","uuid":null,"ip":"10.1.14.16","name":"Samsung Q6DAA 50 TV","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:2d:2b:10:5e","uuid":null,"ip":"10.1.14.16","name":"SHIELD","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:b0:50:2f:c9","uuid":null,"ip":"10.1.14.16","name":"IntegraTHX","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}}]
Dec 18 15:11:15 pifystrm volumio[19637]: info: [squeezelite_mc] Player found: {"id":"d8:3a:dd:0d:07:df","uuid":null,"ip":"10.1.12.92","name":"pifystrm","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}}
Dec 18 15:11:15 pifystrm sudo[20011]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:15 pifystrm volumio[19637]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true
Dec 18 15:11:15 pifystrm volumio[19637]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Dec 18 15:11:15 pifystrm sudo[20016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite
Dec 18 15:11:15 pifystrm sudo[20016]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:15 pifystrm systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator...
Dec 18 15:11:15 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:11:15 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:15 pifystrm volumio[19637]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Dec 18 15:11:15 pifystrm volumio[19637]: info: touch_display: Setting screensaver timeout to 120 seconds.
Dec 18 15:11:15 pifystrm volumio[19637]: info: [squeezelite_mc] 'client' notification received from Gamez-PD (10.1.14.16); type is 'disconnect'
Dec 18 15:11:15 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:11:15 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:15 pifystrm systemd[1]: squeezelite.service: Succeeded.
Dec 18 15:11:15 pifystrm systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator.
Dec 18 15:11:15 pifystrm sudo[20016]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:16 pifystrm volumio[19637]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Dec 18 15:11:16 pifystrm sudo[20028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Dec 18 15:11:16 pifystrm sudo[20028]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:16 pifystrm sudo[20028]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:16 pifystrm volumio[19637]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log
Dec 18 15:11:16 pifystrm sudo[20032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log
Dec 18 15:11:16 pifystrm sudo[20032]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:16 pifystrm sudo[20032]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:16 pifystrm volumio[19637]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true
Dec 18 15:11:16 pifystrm sudo[20035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite
Dec 18 15:11:16 pifystrm sudo[20035]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:16 pifystrm systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator...
Dec 18 15:11:16 pifystrm systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator.
Dec 18 15:11:16 pifystrm sudo[20035]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:16 pifystrm volumio[19637]: (node:19637) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined
Dec 18 15:11:16 pifystrm volumio[19637]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37)
Dec 18 15:11:16 pifystrm volumio[19637]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129)
Dec 18 15:11:16 pifystrm volumio[19637]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Dec 18 15:11:16 pifystrm volumio[19637]: at async PlayerStatusMonitor.start (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:47:9)
Dec 18 15:11:16 pifystrm volumio[19637]: at async PlayerFinder. (/data/plugins/music_service/squeezelite_mc/dist/index.js:686:13)
Dec 18 15:11:16 pifystrm volumio[19637]: (Use `node --trace-warnings ...` to show where the warning was created)
Dec 18 15:11:16 pifystrm volumio[19637]: (node:19637) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1)
Dec 18 15:11:16 pifystrm volumio[19637]: (node:19637) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code.
Dec 18 15:11:16 pifystrm volumio[19637]: info: [squeezelite_mc] 'client' notification received from Gamez-PD (10.1.14.16); type is 'connect'
Dec 18 15:11:16 pifystrm volumio[19637]: info: [squeezelite_mc] Getting players connected to Gamez-PD (10.1.14.16)
Dec 18 15:11:16 pifystrm volumio[19637]: info: [squeezelite_mc] Players connected to Gamez-PD (10.1.14.16): [{"id":"bb:bb:17:34:c5:ca","uuid":null,"ip":"10.1.14.16","name":"GoogleTV5315","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:de:b5:4a:65","uuid":null,"ip":"10.1.14.16","name":"YamahaAVR","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"d8:3a:dd:0d:07:df","uuid":null,"ip":"10.1.12.92","name":"pifystrm","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:33:fc:1a:20","uuid":null,"ip":"10.1.14.16","name":"Training Room 9","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:86:4c:c5:16","uuid":null,"ip":"10.1.14.16","name":"Samsung Q6DAA 50 TV","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:2d:2b:10:5e","uuid":null,"ip":"10.1.14.16","name":"SHIELD","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:b0:50:2f:c9","uuid":null,"ip":"10.1.14.16","name":"IntegraTHX","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}}]
Dec 18 15:11:16 pifystrm volumio[19637]: info: [squeezelite_mc] Player found: {"id":"d8:3a:dd:0d:07:df","uuid":null,"ip":"10.1.12.92","name":"pifystrm","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}}
Dec 18 15:11:16 pifystrm volumio[19637]: (node:19637) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined
Dec 18 15:11:16 pifystrm volumio[19637]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37)
Dec 18 15:11:16 pifystrm volumio[19637]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129)
Dec 18 15:11:16 pifystrm volumio[19637]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Dec 18 15:11:16 pifystrm volumio[19637]: (node:19637) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2)
Dec 18 15:11:16 pifystrm volumio[19637]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Dec 18 15:11:16 pifystrm sudo[20042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Dec 18 15:11:16 pifystrm sudo[20042]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:16 pifystrm sudo[20042]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:17 pifystrm volumio[19637]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Dec 18 15:11:17 pifystrm sudo[20045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Dec 18 15:11:17 pifystrm sudo[20045]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:17 pifystrm sudo[20045]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:17 pifystrm sudo[20048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 18 15:11:17 pifystrm sudo[20048]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:17 pifystrm sudo[20051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 18 15:11:17 pifystrm systemd[1]: Stopping MPD Monitor Service...
Dec 18 15:11:17 pifystrm sudo[20051]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:17 pifystrm systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM
Dec 18 15:11:17 pifystrm systemd[1]: mpd_monitor.service: Succeeded.
Dec 18 15:11:17 pifystrm systemd[1]: Stopped MPD Monitor Service.
Dec 18 15:11:17 pifystrm volumio[19637]: (node:19637) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined
Dec 18 15:11:17 pifystrm volumio[19637]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37)
Dec 18 15:11:17 pifystrm volumio[19637]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129)
Dec 18 15:11:17 pifystrm volumio[19637]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Dec 18 15:11:17 pifystrm volumio[19637]: at async PlayerStatusMonitor.start (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:47:9)
Dec 18 15:11:17 pifystrm volumio[19637]: at async PlayerFinder. (/data/plugins/music_service/squeezelite_mc/dist/index.js:686:13)
Dec 18 15:11:17 pifystrm volumio[19637]: (node:19637) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 3)
Dec 18 15:11:17 pifystrm systemd[1]: Started MPD Monitor Service.
Dec 18 15:11:17 pifystrm mpd_monitor.sh[20053]: MPD Monitor Service: Starting MPD Monitor Service
Dec 18 15:11:17 pifystrm sudo[20048]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:17 pifystrm systemd[1]: Stopping MPD Monitor Service...
Dec 18 15:11:17 pifystrm systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM
Dec 18 15:11:17 pifystrm systemd[1]: mpd_monitor.service: Succeeded.
Dec 18 15:11:17 pifystrm systemd[1]: Stopped MPD Monitor Service.
Dec 18 15:11:17 pifystrm volumio[19637]: info: Successfully started MPD Monitor
Dec 18 15:11:17 pifystrm systemd[1]: Started MPD Monitor Service.
Dec 18 15:11:17 pifystrm sudo[20051]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:17 pifystrm volumio[19637]: info: Successfully started MPD Monitor
Dec 18 15:11:17 pifystrm mpd_monitor.sh[20056]: MPD Monitor Service: Starting MPD Monitor Service
Dec 18 15:11:17 pifystrm volumio[19637]: info: Checking Spotify Web API
Dec 18 15:11:17 pifystrm volumio[19637]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Dec 18 15:11:17 pifystrm sudo[20061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Dec 18 15:11:17 pifystrm sudo[20061]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:17 pifystrm sudo[20061]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:18 pifystrm sudo[20064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio
Dec 18 15:11:18 pifystrm sudo[20064]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:18 pifystrm volumio[19637]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Dec 18 15:11:18 pifystrm sudo[20068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Dec 18 15:11:18 pifystrm sudo[20068]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:18 pifystrm sudo[20068]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:18 pifystrm dhcpcd[941]: wlan0: carrier lost
Dec 18 15:11:18 pifystrm wpa_supplicant[929]: wlan0: Associated with 9c:c9:eb:9c:ee:61
Dec 18 15:11:18 pifystrm wpa_supplicant[929]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=]
Dec 18 15:11:18 pifystrm wpa_supplicant[929]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Dec 18 15:11:18 pifystrm avahi-daemon[649]: Withdrawing address record for 10.1.12.92 on wlan0.
Dec 18 15:11:18 pifystrm avahi-daemon[649]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92.
Dec 18 15:11:19 pifystrm avahi-daemon[649]: Interface wlan0.IPv4 no longer relevant for mDNS.
Dec 18 15:11:19 pifystrm dhcpcd[941]: wlan0: deleting route to 10.1.8.0/21
Dec 18 15:11:19 pifystrm dhcpcd[941]: wlan0: deleting default route via 10.1.10.1
Dec 18 15:11:19 pifystrm volumio[19637]: info: Discovery: A device disappeared from network
Dec 18 15:11:19 pifystrm volumio[19637]: info: Discovery: Device pifystrm disappeared from network
Dec 18 15:11:19 pifystrm dhcpcd[941]: wlan0: carrier acquired
Dec 18 15:11:19 pifystrm dhcpcd[941]: wlan0: IAID dd:0d:07:df
Dec 18 15:11:19 pifystrm volumio[19637]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Dec 18 15:11:19 pifystrm sudo[20103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Dec 18 15:11:19 pifystrm sudo[20103]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:19 pifystrm sudo[20103]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:19 pifystrm volumio[19637]: info: Completed starting Core Plugins
Dec 18 15:11:19 pifystrm volumio[19637]: info: -------------------------------------------
Dec 18 15:11:19 pifystrm volumio[19637]: info: ----- MyVolumio plugins startup ----
Dec 18 15:11:19 pifystrm volumio[19637]: info: -------------------------------------------
Dec 18 15:11:19 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Fetching plans data....
Dec 18 15:11:19 pifystrm dhcpcd[941]: wlan0: rebinding lease of 10.1.12.92
Dec 18 15:11:19 pifystrm volumio[19637]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Dec 18 15:11:19 pifystrm dhcpcd[941]: wlan0: soliciting an IPv6 router
Dec 18 15:11:19 pifystrm dhcpcd[941]: wlan0: probing address 10.1.12.92/21
Dec 18 15:11:20 pifystrm ntpd[814]: Deleting interface #49 wlan0, 10.1.12.92#123, interface stats: received=6, sent=6, dropped=0, active_time=6 secs
Dec 18 15:11:20 pifystrm ntpd[814]: 12.205.28.193 local addr 10.1.12.92 ->
Dec 18 15:11:20 pifystrm ntpd[814]: 162.159.200.123 local addr 10.1.12.92 ->
Dec 18 15:11:20 pifystrm ntpd[814]: 166.88.142.52 local addr 10.1.12.92 ->
Dec 18 15:11:20 pifystrm ntpd[814]: 23.142.248.9 local addr 10.1.12.92 ->
Dec 18 15:11:20 pifystrm ntpd[814]: 137.110.222.27 local addr 10.1.12.92 ->
Dec 18 15:11:20 pifystrm ntpd[814]: 172.233.155.39 local addr 10.1.12.92 ->
Dec 18 15:11:23 pifystrm ntpd[814]: Soliciting pool server 2605:6400:488d:5105:e3bf:81f2:fce8:3b7f
Dec 18 15:11:24 pifystrm dhcpcd[941]: wlan0: leased 10.1.12.92 for 604800 seconds
Dec 18 15:11:24 pifystrm avahi-daemon[649]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92.
Dec 18 15:11:24 pifystrm avahi-daemon[649]: New relevant interface wlan0.IPv4 for mDNS.
Dec 18 15:11:24 pifystrm avahi-daemon[649]: Registering new address record for 10.1.12.92 on wlan0.IPv4.
Dec 18 15:11:24 pifystrm dhcpcd[941]: wlan0: adding route to 10.1.8.0/21
Dec 18 15:11:24 pifystrm dhcpcd[941]: wlan0: adding default route via 10.1.10.1
Dec 18 15:11:26 pifystrm ntpd[814]: Listen normally on 50 wlan0 10.1.12.92:123
Dec 18 15:11:26 pifystrm ntpd[814]: new interface(s) found: waking up resolver
Dec 18 15:11:30 pifystrm volumio[19637]: info: Discovery: adding 8a7efacc-4a4c-45e8-b169-57dc6a52f98c
Dec 18 15:11:30 pifystrm volumio[19637]: info: Discovery: Found device PiFyStrm
Dec 18 15:11:30 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:11:30 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Dec 18 15:11:31 pifystrm volumio[19637]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 18 15:11:31 pifystrm volumio[19637]: info: Adding plugin multiroom to MyMusic Plugins
Dec 18 15:11:31 pifystrm volumio[19637]: info: Adding plugin metavolumio to MyMusic Plugins
Dec 18 15:11:31 pifystrm volumio[19637]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 18 15:11:31 pifystrm volumio[19637]: info: Adding plugin qobuzconnect to MyMusic Plugins
Dec 18 15:11:31 pifystrm volumio[19637]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 18 15:11:31 pifystrm volumio[19637]: info: Adding plugin tidalconnect to MyMusic Plugins
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 18 15:11:31 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:31 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:31 pifystrm volumio[19637]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 18 15:11:31 pifystrm volumio[19637]: info: MyVolumio login type: Token
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 18 15:11:31 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 18 15:11:32 pifystrm volumio[19637]: info: Starting Streaming Service Transparent Proxy
Dec 18 15:11:32 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 18 15:11:32 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 18 15:11:32 pifystrm volumio[19637]: info: Streaming services startup
Dec 18 15:11:32 pifystrm volumio[19637]: info: Starting Streaming Daemon
Dec 18 15:11:32 pifystrm sudo[20166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 18 15:11:32 pifystrm sudo[20166]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:32 pifystrm sudo[20166]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:32 pifystrm volumio[19637]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 18 15:11:32 pifystrm volumio[19637]: error: Cannot start Volumio Streaming Daemon
Dec 18 15:11:32 pifystrm volumio[19637]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 18 15:11:32 pifystrm volumio[19637]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 18 15:11:32 pifystrm volumio[19637]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Dec 18 15:11:33 pifystrm volumio[19637]: info: Volumio Network Manager: Network status updated: 2
Dec 18 15:11:33 pifystrm volumio[19637]: STREAMING PROXY: Starting server on port 3245
Dec 18 15:11:33 pifystrm volumio[19637]: Node JS runtime: 14
Dec 18 15:11:36 pifystrm volumio[19637]: info: MyVolumio token set successfully
Dec 18 15:11:36 pifystrm volumio[19637]: info: MYVOLUMIO: Adding device
Dec 18 15:11:36 pifystrm volumio[19637]: info: MYVOLUMIO: Evaluating Server
Dec 18 15:11:37 pifystrm volumio[19637]: info: MyVolumio status changed
Dec 18 15:11:37 pifystrm volumio[19637]: info: Streaming services startup
Dec 18 15:11:37 pifystrm volumio[19637]: info: Starting Streaming Daemon
Dec 18 15:11:37 pifystrm volumio[19637]: info: Removing browser output: myVolumio user plan is not superstar
Dec 18 15:11:37 pifystrm volumio[19637]: info: Removing audio output:
Dec 18 15:11:37 pifystrm volumio[19637]: info: Stoppping Tunnel 1
Dec 18 15:11:37 pifystrm sudo[20249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 18 15:11:37 pifystrm sudo[20249]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:37 pifystrm sudo[20252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Dec 18 15:11:37 pifystrm sudo[20252]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:11:37 pifystrm sudo[20249]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:37 pifystrm volumio[19637]: error: Cannot start Volumio Streaming Daemon
Dec 18 15:11:37 pifystrm volumio[19637]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 18 15:11:37 pifystrm volumio[19637]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 18 15:11:37 pifystrm sudo[20252]: pam_unix(sudo:session): session closed for user root
Dec 18 15:11:37 pifystrm volumio[19637]: info: Remote SSH Stopped
Dec 18 15:11:37 pifystrm volumio[19637]: info: Setting Geolocation for MyVolumio to us4
Dec 18 15:11:37 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:37 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:37 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:38 pifystrm volumio[19637]: info: Updating MyVolumio device info
Dec 18 15:11:38 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:39 pifystrm volumio[19637]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 18 15:11:43 pifystrm volumio[19637]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 18 15:11:43 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 18 15:11:43 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:43 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 18 15:11:43 pifystrm volumio-remote-updater[16014]: No test mode
Dec 18 15:11:43 pifystrm volumio-remote-updater[16014]: No alpha test mode
Dec 18 15:11:43 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 18 15:11:43 pifystrm volumio[19637]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Fix TIDAL low quality (AAC) playback
- Fix for DLNA Server discovery
- Fix for Plugins installation
NEW ADDITIONS
- BLE-based onboarding setup
- Update TIDAL SDK
","title":"Update v3.886","updateavailable":true}
Dec 18 15:11:43 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 18 15:11:46 pifystrm wpa_supplicant[929]: wlan0: Associated with 9c:c9:eb:9c:ee:61
Dec 18 15:11:46 pifystrm dhcpcd[941]: wlan0: carrier lost
Dec 18 15:11:46 pifystrm wpa_supplicant[929]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=]
Dec 18 15:11:46 pifystrm wpa_supplicant[929]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Dec 18 15:11:46 pifystrm avahi-daemon[649]: Withdrawing address record for 10.1.12.92 on wlan0.
Dec 18 15:11:46 pifystrm avahi-daemon[649]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92.
Dec 18 15:11:46 pifystrm dhcpcd[941]: wlan0: deleting route to 10.1.8.0/21
Dec 18 15:11:46 pifystrm dhcpcd[941]: wlan0: deleting default route via 10.1.10.1
Dec 18 15:11:46 pifystrm avahi-daemon[649]: Interface wlan0.IPv4 no longer relevant for mDNS.
Dec 18 15:11:46 pifystrm volumio[19637]: info: Discovery: A device disappeared from network
Dec 18 15:11:46 pifystrm volumio[19637]: info: Discovery: Device pifystrm disappeared from network
Dec 18 15:11:46 pifystrm dhcpcd[941]: wlan0: carrier acquired
Dec 18 15:11:46 pifystrm dhcpcd[941]: wlan0: IAID dd:0d:07:df
Dec 18 15:11:46 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 18 15:11:46 pifystrm volumio[19637]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Dec 18 15:11:46 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:11:46 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:47 pifystrm volumio[19637]: info: MYVOLUMIO: Adding device
Dec 18 15:11:47 pifystrm volumio[19637]: info: MYVOLUMIO: Evaluating Server
Dec 18 15:11:47 pifystrm dhcpcd[941]: wlan0: soliciting an IPv6 router
Dec 18 15:11:47 pifystrm dhcpcd[941]: wlan0: rebinding lease of 10.1.12.92
Dec 18 15:11:47 pifystrm dhcpcd[941]: wlan0: probing address 10.1.12.92/21
Dec 18 15:11:48 pifystrm ntpd[814]: Deleting interface #50 wlan0, 10.1.12.92#123, interface stats: received=6, sent=6, dropped=0, active_time=22 secs
Dec 18 15:11:48 pifystrm ntpd[814]: 12.205.28.193 local addr 10.1.12.92 ->
Dec 18 15:11:48 pifystrm ntpd[814]: 162.159.200.123 local addr 10.1.12.92 ->
Dec 18 15:11:48 pifystrm ntpd[814]: 166.88.142.52 local addr 10.1.12.92 ->
Dec 18 15:11:48 pifystrm ntpd[814]: 23.142.248.9 local addr 10.1.12.92 ->
Dec 18 15:11:48 pifystrm ntpd[814]: 137.110.222.27 local addr 10.1.12.92 ->
Dec 18 15:11:48 pifystrm ntpd[814]: 172.233.155.39 local addr 10.1.12.92 ->
Dec 18 15:11:51 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:51 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:51 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 18 15:11:51 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 18 15:11:51 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 18 15:11:51 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 18 15:11:51 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 18 15:11:51 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 18 15:11:52 pifystrm volumio[19637]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Dec 18 15:11:52 pifystrm volumio[19637]: info: Completed starting MyVolumio Plugin
Dec 18 15:11:52 pifystrm volumio[19637]: [Metrics] CommandRouter: 50s 724.51ms
Dec 18 15:11:52 pifystrm volumio[19637]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 18 15:11:52 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:11:52 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:52 pifystrm volumio[19637]: info: CoreCommandRouter::Close All Modals sent
Dec 18 15:11:52 pifystrm volumio[19637]: info: CoreCommandRouter::Close All Modals sent
Dec 18 15:11:53 pifystrm volumio[19637]: info: Volumio Network Manager: Network status updated: 0
Dec 18 15:11:53 pifystrm dhcpcd[941]: wlan0: leased 10.1.12.92 for 604800 seconds
Dec 18 15:11:53 pifystrm avahi-daemon[649]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92.
Dec 18 15:11:53 pifystrm avahi-daemon[649]: New relevant interface wlan0.IPv4 for mDNS.
Dec 18 15:11:53 pifystrm avahi-daemon[649]: Registering new address record for 10.1.12.92 on wlan0.IPv4.
Dec 18 15:11:53 pifystrm dhcpcd[941]: wlan0: adding route to 10.1.8.0/21
Dec 18 15:11:53 pifystrm dhcpcd[941]: wlan0: adding default route via 10.1.10.1
Dec 18 15:11:53 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 18 15:11:53 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 18 15:11:53 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 18 15:11:54 pifystrm volumio[19637]: info: Discovery: adding 8a7efacc-4a4c-45e8-b169-57dc6a52f98c
Dec 18 15:11:54 pifystrm volumio[19637]: info: Discovery: Found device PiFyStrm
Dec 18 15:11:54 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:11:54 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:11:54 pifystrm ntpd[814]: Listen normally on 51 wlan0 10.1.12.92:123
Dec 18 15:11:54 pifystrm ntpd[814]: new interface(s) found: waking up resolver
Dec 18 15:11:54 pifystrm volumio[19637]: info: Setting Geolocation for MyVolumio to us4
Dec 18 15:11:54 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:54 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:54 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:55 pifystrm volumio[19637]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 18 15:11:55 pifystrm volumio[19637]: info: Updating MyVolumio device info
Dec 18 15:11:55 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:11:56 pifystrm volumio[19637]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 18 15:11:58 pifystrm wpa_supplicant[929]: wlan0: Associated with 9c:c9:eb:9c:ee:61
Dec 18 15:11:58 pifystrm dhcpcd[941]: wlan0: carrier lost
Dec 18 15:11:58 pifystrm wpa_supplicant[929]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=]
Dec 18 15:11:58 pifystrm wpa_supplicant[929]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Dec 18 15:11:58 pifystrm avahi-daemon[649]: Withdrawing address record for 10.1.12.92 on wlan0.
Dec 18 15:11:58 pifystrm avahi-daemon[649]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92.
Dec 18 15:11:58 pifystrm dhcpcd[941]: wlan0: deleting route to 10.1.8.0/21
Dec 18 15:11:58 pifystrm dhcpcd[941]: wlan0: deleting default route via 10.1.10.1
Dec 18 15:11:58 pifystrm avahi-daemon[649]: Interface wlan0.IPv4 no longer relevant for mDNS.
Dec 18 15:11:58 pifystrm volumio[19637]: info: Discovery: A device disappeared from network
Dec 18 15:11:58 pifystrm volumio[19637]: info: Discovery: Device pifystrm disappeared from network
Dec 18 15:11:58 pifystrm dhcpcd[941]: wlan0: carrier acquired
Dec 18 15:11:58 pifystrm dhcpcd[941]: wlan0: IAID dd:0d:07:df
Dec 18 15:11:58 pifystrm dhcpcd[941]: wlan0: soliciting an IPv6 router
Dec 18 15:11:59 pifystrm volumio[19637]: info: BOOT COMPLETED
Dec 18 15:11:59 pifystrm dhcpcd[941]: wlan0: rebinding lease of 10.1.12.92
Dec 18 15:11:59 pifystrm dhcpcd[941]: wlan0: probing address 10.1.12.92/21
Dec 18 15:12:00 pifystrm ntpd[814]: Deleting interface #51 wlan0, 10.1.12.92#123, interface stats: received=6, sent=6, dropped=0, active_time=6 secs
Dec 18 15:12:00 pifystrm ntpd[814]: 12.205.28.193 local addr 10.1.12.92 ->
Dec 18 15:12:00 pifystrm ntpd[814]: 162.159.200.123 local addr 10.1.12.92 ->
Dec 18 15:12:00 pifystrm ntpd[814]: 166.88.142.52 local addr 10.1.12.92 ->
Dec 18 15:12:00 pifystrm ntpd[814]: 23.142.248.9 local addr 10.1.12.92 ->
Dec 18 15:12:00 pifystrm ntpd[814]: 137.110.222.27 local addr 10.1.12.92 ->
Dec 18 15:12:00 pifystrm ntpd[814]: 172.233.155.39 local addr 10.1.12.92 ->
Dec 18 15:12:01 pifystrm ntpd[814]: Soliciting pool server 23.186.168.127
Dec 18 15:12:04 pifystrm dhcpcd[941]: wlan0: leased 10.1.12.92 for 604800 seconds
Dec 18 15:12:04 pifystrm avahi-daemon[649]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92.
Dec 18 15:12:04 pifystrm avahi-daemon[649]: New relevant interface wlan0.IPv4 for mDNS.
Dec 18 15:12:04 pifystrm avahi-daemon[649]: Registering new address record for 10.1.12.92 on wlan0.IPv4.
Dec 18 15:12:04 pifystrm dhcpcd[941]: wlan0: adding route to 10.1.8.0/21
Dec 18 15:12:04 pifystrm dhcpcd[941]: wlan0: adding default route via 10.1.10.1
Dec 18 15:12:05 pifystrm ntpd[814]: Soliciting pool server 172.238.164.57
Dec 18 15:12:05 pifystrm volumio[19637]: info: Discovery: adding 8a7efacc-4a4c-45e8-b169-57dc6a52f98c
Dec 18 15:12:05 pifystrm volumio[19637]: info: Discovery: Found device PiFyStrm
Dec 18 15:12:05 pifystrm volumio[19637]: info: CoreCommandRouter::volumioGetState
Dec 18 15:12:05 pifystrm volumio[19637]: info: CorePlayQueue::getTrack 0
Dec 18 15:12:06 pifystrm ntpd[814]: Listen normally on 52 wlan0 10.1.12.92:123
Dec 18 15:12:06 pifystrm ntpd[814]: new interface(s) found: waking up resolver
Dec 18 15:12:10 pifystrm wpa_supplicant[929]: wlan0: Associated with 9c:c9:eb:9c:ee:61
Dec 18 15:12:10 pifystrm dhcpcd[941]: wlan0: carrier lost
Dec 18 15:12:10 pifystrm wpa_supplicant[929]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=]
Dec 18 15:12:10 pifystrm wpa_supplicant[929]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Dec 18 15:12:10 pifystrm avahi-daemon[649]: Withdrawing address record for 10.1.12.92 on wlan0.
Dec 18 15:12:10 pifystrm avahi-daemon[649]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92.
Dec 18 15:12:10 pifystrm avahi-daemon[649]: Interface wlan0.IPv4 no longer relevant for mDNS.
Dec 18 15:12:10 pifystrm dhcpcd[941]: wlan0: deleting route to 10.1.8.0/21
Dec 18 15:12:10 pifystrm dhcpcd[941]: wlan0: deleting default route via 10.1.10.1
Dec 18 15:12:10 pifystrm volumio[19637]: info: Discovery: A device disappeared from network
Dec 18 15:12:10 pifystrm volumio[19637]: info: Discovery: Device pifystrm disappeared from network
Dec 18 15:12:10 pifystrm dhcpcd[941]: wlan0: carrier acquired
Dec 18 15:12:10 pifystrm dhcpcd[941]: wlan0: IAID dd:0d:07:df
Dec 18 15:12:10 pifystrm sudo[20064]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:10 pifystrm volumio[19637]: info: Cannot mount NAS Curaudio at system boot, trial number 2 ,retrying in 5 seconds
Dec 18 15:12:10 pifystrm dhcpcd[941]: wlan0: rebinding lease of 10.1.12.92
Dec 18 15:12:11 pifystrm dhcpcd[941]: wlan0: probing address 10.1.12.92/21
Dec 18 15:12:11 pifystrm dhcpcd[941]: wlan0: soliciting an IPv6 router
Dec 18 15:12:11 pifystrm ntpd[814]: Soliciting pool server 23.95.49.216
Dec 18 15:12:11 pifystrm ntpd[814]: Deleting interface #52 wlan0, 10.1.12.92#123, interface stats: received=4, sent=4, dropped=2, active_time=5 secs
Dec 18 15:12:11 pifystrm ntpd[814]: 12.205.28.193 local addr 10.1.12.92 ->
Dec 18 15:12:11 pifystrm ntpd[814]: 162.159.200.123 local addr 10.1.12.92 ->
Dec 18 15:12:11 pifystrm ntpd[814]: 166.88.142.52 local addr 10.1.12.92 ->
Dec 18 15:12:11 pifystrm ntpd[814]: 23.142.248.9 local addr 10.1.12.92 ->
Dec 18 15:12:11 pifystrm ntpd[814]: 137.110.222.27 local addr 10.1.12.92 ->
Dec 18 15:12:11 pifystrm ntpd[814]: 172.233.155.39 local addr 10.1.12.92 ->
Dec 18 15:12:13 pifystrm volumio[19637]: info: UPDATER: Scheduling automatic update
Dec 18 15:12:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 18 15:12:13 pifystrm volumio[19637]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 18 15:12:13 pifystrm volumio[19637]: info: UPDATER: Auto update will take place at: Fri Dec 19 2025 05:12:47 GMT-0500 (Eastern Standard Time)
Dec 18 15:12:13 pifystrm volumio[19637]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 18 15:12:13 pifystrm volumio[19637]: Error: send ENETUNREACH 255.255.255.255:3483
Dec 18 15:12:13 pifystrm volumio[19637]: at doSend (dgram.js:692:16)
Dec 18 15:12:13 pifystrm volumio[19637]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12)
Dec 18 15:12:13 pifystrm volumio[19637]: at afterDns (dgram.js:638:5)
Dec 18 15:12:13 pifystrm volumio[19637]: at processTicksAndRejections (internal/process/task_queues.js:81:21) {
Dec 18 15:12:13 pifystrm volumio[19637]: errno: -101,
Dec 18 15:12:13 pifystrm volumio[19637]: code: 'ENETUNREACH',
Dec 18 15:12:13 pifystrm volumio[19637]: syscall: 'send',
Dec 18 15:12:13 pifystrm volumio[19637]: address: '255.255.255.255',
Dec 18 15:12:13 pifystrm volumio[19637]: port: 3483
Dec 18 15:12:13 pifystrm volumio[19637]: }
Dec 18 15:12:13 pifystrm volumio[19637]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 18 15:12:14 pifystrm sudo[20522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-18 15:11
Dec 18 15:12:14 pifystrm sudo[20522]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:14 pifystrm sudo[20522]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:14 pifystrm volumio-remote-updater[16014]: [2025-12-18 15:12:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Dec 18 15:12:14 pifystrm volumio-remote-updater[16014]: [2025-12-18 15:12:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Dec 18 15:12:14 pifystrm systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Dec 18 15:12:14 pifystrm systemd[1]: volumio.service: Failed with result 'exit-code'.
Dec 18 15:12:14 pifystrm systemd[1]: Started dynamicswap service.
Dec 18 15:12:14 pifystrm systemd[1]: dynamicswap.service: Succeeded.
Dec 18 15:12:14 pifystrm systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Dec 18 15:12:14 pifystrm systemd[1]: volumio.service: Scheduled restart job, restart counter is at 22.
Dec 18 15:12:14 pifystrm systemd[1]: Stopped Volumio Backend Module.
Dec 18 15:12:14 pifystrm systemd[1]: Started Volumio Backend Module.
Dec 18 15:12:14 pifystrm systemd[1]: Started dynamicswap service.
Dec 18 15:12:14 pifystrm systemd[1]: dynamicswap.service: Succeeded.
Dec 18 15:12:15 pifystrm volumio[20535]: info: -------------------------------------------
Dec 18 15:12:15 pifystrm volumio[20535]: info: ----- Volumio3 ----
Dec 18 15:12:15 pifystrm volumio[20535]: info: -------------------------------------------
Dec 18 15:12:15 pifystrm volumio[20535]: info: ----- System startup ----
Dec 18 15:12:15 pifystrm volumio[20535]: info: -------------------------------------------
Dec 18 15:12:15 pifystrm volumio[20535]: info: MYVOLUMIO Environment detected
Dec 18 15:12:16 pifystrm volumio[20535]: info: Plugin folders cleanup
Dec 18 15:12:16 pifystrm volumio[20535]: info: Scanning into folder /volumio/app/plugins/
Dec 18 15:12:16 pifystrm volumio[20535]: info: Scanning category audio_interface
Dec 18 15:12:16 pifystrm volumio[20535]: info: Scanning category miscellanea
Dec 18 15:12:16 pifystrm volumio[20535]: info: Scanning category music_service
Dec 18 15:12:16 pifystrm volumio[20535]: info: Scanning category plugins.json
Dec 18 15:12:16 pifystrm volumio[20535]: info: Scanning category system_controller
Dec 18 15:12:16 pifystrm volumio[20535]: info: Scanning category user_interface
Dec 18 15:12:16 pifystrm volumio[20535]: info: Scanning into folder /data/plugins/
Dec 18 15:12:16 pifystrm volumio[20535]: info: Scanning category music_service
Dec 18 15:12:16 pifystrm volumio[20535]: info: Scanning category user_interface
Dec 18 15:12:16 pifystrm volumio[20535]: info: Plugin folders cleanup completed
Dec 18 15:12:16 pifystrm volumio[20535]: info: -------------------------------------------
Dec 18 15:12:16 pifystrm volumio[20535]: info: ----- Core plugins startup ----
Dec 18 15:12:16 pifystrm volumio[20535]: info: -------------------------------------------
Dec 18 15:12:16 pifystrm volumio[20535]: info: Loading plugins from folder /volumio/app/plugins/
Dec 18 15:12:16 pifystrm volumio[20535]: info: Adding plugin upnp to MyMusic Plugins
Dec 18 15:12:16 pifystrm volumio[20535]: info: Adding plugin airplay_emulation to MyMusic Plugins
Dec 18 15:12:16 pifystrm volumio[20535]: info: Adding plugin upnp_browser to MyMusic Plugins
Dec 18 15:12:16 pifystrm volumio[20535]: info: Loading plugins from folder /data/plugins/
Dec 18 15:12:16 pifystrm volumio[20535]: info: Loading plugin "system"...
Dec 18 15:12:16 pifystrm volumio[20535]: info: Loading plugin "appearance"...
Dec 18 15:12:16 pifystrm dhcpcd[941]: wlan0: leased 10.1.12.92 for 604800 seconds
Dec 18 15:12:16 pifystrm avahi-daemon[649]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92.
Dec 18 15:12:16 pifystrm dhcpcd[941]: wlan0: adding route to 10.1.8.0/21
Dec 18 15:12:16 pifystrm dhcpcd[941]: wlan0: adding default route via 10.1.10.1
Dec 18 15:12:16 pifystrm avahi-daemon[649]: New relevant interface wlan0.IPv4 for mDNS.
Dec 18 15:12:16 pifystrm avahi-daemon[649]: Registering new address record for 10.1.12.92 on wlan0.IPv4.
Dec 18 15:12:16 pifystrm volumio[20535]: info: Loading plugin "network"...
Dec 18 15:12:16 pifystrm volumio[20535]: info: Refreshing Cached IP Addresses
Dec 18 15:12:16 pifystrm sudo[20584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 18 15:12:16 pifystrm sudo[20584]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:16 pifystrm sudo[20584]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:16 pifystrm sudo[20586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 18 15:12:16 pifystrm sudo[20586]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:16 pifystrm sudo[20586]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:16 pifystrm volumio[20535]: info: Loading plugin "services"...
Dec 18 15:12:16 pifystrm volumio[20535]: info: Loading plugin "alsa_controller"...
Dec 18 15:12:16 pifystrm sudo[20595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Dec 18 15:12:16 pifystrm sudo[20595]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:16 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 18 15:12:16 pifystrm volumio[20535]: info: Loading plugin "wizard"...
Dec 18 15:12:16 pifystrm volumio[20535]: info: Loading plugin "networkfs"...
Dec 18 15:12:16 pifystrm volumio[20535]: info: Starting Udev Watcher for removable devices
Dec 18 15:12:16 pifystrm sudo[20610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio
Dec 18 15:12:16 pifystrm sudo[20610]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:16 pifystrm volumio[20535]: info: Ignoring mount for partition: boot
Dec 18 15:12:16 pifystrm volumio[20535]: info: Ignoring mount for partition: volumio
Dec 18 15:12:16 pifystrm volumio[20535]: info: Ignoring mount for partition: volumio_data
Dec 18 15:12:16 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 18 15:12:16 pifystrm volumio[20535]: info: Loading plugin "volumio_command_line_client"...
Dec 18 15:12:16 pifystrm volumio[20535]: info: Loading plugin "upnp"...
Dec 18 15:12:16 pifystrm volumio[20535]: info: [1766088736852] Starting Upmpd Daemon
Dec 18 15:12:16 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 18 15:12:16 pifystrm volumio[20535]: info: Loading plugin "my_music"...
Dec 18 15:12:16 pifystrm volumio[20535]: info: Loading plugin "mpd"...
Dec 18 15:12:17 pifystrm volumio[20535]: info: Loading plugin "upnp_browser"...
Dec 18 15:12:17 pifystrm volumio[20535]: info: Loading plugin "alarm-clock"...
Dec 18 15:12:17 pifystrm volumio[20535]: info: Loading plugin "airplay_emulation"...
Dec 18 15:12:17 pifystrm volumio[20535]: info: Starting Shairport Sync
Dec 18 15:12:17 pifystrm volumio[20535]: info: Loading plugin "last_100"...
Dec 18 15:12:17 pifystrm volumio[20535]: info: Loading plugin "webradio"...
Dec 18 15:12:17 pifystrm volumio[20535]: info: Loading plugin "i2s_dacs"...
Dec 18 15:12:17 pifystrm volumio[20535]: info: Loading plugin "volumiodiscovery"...
Dec 18 15:12:17 pifystrm volumio[20535]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Dec 18 15:12:17 pifystrm volumio[20535]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 18 15:12:17 pifystrm volumio[20535]: *** WARNING *** For more information see
Dec 18 15:12:17 pifystrm node[20535]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Dec 18 15:12:17 pifystrm volumio[20535]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Dec 18 15:12:17 pifystrm volumio[20535]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 18 15:12:17 pifystrm volumio[20535]: *** WARNING *** For more information see
Dec 18 15:12:17 pifystrm node[20535]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 18 15:12:17 pifystrm node[20535]: *** WARNING *** For more information see
Dec 18 15:12:17 pifystrm node[20535]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Dec 18 15:12:17 pifystrm node[20535]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 18 15:12:17 pifystrm node[20535]: *** WARNING *** For more information see
Dec 18 15:12:17 pifystrm volumio[20535]: info: Applying required configuration parameters for plugin volumiodiscovery
Dec 18 15:12:17 pifystrm volumio[20535]: info: Discovery: Started advertising with name: PiFyStrm
Dec 18 15:12:17 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 18 15:12:17 pifystrm volumio[20535]: info: Loading plugin "spop"...
Dec 18 15:12:17 pifystrm ntpd[814]: Listen normally on 53 wlan0 10.1.12.92:123
Dec 18 15:12:17 pifystrm ntpd[814]: new interface(s) found: waking up resolver
Dec 18 15:12:18 pifystrm volumio[20535]: info: Loading plugin "squeezelite_mc"...
Dec 18 15:12:18 pifystrm volumio[20535]: info: Loading plugin "now_playing"...
Dec 18 15:12:19 pifystrm volumio[20535]: info: Loading plugin "outputs"...
Dec 18 15:12:19 pifystrm volumio[20535]: info: Loading plugin "albumart"...
Dec 18 15:12:19 pifystrm volumio[20535]: info: Plugin example_plugin is not enabled
Dec 18 15:12:19 pifystrm volumio[20535]: info: Loading plugin "inputs"...
Dec 18 15:12:19 pifystrm volumio[20535]: info: Loading plugin "updater_comm"...
Dec 18 15:12:19 pifystrm volumio[20535]: info: Plugin mpdemulation is not enabled
Dec 18 15:12:19 pifystrm volumio[20535]: info: Loading plugin "rest_api"...
Dec 18 15:12:19 pifystrm volumio[20535]: info: Loading plugin "websocket"...
Dec 18 15:12:19 pifystrm volumio[20535]: info: Starting Socket.io Server version 2.3.0
Dec 18 15:12:19 pifystrm volumio[20535]: info: Loading plugin "touch_display"...
Dec 18 15:12:19 pifystrm volumio-remote-updater[16014]: [2025-12-18 15:12:19] [connect] Successful connection
Dec 18 15:12:19 pifystrm volumio[20535]: Forking 3 albumart workers
Dec 18 15:12:19 pifystrm volumio[20535]: info: Applying required configuration parameters for plugin touch_display
Dec 18 15:12:19 pifystrm volumio[20535]: info: Loading i18n strings for locale en
Dec 18 15:12:19 pifystrm volumio[20535]: Updating browse sources language
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::initPlayerControls
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 18 15:12:19 pifystrm volumio[20535]: Express server listening on port 3000
Dec 18 15:12:19 pifystrm volumio[20535]: [Metrics] WebUI: 4s 393.69ms
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreStateMachine::resetVolumioState
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreStateMachine::getcurrentVolume
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::volumioRetrievevolume
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreStateMachine::pushState
Dec 18 15:12:19 pifystrm volumio[20535]: info: CorePlayQueue::getTrack 0
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::volumioPushState
Dec 18 15:12:19 pifystrm volumio[20535]: info: Volumio Network Manager: Network status updated: 2
Dec 18 15:12:19 pifystrm volumio[20535]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1
Dec 18 15:12:19 pifystrm volumio-remote-updater[16014]: [2025-12-18 15:12:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766088739 101
Dec 18 15:12:19 pifystrm volumio[20535]: 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: 2
Dec 18 15:12:19 pifystrm volumio[20535]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:12:19 pifystrm volumio[20535]: info: Reloading queue from file
Dec 18 15:12:19 pifystrm volumio[20535]: Starting albumart workers
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreStateMachine::setRepeat false single undefined
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreStateMachine::pushState
Dec 18 15:12:19 pifystrm volumio[20535]: info: CorePlayQueue::getTrack 0
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::volumioPushState
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreStateMachine::setRandom false
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreStateMachine::pushState
Dec 18 15:12:19 pifystrm volumio[20535]: info: CorePlayQueue::getTrack 0
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 18 15:12:19 pifystrm volumio[20535]: info: CoreCommandRouter::volumioPushState
Dec 18 15:12:19 pifystrm volumio[20535]: info: Setting Device type: Raspberry PI
Dec 18 15:12:19 pifystrm volumio[20535]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb
Dec 18 15:12:19 pifystrm volumio[20535]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115
Dec 18 15:12:19 pifystrm volumio[20535]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI
Dec 18 15:12:19 pifystrm volumio[20535]: Starting albumart workers
Dec 18 15:12:20 pifystrm volumio[20535]: Starting albumart workers
Dec 18 15:12:20 pifystrm volumio[20535]: info: Discovery: adding 8a7efacc-4a4c-45e8-b169-57dc6a52f98c
Dec 18 15:12:20 pifystrm volumio[20535]: info: Discovery: Found device PiFyStrm
Dec 18 15:12:20 pifystrm volumio[20535]: info: CoreCommandRouter::volumioGetState
Dec 18 15:12:20 pifystrm volumio[20535]: info: CorePlayQueue::getTrack 0
Dec 18 15:12:20 pifystrm sudo[20595]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:21 pifystrm dhcpcd[941]: wlan0: carrier lost
Dec 18 15:12:21 pifystrm wpa_supplicant[929]: wlan0: Associated with 9c:c9:eb:9c:ee:61
Dec 18 15:12:21 pifystrm wpa_supplicant[929]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=]
Dec 18 15:12:21 pifystrm wpa_supplicant[929]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Dec 18 15:12:21 pifystrm avahi-daemon[649]: Withdrawing address record for 10.1.12.92 on wlan0.
Dec 18 15:12:21 pifystrm avahi-daemon[649]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92.
Dec 18 15:12:21 pifystrm avahi-daemon[649]: Interface wlan0.IPv4 no longer relevant for mDNS.
Dec 18 15:12:21 pifystrm dhcpcd[941]: wlan0: deleting route to 10.1.8.0/21
Dec 18 15:12:21 pifystrm dhcpcd[941]: wlan0: deleting default route via 10.1.10.1
Dec 18 15:12:21 pifystrm volumio[20535]: info: Discovery: A device disappeared from network
Dec 18 15:12:21 pifystrm volumio[20535]: info: Discovery: Device pifystrm disappeared from network
Dec 18 15:12:21 pifystrm dhcpcd[941]: wlan0: carrier acquired
Dec 18 15:12:21 pifystrm dhcpcd[941]: wlan0: IAID dd:0d:07:df
Dec 18 15:12:22 pifystrm dhcpcd[941]: wlan0: rebinding lease of 10.1.12.92
Dec 18 15:12:22 pifystrm volumio[20535]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4
Dec 18 15:12:22 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 18 15:12:22 pifystrm volumio[20535]: info: CoreCommandRouter::volumioGetVisibleSources
Dec 18 15:12:22 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 18 15:12:22 pifystrm volumio[20535]: info: CoreCommandRouter::volumioGetState
Dec 18 15:12:22 pifystrm volumio[20535]: info: CorePlayQueue::getTrack 0
Dec 18 15:12:22 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Dec 18 15:12:22 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Dec 18 15:12:22 pifystrm volumio[20535]: info: Received Get System Info
Dec 18 15:12:22 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 18 15:12:22 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 18 15:12:22 pifystrm volumio[20535]: info: Discovery: Getting this device information
Dec 18 15:12:22 pifystrm volumio[20535]: info: CoreCommandRouter::volumioGetState
Dec 18 15:12:22 pifystrm volumio[20535]: info: CorePlayQueue::getTrack 0
Dec 18 15:12:22 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 18 15:12:22 pifystrm volumio[20535]: info: CoreCommandRouter::volumioGetState
Dec 18 15:12:22 pifystrm volumio[20535]: info: CorePlayQueue::getTrack 0
Dec 18 15:12:22 pifystrm dhcpcd[941]: wlan0: probing address 10.1.12.92/21
Dec 18 15:12:22 pifystrm volumio[20535]: info: Listing playlists
Dec 18 15:12:22 pifystrm dhcpcd[941]: wlan0: soliciting an IPv6 router
Dec 18 15:12:23 pifystrm ntpd[814]: Deleting interface #53 wlan0, 10.1.12.92#123, interface stats: received=5, sent=5, dropped=1, active_time=6 secs
Dec 18 15:12:23 pifystrm ntpd[814]: 12.205.28.193 local addr 10.1.12.92 ->
Dec 18 15:12:23 pifystrm ntpd[814]: 162.159.200.123 local addr 10.1.12.92 ->
Dec 18 15:12:23 pifystrm ntpd[814]: 166.88.142.52 local addr 10.1.12.92 ->
Dec 18 15:12:23 pifystrm ntpd[814]: 23.142.248.9 local addr 10.1.12.92 ->
Dec 18 15:12:23 pifystrm ntpd[814]: 137.110.222.27 local addr 10.1.12.92 ->
Dec 18 15:12:23 pifystrm ntpd[814]: 172.233.155.39 local addr 10.1.12.92 ->
Dec 18 15:12:26 pifystrm volumio[20535]: info: Volumio Network Manager: Network status updated: 0
Dec 18 15:12:26 pifystrm sudo[20742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 18 15:12:26 pifystrm sudo[20742]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:26 pifystrm sudo[20742]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:26 pifystrm sudo[20745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 18 15:12:26 pifystrm sudo[20745]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:26 pifystrm sudo[20745]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:26 pifystrm sudo[20748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Dec 18 15:12:26 pifystrm sudo[20748]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:26 pifystrm sudo[20610]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:26 pifystrm volumio[20535]: info: Cannot mount NAS Curaudio at system boot, trial number 1 ,retrying in 5 seconds
Dec 18 15:12:26 pifystrm volumio[20535]: info: Completed loading Core Plugins
Dec 18 15:12:26 pifystrm volumio[20535]: info: Preparing to generate the ALSA configuration file
Dec 18 15:12:26 pifystrm sudo[20748]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:26 pifystrm volumio[20535]: info: Upmpdcli Daemon Started
Dec 18 15:12:26 pifystrm volumio[20535]: info: Asound.conf file unchanged, so no further update is needed
Dec 18 15:12:26 pifystrm volumio[20535]: info: Output device has changed, restarting MPD
Dec 18 15:12:26 pifystrm sudo[20751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 18 15:12:26 pifystrm sudo[20751]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:26 pifystrm volumio[20535]: info: Output device has changed, restarting Shairport Sync
Dec 18 15:12:26 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:26 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:12:26 pifystrm sudo[20751]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:26 pifystrm sudo[20754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 18 15:12:26 pifystrm sudo[20754]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:27 pifystrm systemd[1]: Stopping Music Player Daemon...
Dec 18 15:12:27 pifystrm volumio[20535]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 18 15:12:27 pifystrm volumio[20535]: info: ___________ START PLUGINS ___________
Dec 18 15:12:27 pifystrm systemd[1]: mpd.service: Succeeded.
Dec 18 15:12:27 pifystrm volumio[20535]: info: ControllerMpd::onStart: Initializing MPD
Dec 18 15:12:27 pifystrm volumio[20535]: info: Creating MPD Configuration file
Dec 18 15:12:27 pifystrm systemd[1]: Stopped Music Player Daemon.
Dec 18 15:12:27 pifystrm sudo[20761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 18 15:12:27 pifystrm sudo[20761]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 18 15:12:27 pifystrm volumio[20535]: info: [1766088747059] CoreMusicLibrary::Adding element Media Servers
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:12:27 pifystrm sudo[20761]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:27 pifystrm sudo[20764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 18 15:12:27 pifystrm sudo[20764]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:27 pifystrm systemd[1]: Starting Music Player Daemon...
Dec 18 15:12:27 pifystrm volumio[20535]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:12:27 pifystrm systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Dec 18 15:12:27 pifystrm systemd[1]: mpd.service: Succeeded.
Dec 18 15:12:27 pifystrm systemd[1]: Stopped Music Player Daemon.
Dec 18 15:12:27 pifystrm systemd[1]: Starting Music Player Daemon...
Dec 18 15:12:27 pifystrm volumio[20535]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 18 15:12:27 pifystrm volumio[20535]: info: [1766088747149] CoreMusicLibrary::Adding element Last_100
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 18 15:12:27 pifystrm volumio[20535]: info: [1766088747151] CoreMusicLibrary::Adding element Webradio
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 18 15:12:27 pifystrm volumio[20535]: info: Initializing BBC Radios
Dec 18 15:12:27 pifystrm sudo[20770]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Dec 18 15:12:27 pifystrm sudo[20770]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:27 pifystrm sudo[20770]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 18 15:12:27 pifystrm volumio[20535]: info: Checking for old spotify connect plugin installed
Dec 18 15:12:27 pifystrm volumio[20535]: info: [squeezelite_mc] Starting proxy server...
Dec 18 15:12:27 pifystrm volumio[20535]: info: [now-playing-config] Config is up to date.
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 18 15:12:27 pifystrm volumio[20535]: info: Discovery: Getting this device information
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::volumioGetState
Dec 18 15:12:27 pifystrm volumio[20535]: info: CorePlayQueue::getTrack 0
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 18 15:12:27 pifystrm volumio[20535]: info: Loading i18n strings for locale en
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 18 15:12:27 pifystrm volumio[20535]: info: Volumio Calling Home
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 18 15:12:27 pifystrm volumio[20535]: [SpotifyConnect] Creating VLS config file
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:27 pifystrm volumio[20535]: [SpotifyConnect] Starting metadata listener
Dec 18 15:12:27 pifystrm volumio[20535]: info: [squeezelite_mc] Proxy server started on port 39673
Dec 18 15:12:27 pifystrm volumio[20535]: info: [now-playing-app] App is listening on port 4004.
Dec 18 15:12:27 pifystrm sudo[20789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service
Dec 18 15:12:27 pifystrm sudo[20789]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:27 pifystrm sudo[20789]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:27 pifystrm sudo[20792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Dec 18 15:12:27 pifystrm sudo[20792]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:27 pifystrm systemd[1]: Reloading.
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::volumioRetrievevolume
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreStateMachine::pushState
Dec 18 15:12:27 pifystrm volumio[20535]: info: CorePlayQueue::getTrack 0
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::volumioPushState
Dec 18 15:12:27 pifystrm volumio[20535]: info: [squeezelite_mc] Server discovery started
Dec 18 15:12:27 pifystrm volumio[20535]: info: [squeezelite_mc] Player finder started
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 18 15:12:27 pifystrm volumio[20535]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 18 15:12:27 pifystrm volumio[20535]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true
Dec 18 15:12:27 pifystrm volumio[20535]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 18 15:12:27 pifystrm volumio[20535]: Error: send ENETUNREACH 255.255.255.255:3483
Dec 18 15:12:27 pifystrm volumio[20535]: at doSend (dgram.js:692:16)
Dec 18 15:12:27 pifystrm volumio[20535]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12)
Dec 18 15:12:27 pifystrm volumio[20535]: at afterDns (dgram.js:638:5)
Dec 18 15:12:27 pifystrm volumio[20535]: at processTicksAndRejections (internal/process/task_queues.js:81:21) {
Dec 18 15:12:27 pifystrm volumio[20535]: errno: -101,
Dec 18 15:12:27 pifystrm volumio[20535]: code: 'ENETUNREACH',
Dec 18 15:12:27 pifystrm volumio[20535]: syscall: 'send',
Dec 18 15:12:27 pifystrm volumio[20535]: address: '255.255.255.255',
Dec 18 15:12:27 pifystrm volumio[20535]: port: 3483
Dec 18 15:12:27 pifystrm volumio[20535]: }
Dec 18 15:12:27 pifystrm volumio[20535]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 18 15:12:27 pifystrm 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.
Dec 18 15:12:27 pifystrm dhcpcd[941]: wlan0: leased 10.1.12.92 for 604800 seconds
Dec 18 15:12:27 pifystrm avahi-daemon[649]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92.
Dec 18 15:12:27 pifystrm dhcpcd[941]: wlan0: adding route to 10.1.8.0/21
Dec 18 15:12:27 pifystrm dhcpcd[941]: wlan0: adding default route via 10.1.10.1
Dec 18 15:12:27 pifystrm avahi-daemon[649]: New relevant interface wlan0.IPv4 for mDNS.
Dec 18 15:12:27 pifystrm avahi-daemon[649]: Registering new address record for 10.1.12.92 on wlan0.IPv4.
Dec 18 15:12:27 pifystrm systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly.
Dec 18 15:12:28 pifystrm sudo[20841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-18 15:11
Dec 18 15:12:28 pifystrm sudo[20841]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 18 15:12:28 pifystrm mpd[20780]: Dec 18 15:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 18 15:12:28 pifystrm systemd[1]: Started Music Player Daemon.
Dec 18 15:12:28 pifystrm sudo[20764]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:28 pifystrm sudo[20754]: pam_unix(sudo:session): session closed for user root
Dec 18 15:12:28 pifystrm sudo[20792]: pam_unix(sudo:session): session closed for user root
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET"
VOLUMIO_VERSION="3.874"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"