-- Logs begin at Thu 2019-02-14 19:11:58 KST, end at Mon 2025-03-03 15:00:28 KST. -- Mar 03 14:59:42 volumio systemd-timedated[1625]: Changed local time to Mon Mar 3 14:59:42 2025 Mar 03 14:59:42 volumio sudo[1604]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:42 volumio volumio-time-update[623]: volumio-time-update-util: System time updated successfully. Mar 03 14:59:42 volumio kernel: eth0: renamed from veth75acafb Mar 03 14:59:42 volumio systemd[1]: Starting Daily apt download activities... Mar 03 14:59:42 volumio volumio[892]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 03 14:59:42 volumio volumio[892]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 14:59:42 volumio volumio[892]: *** WARNING *** For more information see Mar 03 14:59:42 volumio volumio[892]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 03 14:59:42 volumio volumio[892]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 14:59:42 volumio volumio[892]: *** WARNING *** For more information see Mar 03 14:59:42 volumio volumio[892]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 03 14:59:42 volumio volumio[892]: info: Discovery: Started advertising with name: Volumio Mar 03 14:59:42 volumio node[892]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 03 14:59:42 volumio node[892]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 14:59:42 volumio node[892]: *** WARNING *** For more information see Mar 03 14:59:42 volumio node[892]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 03 14:59:42 volumio node[892]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 14:59:42 volumio node[892]: *** WARNING *** For more information see Mar 03 14:59:42 volumio systemd[1]: Starting Daily man-db regeneration... Mar 03 14:59:42 volumio systemd[1]: Started Volumio Time Update Utility. Mar 03 14:59:42 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 03 14:59:42 volumio volumio[892]: info: Loading plugin "spop"... Mar 03 14:59:42 volumio kernel: br-7baa97ca4e9b: port 3(vethda542e3) entered blocking state Mar 03 14:59:42 volumio kernel: br-7baa97ca4e9b: port 3(vethda542e3) entered forwarding state Mar 03 14:59:42 volumio dockerd[755]: time="2025-03-03T14:59:42.174545137+09:00" level=warning msg="OOM monitoring failed" error="cgroups: memory cgroup not supported on this system" Mar 03 14:59:42 volumio kernel: eth0: renamed from veth5ada17e Mar 03 14:59:42 volumio kernel: br-7baa97ca4e9b: port 2(vethee429ac) entered blocking state Mar 03 14:59:42 volumio kernel: br-7baa97ca4e9b: port 2(vethee429ac) entered forwarding state Mar 03 14:59:42 volumio dockerd[755]: time="2025-03-03T14:59:42.415451016+09:00" level=warning msg="OOM monitoring failed" error="cgroups: memory cgroup not supported on this system" Mar 03 14:59:42 volumio kernel: eth0: renamed from veth67d3029 Mar 03 14:59:42 volumio kernel: br-7baa97ca4e9b: port 1(vethe663c0b) entered blocking state Mar 03 14:59:42 volumio kernel: br-7baa97ca4e9b: port 1(vethe663c0b) entered forwarding state Mar 03 14:59:42 volumio dockerd[755]: time="2025-03-03T14:59:42.648086505+09:00" level=warning msg="OOM monitoring failed" error="cgroups: memory cgroup not supported on this system" Mar 03 14:59:42 volumio dockerd[755]: time="2025-03-03T14:59:42.886728095+09:00" level=info msg="Loading containers: done." Mar 03 14:59:42 volumio dockerd[755]: time="2025-03-03T14:59:42.962888699+09:00" level=info msg="Docker daemon" commit=4c52b90 graphdriver(s)=devicemapper version=18.09.1 Mar 03 14:59:42 volumio dockerd[755]: time="2025-03-03T14:59:42.968907892+09:00" level=info msg="Daemon has completed initialization" Mar 03 14:59:43 volumio dockerd[755]: time="2025-03-03T14:59:43.087874283+09:00" level=info msg="API listen on /var/run/docker.sock" Mar 03 14:59:43 volumio systemd[1]: Started Docker Application Container Engine. Mar 03 14:59:43 volumio systemd[1]: Reached target Multi-User System. Mar 03 14:59:43 volumio systemd[1]: Reached target Graphical Interface. Mar 03 14:59:43 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 03 14:59:43 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 03 14:59:43 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 03 14:59:43 volumio volumio-remote-updater[642]: [2025-03-03 14:59:43] [connect] Successful connection Mar 03 14:59:43 volumio systemd[1]: apt-daily.service: Succeeded. Mar 03 14:59:43 volumio systemd[1]: Started Daily apt download activities. Mar 03 14:59:43 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Mar 03 14:59:43 volumio ntpd[798]: Listen normally on 3 eth0 192.168.219.109:123 Mar 03 14:59:43 volumio ntpd[798]: Listen normally on 4 br-7baa97ca4e9b 172.18.0.1:123 Mar 03 14:59:43 volumio ntpd[798]: new interface(s) found: waking up resolver Mar 03 14:59:45 volumio kernel: process 'Plugins/RaopBridge/Bin/squeeze2raop-linux-arm' started with executable stack Mar 03 14:59:45 volumio squeezeboxserver[759]: [14:59:45.176] main:1583 Starting squeeze2raop version: v1.7.7 (Apr 2 2024 @ 16:11:47) Mar 03 14:59:45 volumio squeezeboxserver[759]: [14:59:45.193] Start:1316 Binding to 192.168.219.109 [eth0] with mask 0xffffff00 Mar 03 14:59:45 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Mar 03 14:59:45 volumio systemd[1]: Started Daily apt upgrade and clean activities. Mar 03 14:59:45 volumio squeezeboxserver[759]: [14:59:45.200] StartActiveRemote:1221 DACP port: 50107 Mar 03 14:59:45 volumio squeezeboxserver[759]: [14:59:45.315] AddRaopDevice:865 [0x195a80]: AirPort Express Mar 03 14:59:45 volumio squeezeboxserver[759]: [14:59:45.315] AddRaopDevice:929 [0x195a80]: adding renderer (volumio@192.168.219.109) with mac AAAA-DF0F404D Mar 03 14:59:45 volumio squeezeboxserver[759]: [14:59:45.315] raopcl_create:715 [0xb5f08d68]: using PCM coding Mar 03 14:59:45 volumio squeezeboxserver[759]: [14:59:45.321] output_raop_thread_init:119 [0x13c280]: init output raop Mar 03 14:59:45 volumio squeezeboxserver[759]: [14:59:45.321] output_init_common:290 allocating 1764000 Mar 03 14:59:45 volumio squeezeboxserver[759]: [14:59:45.322] mDNSsearchCallback:746 Updating configuration /var/lib/squeezeboxserver/prefs/raopbridge.xml Mar 03 14:59:45 volumio volumio[892]: info: Loading plugin "now_playing"... Mar 03 14:59:45 volumio squeezeboxserver[759]: [14:59:45.381] main:1831 Starting squeeze2upnp version: v3.3.9 (Apr 5 2024 @ 22:09:49) Mar 03 14:59:45 volumio squeezeboxserver[759]: [14:59:45.447] Start:1530 Binding to iface 192.168.219.109:49152 [eth0] (http port 0) Mar 03 14:59:45 volumio squeezeboxserver[759]: [14:59:45.448] Start:1566 UPnP search for urn:schemas-upnp-org:device:MediaRenderer:1 Mar 03 14:59:45 volumio squeezeboxserver[759]: [14:59:45.649] Start:1566 UPnP search for urn:schemas-upnp-org:device:MediaRenderer:2 Mar 03 14:59:45 volumio systemd[1]: man-db.service: Succeeded. Mar 03 14:59:45 volumio systemd[1]: Started Daily man-db regeneration. Mar 03 14:59:45 volumio systemd[1]: Startup finished in 11.708s (kernel) + 18.553s (userspace) = 30.261s. Mar 03 14:59:46 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 Mar 03 14:59:46 volumio kernel: xhci_hcd 0000:01:00.0: @000000042951ab40 00000000 00000000 0e000000 02028001 Mar 03 14:59:46 volumio squeezeboxserver[759]: [14:59:46.456] slimproto:834 squeezelite [0x13c280] <=> player [0x195a80] Mar 03 14:59:46 volumio squeezeboxserver[759]: [14:59:46.456] slimproto:835 [0x13c280] connecting to 192.168.219.109:3483 Mar 03 14:59:46 volumio squeezeboxserver[759]: [14:59:46.458] slimproto:865 [0x13c280] connected Mar 03 14:59:46 volumio squeezeboxserver[759]: [14:59:46.458] sendHELO:107 [0x13c280] cap: CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3 Mar 03 14:59:46 volumio squeezeboxserver[759]: [14:59:46.531] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.17.0.1:5000 Mar 03 14:59:46 volumio squeezeboxserver[759]: [14:59:46.531] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.18.0.1:5000 Mar 03 14:59:46 volumio squeezeboxserver[759]: [14:59:46.546] PlayerThread:598 [0x195a80]: processing volume device:100 request:0.00 Mar 03 14:59:47 volumio squeezeboxserver[759]: [14:59:47.073] main:253 host: volumio.local Mar 03 14:59:47 volumio squeezeboxserver[759]: identity: E938298D6905@volumio-AirPlay Mar 03 14:59:47 volumio squeezeboxserver[759]: type: _raop._tcp.local Mar 03 14:59:47 volumio squeezeboxserver[759]: ip: 192.168.219.109 Mar 03 14:59:47 volumio squeezeboxserver[759]: port: 44849 Mar 03 14:59:47 volumio squeezeboxserver[759]: with TXT: Mar 03 14:59:47 volumio squeezeboxserver[759]: tp=UDP Mar 03 14:59:47 volumio squeezeboxserver[759]: sm=false Mar 03 14:59:47 volumio squeezeboxserver[759]: sv=false Mar 03 14:59:47 volumio squeezeboxserver[759]: ek=1 Mar 03 14:59:47 volumio squeezeboxserver[759]: et=0,1 Mar 03 14:59:47 volumio squeezeboxserver[759]: md=0,1,2 Mar 03 14:59:47 volumio squeezeboxserver[759]: cn=0,1 Mar 03 14:59:47 volumio squeezeboxserver[759]: ch=2 Mar 03 14:59:47 volumio squeezeboxserver[759]: ss=16 Mar 03 14:59:47 volumio squeezeboxserver[759]: sr=44100 Mar 03 14:59:47 volumio squeezeboxserver[759]: pw=false Mar 03 14:59:47 volumio squeezeboxserver[759]: vn=3 Mar 03 14:59:47 volumio squeezeboxserver[759]: txtvers=1 Mar 03 14:59:47 volumio squeezeboxserver[759]: am=shairtunes2 Mar 03 14:59:47 volumio squeezeboxserver[759]: [14:59:47.073] mDNSsearchCallback:691 [0x195a80]: changed ip:port 192.168.219.109:5000 Mar 03 14:59:47 volumio squeezeboxserver[759]: [14:59:47.542] main:253 host: volumio.local Mar 03 14:59:47 volumio squeezeboxserver[759]: identity: 2012A12284C5@Squeezelite-Vanilla Mar 03 14:59:47 volumio squeezeboxserver[759]: type: _raop._tcp.local Mar 03 14:59:47 volumio squeezeboxserver[759]: ip: 192.168.219.109 Mar 03 14:59:47 volumio squeezeboxserver[759]: port: 32955 Mar 03 14:59:47 volumio squeezeboxserver[759]: with TXT: Mar 03 14:59:47 volumio squeezeboxserver[759]: tp=UDP Mar 03 14:59:47 volumio squeezeboxserver[759]: sm=false Mar 03 14:59:47 volumio squeezeboxserver[759]: sv=false Mar 03 14:59:47 volumio squeezeboxserver[759]: ek=1 Mar 03 14:59:47 volumio squeezeboxserver[759]: et=0,1 Mar 03 14:59:47 volumio squeezeboxserver[759]: md=0,1,2 Mar 03 14:59:47 volumio squeezeboxserver[759]: cn=0,1 Mar 03 14:59:47 volumio squeezeboxserver[759]: ch=2 Mar 03 14:59:47 volumio squeezeboxserver[759]: ss=16 Mar 03 14:59:47 volumio squeezeboxserver[759]: sr=44100 Mar 03 14:59:47 volumio squeezeboxserver[759]: pw=false Mar 03 14:59:47 volumio squeezeboxserver[759]: vn=3 Mar 03 14:59:47 volumio squeezeboxserver[759]: txtvers=1 Mar 03 14:59:47 volumio squeezeboxserver[759]: am=shairtunes2 Mar 03 14:59:47 volumio squeezeboxserver[759]: [14:59:47.567] main:253 host: volumio.local Mar 03 14:59:47 volumio squeezeboxserver[759]: identity: 51D168366A46@Squeezelite-Goldilocks Mar 03 14:59:47 volumio squeezeboxserver[759]: type: _raop._tcp.local Mar 03 14:59:47 volumio squeezeboxserver[759]: ip: 192.168.219.109 Mar 03 14:59:47 volumio squeezeboxserver[759]: port: 41137 Mar 03 14:59:47 volumio squeezeboxserver[759]: with TXT: Mar 03 14:59:47 volumio squeezeboxserver[759]: tp=UDP Mar 03 14:59:47 volumio squeezeboxserver[759]: sm=false Mar 03 14:59:47 volumio squeezeboxserver[759]: sv=false Mar 03 14:59:47 volumio squeezeboxserver[759]: ek=1 Mar 03 14:59:47 volumio squeezeboxserver[759]: et=0,1 Mar 03 14:59:47 volumio squeezeboxserver[759]: md=0,1,2 Mar 03 14:59:47 volumio squeezeboxserver[759]: cn=0,1 Mar 03 14:59:47 volumio squeezeboxserver[759]: ch=2 Mar 03 14:59:47 volumio squeezeboxserver[759]: ss=16 Mar 03 14:59:47 volumio squeezeboxserver[759]: sr=44100 Mar 03 14:59:47 volumio squeezeboxserver[759]: pw=false Mar 03 14:59:47 volumio squeezeboxserver[759]: vn=3 Mar 03 14:59:47 volumio squeezeboxserver[759]: txtvers=1 Mar 03 14:59:47 volumio squeezeboxserver[759]: am=shairtunes2 Mar 03 14:59:47 volumio squeezeboxserver[759]: [14:59:47.598] main:253 host: volumio.local Mar 03 14:59:47 volumio squeezeboxserver[759]: identity: AC514C45BA4B@Squeezelite-MQA Mar 03 14:59:47 volumio squeezeboxserver[759]: type: _raop._tcp.local Mar 03 14:59:47 volumio squeezeboxserver[759]: ip: 192.168.219.109 Mar 03 14:59:47 volumio squeezeboxserver[759]: port: 39619 Mar 03 14:59:47 volumio squeezeboxserver[759]: with TXT: Mar 03 14:59:47 volumio squeezeboxserver[759]: tp=UDP Mar 03 14:59:47 volumio squeezeboxserver[759]: sm=false Mar 03 14:59:47 volumio squeezeboxserver[759]: sv=false Mar 03 14:59:47 volumio squeezeboxserver[759]: ek=1 Mar 03 14:59:47 volumio squeezeboxserver[759]: et=0,1 Mar 03 14:59:47 volumio squeezeboxserver[759]: md=0,1,2 Mar 03 14:59:47 volumio squeezeboxserver[759]: cn=0,1 Mar 03 14:59:47 volumio squeezeboxserver[759]: ch=2 Mar 03 14:59:47 volumio squeezeboxserver[759]: ss=16 Mar 03 14:59:47 volumio squeezeboxserver[759]: sr=44100 Mar 03 14:59:47 volumio squeezeboxserver[759]: pw=false Mar 03 14:59:47 volumio squeezeboxserver[759]: vn=3 Mar 03 14:59:47 volumio squeezeboxserver[759]: txtvers=1 Mar 03 14:59:47 volumio squeezeboxserver[759]: am=shairtunes2 Mar 03 14:59:47 volumio volumio[892]: info: Loading plugin "peppy_screensaver"... Mar 03 14:59:48 volumio volumio[892]: info: Applying required configuration parameters for plugin peppy_screensaver Mar 03 14:59:48 volumio volumio[892]: info: Loading plugin "outputs"... Mar 03 14:59:48 volumio volumio[892]: info: Loading plugin "albumart"... Mar 03 14:59:48 volumio volumio[892]: info: Plugin example_plugin is not enabled Mar 03 14:59:48 volumio volumio[892]: info: Loading plugin "inputs"... Mar 03 14:59:48 volumio volumio[892]: info: Loading plugin "updater_comm"... Mar 03 14:59:48 volumio volumio[892]: info: Plugin mpdemulation is not enabled Mar 03 14:59:48 volumio volumio[892]: info: Loading plugin "rest_api"... Mar 03 14:59:48 volumio volumio[892]: info: Loading plugin "websocket"... Mar 03 14:59:48 volumio volumio[892]: info: Starting Socket.io Server version 2.3.0 Mar 03 14:59:48 volumio volumio[892]: info: Plugin RoonBridge is not enabled Mar 03 14:59:48 volumio volumio[892]: info: Loading plugin "touch_display"... Mar 03 14:59:48 volumio volumio[892]: Forking 3 albumart workers Mar 03 14:59:48 volumio squeezeboxserver[759]: [14:59:48.890] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.17.0.1:5000 Mar 03 14:59:48 volumio squeezeboxserver[759]: [14:59:48.890] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.18.0.1:5000 Mar 03 14:59:49 volumio volumio[892]: info: Applying required configuration parameters for plugin touch_display Mar 03 14:59:49 volumio volumio[892]: info: Loading i18n strings for locale ko Mar 03 14:59:49 volumio volumio[892]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 03 14:59:49 volumio volumio[892]: Updating browse sources language Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 14:59:49 volumio volumio[892]: Starting albumart workers Mar 03 14:59:49 volumio volumio[892]: Starting albumart workers Mar 03 14:59:49 volumio volumio[892]: Starting albumart workers Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::initPlayerControls Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 14:59:49 volumio volumio[892]: Express server listening on port 3000 Mar 03 14:59:49 volumio volumio[892]: [Metrics] WebUI: 12s 797.96ms Mar 03 14:59:49 volumio volumio[892]: info: CoreStateMachine::resetVolumioState Mar 03 14:59:49 volumio volumio[892]: info: CoreStateMachine::getcurrentVolume Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::volumioRetrievevolume Mar 03 14:59:49 volumio volumio[892]: info: CoreStateMachine::pushState Mar 03 14:59:49 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 14:59:49 volumio volumio[892]: info: Volumio Network Manager: Network status updated: 0 Mar 03 14:59:49 volumio volumio[892]: info: Cannot mount NAS MUSE at system boot, trial number 1 ,retrying in 5 seconds Mar 03 14:59:49 volumio volumio[892]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 03 14:59:49 volumio volumio[892]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 03 14:59:49 volumio volumio[892]: info: CoreStateMachine::setRepeat null single undefined Mar 03 14:59:49 volumio volumio[892]: info: CoreStateMachine::pushState Mar 03 14:59:49 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Mar 03 14:59:49 volumio volumio[892]: info: CoreStateMachine::setRandom null Mar 03 14:59:49 volumio volumio[892]: info: CoreStateMachine::pushState Mar 03 14:59:49 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Mar 03 14:59:49 volumio volumio[892]: info: Setting Device type: Raspberry PI Mar 03 14:59:49 volumio volumio[892]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 03 14:59:49 volumio volumio[892]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Mar 03 14:59:49 volumio volumio[892]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 03 14:59:49 volumio volumio[892]: info: Discovery: adding a8f7da66-c4ee-4837-a17c-20dc85d43ef9 Mar 03 14:59:49 volumio volumio[892]: info: Discovery: Found device Volumio Mar 03 14:59:49 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 14:59:49 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 14:59:50 volumio volumio[892]: info: Completed loading Core Plugins Mar 03 14:59:50 volumio volumio[892]: info: Preparing to generate the ALSA configuration file Mar 03 14:59:50 volumio volumio[892]: info: Discovery: this is already registered, a8f7da66-c4ee-4837-a17c-20dc85d43ef9 Mar 03 14:59:50 volumio volumio[892]: info: Discovery: Found device Volumio Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 14:59:50 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 14:59:50 volumio volumio[892]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 03 14:59:50 volumio volumio[892]: info: Reading ALSA contributions from plugins. Mar 03 14:59:50 volumio volumio[892]: info: Asound.conf file unchanged, so no further update is needed Mar 03 14:59:50 volumio volumio[892]: info: Output device has changed, restarting MPD Mar 03 14:59:50 volumio volumio[892]: info: Output device has changed, restarting Shairport Sync Mar 03 14:59:50 volumio sudo[2680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 14:59:50 volumio sudo[2680]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:50 volumio sudo[2680]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:50 volumio sudo[2682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 14:59:50 volumio sudo[2682]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:50 volumio systemd[1]: Stopping Music Player Daemon... Mar 03 14:59:50 volumio volumio[892]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 14:59:50 volumio volumio[892]: info: ___________ START PLUGINS ___________ Mar 03 14:59:50 volumio squeezeboxserver[759]: [14:59:50.123] mDNSsearchCallback:691 [0x195a80]: changed ip:port 192.168.219.109:5000 Mar 03 14:59:50 volumio squeezeboxserver[759]: [14:59:50.123] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.17.0.1:5000 Mar 03 14:59:50 volumio squeezeboxserver[759]: [14:59:50.123] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.18.0.1:5000 Mar 03 14:59:50 volumio volumio[892]: info: ControllerMpd::onStart: Initializing MPD Mar 03 14:59:50 volumio volumio[892]: info: Creating MPD Configuration file Mar 03 14:59:50 volumio sudo[2693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 14:59:50 volumio sudo[2693]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:50 volumio sudo[2693]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 14:59:50 volumio volumio[892]: info: [1740981590176] CoreMusicLibrary::Adding element 미디어 서버 Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 14:59:50 volumio sudo[2705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 14:59:50 volumio sudo[2705]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 14:59:50 volumio systemd[1]: mpd.service: Succeeded. Mar 03 14:59:50 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 14:59:50 volumio volumio[892]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 14:59:50 volumio volumio[892]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 14:59:50 volumio volumio[892]: info: [1740981590272] CoreMusicLibrary::Adding element Last_100 Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 14:59:50 volumio volumio[892]: info: [1740981590274] CoreMusicLibrary::Adding element Webradio Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 14:59:50 volumio volumio[892]: info: Initializing BBC Radios Mar 03 14:59:50 volumio systemd[1]: Starting Music Player Daemon... Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 14:59:50 volumio volumio[892]: info: Creating Spotify config file Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:50 volumio sudo[2711]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 14:59:50 volumio sudo[2711]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:50 volumio sudo[2711]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:50 volumio volumio[892]: info: [now-playing] ConfigUpdater: config is up to date. Mar 03 14:59:50 volumio volumio[892]: info: Loading i18n strings for locale ko Mar 03 14:59:50 volumio volumio[892]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 03 14:59:50 volumio volumio[892]: info: /tmp/myfifo created Mar 03 14:59:50 volumio volumio[892]: info: /tmp/myfifosa created Mar 03 14:59:50 volumio sudo[2732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Mar 03 14:59:50 volumio sudo[2732]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:50 volumio sudo[2732]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:50 volumio volumio[892]: info: snd-dummy loaded Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 14:59:50 volumio volumio[892]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 14:59:50 volumio volumio[892]: info: Loading i18n strings for locale ko Mar 03 14:59:50 volumio volumio[892]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 03 14:59:50 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 14:59:50 volumio volumio[892]: info: Volumio Calling Home Mar 03 14:59:50 volumio sudo[2755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 03 14:59:50 volumio sudo[2755]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:50 volumio sudo[2760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 03 14:59:50 volumio sudo[2760]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:50 volumio sudo[2757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 03 14:59:50 volumio sudo[2757]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:50 volumio volumio[892]: info: [now-playing] Fetching API key... Mar 03 14:59:50 volumio sudo[2755]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:50 volumio systemd[1]: Reloading. Mar 03 14:59:50 volumio volumio[892]: info: [now-playing] App is listening on port 4004. Mar 03 14:59:50 volumio volumio[892]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Mar 03 14:59:51 volumio volumio[892]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 03 14:59:51 volumio sudo[2778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 03 14:59:51 volumio sudo[2778]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:51 volumio volumio[892]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Mar 03 14:59:51 volumio volumio[892]: info: Volumio Network Manager: Network status updated: 1 Mar 03 14:59:51 volumio sudo[2778]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:51 volumio sudo[2775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 03 14:59:51 volumio sudo[2775]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:51 volumio sudo[2775]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 14:59:51 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 14:59:51 volumio volumio[892]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 03 14:59:51 volumio volumio[892]: info: MPD Permissions set Mar 03 14:59:51 volumio volumio[892]: info: MPD Permissions set Mar 03 14:59:51 volumio volumio[892]: info: Spotify config file written Mar 03 14:59:51 volumio volumio[892]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:51 volumio 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. Mar 03 14:59:51 volumio sudo[2783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 03 14:59:51 volumio sudo[2783]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:51 volumio volumio[892]: info: No need to fix Spotify hosts Mar 03 14:59:51 volumio sudo[2797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Mar 03 14:59:51 volumio sudo[2797]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:51 volumio volumio[892]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Mar 03 14:59:51 volumio volumio[892]: info: Starting Shairport Sync Mar 03 14:59:51 volumio sudo[2797]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:51 volumio sudo[2800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 03 14:59:51 volumio sudo[2800]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:51 volumio sudo[2800]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:51 volumio volumio[892]: info: Starting Shairport Sync Mar 03 14:59:51 volumio sudo[2803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 14:59:51 volumio sudo[2803]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:51 volumio mpd[2726]: Mar 03 14:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 14:59:51 volumio sudo[2805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 14:59:51 volumio sudo[2805]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:51 volumio volumio[892]: info: Starting Shairport Sync Mar 03 14:59:51 volumio sudo[2811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 14:59:51 volumio sudo[2811]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:51 volumio volumio[892]: info: Starting Shairport Sync Mar 03 14:59:51 volumio sudo[2814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 14:59:51 volumio sudo[2814]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:51 volumio sudo[2760]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:51 volumio systemd[1]: Started Music Player Daemon. Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 14:59:51 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 14:59:51 volumio sudo[2705]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:51 volumio sudo[2682]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:51 volumio sudo[2817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 03 14:59:51 volumio sudo[2817]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:51 volumio systemd[1]: Reloading. Mar 03 14:59:51 volumio volumio[892]: info: touch_display: systemctl daemon-reload succeeded. Mar 03 14:59:51 volumio volumio[892]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 03 14:59:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Mar 03 14:59:51 volumio sudo[2826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 03 14:59:51 volumio sudo[2826]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:51 volumio volumio[892]: info: Volumio called home Mar 03 14:59:51 volumio sudo[2838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 14:59:51 volumio sudo[2838]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:51 volumio sudo[2838]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:51 volumio volumio[892]: info: MPD running with PID2726 Mar 03 14:59:51 volumio volumio[892]: ,establishing connection Mar 03 14:59:52 volumio 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. Mar 03 14:59:52 volumio volumio[892]: error: MPD error: The expression evaluated to a falsy value: Mar 03 14:59:52 volumio volumio[892]: assert.ok(self.idling) Mar 03 14:59:52 volumio volumio[892]: error: The expression evaluated to a falsy value: Mar 03 14:59:52 volumio volumio[892]: assert.ok(self.idling) Mar 03 14:59:52 volumio volumio[892]: info: MPD Permissions set Mar 03 14:59:52 volumio volumio[892]: error: MPD error: The expression evaluated to a falsy value: Mar 03 14:59:52 volumio volumio[892]: assert.ok(self.idling) Mar 03 14:59:52 volumio volumio[892]: error: The expression evaluated to a falsy value: Mar 03 14:59:52 volumio volumio[892]: assert.ok(self.idling) Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 14:59:52 volumio volumio[892]: error: updateQueue error: null Mar 03 14:59:52 volumio volumio[892]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 03 14:59:52 volumio volumio[892]: SPOTIFY: BQC9gga0lpAa2lUihIIk_AFP0STufOR1ORewXouNlhGG2SBO7ZK4HW7MNeVAjpD-SZfVGTTWaqgCOIwkgM9CxwPBIP-2E6tN1JjNkZS2qu_yiZmyyWvdnMqG_cB8bzR8xgCoDzAWl0ZAiItnEPUMUx5M7BzmCzGcUqOW-kA9ILKIcFlU5xdDJCjWwxvZtOfrAzOWfkqoo4XOqKTE0Kxqkbncvgq7nro0UxBQ0qAp-tIE7vA-0gsiGltDu6L3qVnHBeYXaeQqF1TIpRAXmss_JKHSGAkinxq4GA4StG7AEoY Mar 03 14:59:52 volumio volumio[892]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 03 14:59:52 volumio volumio[892]: info: New Spotify access token = BQC9gga0lpAa2lUihIIk_AFP0STufOR1ORewXouNlhGG2SBO7ZK4HW7MNeVAjpD-SZfVGTTWaqgCOIwkgM9CxwPBIP-2E6tN1JjNkZS2qu_yiZmyyWvdnMqG_cB8bzR8xgCoDzAWl0ZAiItnEPUMUx5M7BzmCzGcUqOW-kA9ILKIcFlU5xdDJCjWwxvZtOfrAzOWfkqoo4XOqKTE0Kxqkbncvgq7nro0UxBQ0qAp-tIE7vA-0gsiGltDu6L3qVnHBeYXaeQqF1TIpRAXmss_JKHSGAkinxq4GA4StG7AEoY Mar 03 14:59:52 volumio volumio[892]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 03 14:59:52 volumio volumio[892]: SPOTIFY: User informations: {"country":"KR","display_name":"열혈남아","email":"musicreco08@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31axxd6o6cxmc34vml3ywsr25uym"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31axxd6o6cxmc34vml3ywsr25uym","id":"31axxd6o6cxmc34vml3ywsr25uym","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85c88581aca51d816c62fcd26f","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82c88581aca51d816c62fcd26f","width":64}],"product":"premium","type":"user","uri":"spotify:user:31axxd6o6cxmc34vml3ywsr25uym"} Mar 03 14:59:52 volumio volumio[892]: info: Spotify Successfully logged in Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 14:59:52 volumio volumio[892]: info: [1740981592326] CoreMusicLibrary::Adding element Spotify Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 14:59:52 volumio volumio[892]: Cannot find translation for source Spotify Mar 03 14:59:52 volumio squeezeboxserver[759]: [14:59:52.357] mDNSsearchCallback:691 [0x195a80]: changed ip:port 192.168.219.109:5000 Mar 03 14:59:52 volumio squeezeboxserver[759]: [14:59:52.358] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.17.0.1:5000 Mar 03 14:59:52 volumio squeezeboxserver[759]: [14:59:52.358] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.18.0.1:5000 Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 03 14:59:52 volumio sudo[2845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 14:59:52 volumio sudo[2845]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:52 volumio sudo[2757]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:52 volumio volumio[892]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 03 14:59:52 volumio systemd[1]: Stopping Music Player Daemon... Mar 03 14:59:52 volumio systemd[1]: Started Volumio Kiosk. Mar 03 14:59:52 volumio sudo[2826]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:52 volumio volumio[892]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 03 14:59:52 volumio volumio[892]: info: touch_display: Volumio Kiosk started. Mar 03 14:59:52 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 03 14:59:52 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 14:59:52 volumio sudo[2817]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:52 volumio systemd[1]: Started go-librespot Daemon. Mar 03 14:59:52 volumio squeezeboxserver[759]: [14:59:52.579] mDNSsearchCallback:682 [0x195a80]: removing renderer (volumio) Mar 03 14:59:52 volumio squeezeboxserver[759]: [14:59:52.579] slimproto_close:906 [0x13c280] slimproto stop for volumio-AirPlay Mar 03 14:59:52 volumio sudo[2783]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:52 volumio go-librespot[2852]: go-librespot daemon starting... Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 14:59:52 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 14:59:52 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 03 14:59:52 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 14:59:52 volumio volumio[892]: info: Completed starting Core Plugins Mar 03 14:59:52 volumio systemd[1]: mpd.service: Succeeded. Mar 03 14:59:52 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 14:59:52 volumio volumio[892]: info: ------------------------------------------- Mar 03 14:59:52 volumio volumio[892]: info: ----- MyVolumio plugins startup ---- Mar 03 14:59:52 volumio volumio[892]: info: ------------------------------------------- Mar 03 14:59:52 volumio volumio[892]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 03 14:59:52 volumio systemd[1]: Starting Music Player Daemon... Mar 03 14:59:52 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 14:59:52 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 14:59:52 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 14:59:52 volumio sudo[2805]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:52 volumio sudo[2803]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:52 volumio sudo[2811]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:52 volumio volumio[892]: info: Upmpdcli Daemon Started Mar 03 14:59:52 volumio volumio[892]: info: Shairport-Sync Started Mar 03 14:59:52 volumio volumio[892]: Error adding Membership: Error: addMembership EINVAL Mar 03 14:59:52 volumio volumio[892]: info: Shairport-Sync Started Mar 03 14:59:52 volumio volumio[892]: info: Shairport-Sync Started Mar 03 14:59:52 volumio sudo[2814]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:52 volumio volumio[892]: info: Shairport-Sync Started Mar 03 14:59:52 volumio volumio[892]: info: touch_display: X display number found: Mar 03 14:59:52 volumio sudo[2864]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 14:59:52 volumio sudo[2864]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:52 volumio squeezeboxserver[759]: [14:59:52.758] DelRaopDevice:1011 [0x195a80]: Raop device stopped Mar 03 14:59:52 volumio sudo[2864]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:52 volumio startx[2847]: X.Org X Server 1.20.4 Mar 03 14:59:52 volumio startx[2847]: X Protocol Version 11, Revision 0 Mar 03 14:59:52 volumio startx[2847]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Mar 03 14:59:52 volumio startx[2847]: Current Operating System: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Mar 03 14:59:52 volumio startx[2847]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1920 bcm2708_fb.fbheight=1080 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:8B:28:BF vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=826680f7-a407-4ab9-8bd5-1855bda526fd imgfile=/volumio_current.sqsh bootpart=UUID=FD90-ED9E datapart=UUID=686c8554-cef7-4a87-ba8c-7b62fc125f1c uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 03 14:59:52 volumio startx[2847]: Build Date: 04 April 2023 07:50:56AM Mar 03 14:59:52 volumio startx[2847]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Mar 03 14:59:52 volumio startx[2847]: Current version of pixman: 0.36.0 Mar 03 14:59:52 volumio startx[2847]: Before reporting problems, check http://wiki.x.org Mar 03 14:59:52 volumio startx[2847]: to make sure that you have the latest version. Mar 03 14:59:52 volumio startx[2847]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 03 14:59:52 volumio startx[2847]: (++) from command line, (!!) notice, (II) informational, Mar 03 14:59:52 volumio startx[2847]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 03 14:59:52 volumio startx[2847]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Mar 3 14:59:52 2025 Mar 03 14:59:52 volumio volumio[892]: info: touch_display: X display number found: 0 Mar 03 14:59:52 volumio startx[2847]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 03 14:59:52 volumio startx[2847]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 03 14:59:52 volumio volumio[892]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 03 14:59:52 volumio volumio[892]: info: touch_display: X display number found: 0 Mar 03 14:59:53 volumio go-librespot[2852]: time="2025-03-03T14:59:53+09:00" level=info msg="running go-librespot 0.1.3" Mar 03 14:59:53 volumio go-librespot[2852]: time="2025-03-03T14:59:53+09:00" level=debug msg="app state loaded" Mar 03 14:59:53 volumio go-librespot[2852]: time="2025-03-03T14:59:53+09:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 14:59:53 volumio go-librespot[2852]: time="2025-03-03T14:59:53+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 03 14:59:53 volumio go-librespot[2852]: time="2025-03-03T14:59:53+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 03 14:59:53 volumio go-librespot[2852]: time="2025-03-03T14:59:53+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 03 14:59:53 volumio go-librespot[2852]: time="2025-03-03T14:59:53+09:00" level=info msg="zeroconf server listening on port 46055" Mar 03 14:59:53 volumio go-librespot[2852]: time="2025-03-03T14:59:53+09:00" level=debug msg="obtained new client token: AACbrQId94EMYlmTyVmjt8iSBW9LN5OkQ18w/PQb1+u9w3D8rXI08ReN6E03GLPSLVPP7eZgtDJVJ7jXfcVY0110y2q/RE0BXeoIn+vuO5SiaBL+DCh3Jqn9PMBtnkEP5GKRuTfVwPjeO0wpuUZ/OZxfS9Kc8AJTsDSSB+VFEwDu0vLqvTZ3HJiA68XJrs5grnkkG51zR0wuuPNM9IGayruQcBmKV0FU32uoM/TFIBAglp/FFNHmn/EK8f0=" Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.461] AddRaopDevice:865 [0x195a80]: AirPort Express Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.461] AddRaopDevice:929 [0x195a80]: adding renderer (volumio@192.168.219.109) with mac AAAA-DF0F404D Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.461] raopcl_create:715 [0xb5f08d68]: using PCM coding Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.466] output_raop_thread_init:119 [0x13c280]: init output raop Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.466] output_init_common:290 allocating 1764000 Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.466] mDNSsearchCallback:746 Updating configuration /var/lib/squeezeboxserver/prefs/raopbridge.xml Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.467] slimproto:834 squeezelite [0x13c280] <=> player [0x195a80] Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.467] slimproto:835 [0x13c280] connecting to 192.168.219.109:3483 Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.467] slimproto:865 [0x13c280] connected Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.467] sendHELO:107 [0x13c280] cap: CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3 Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.468] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.17.0.1:5000 Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.468] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.18.0.1:5000 Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.470] output_flush:331 [0x13c280]: flush output buffer (full) Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.471] output_flush:331 [0x13c280]: flush output buffer (full) Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.472] PlayerThread:598 [0x195a80]: processing volume device:100 request:0.00 Mar 03 14:59:53 volumio go-librespot[2852]: time="2025-03-03T14:59:53+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.497] main:253 host: volumio.local Mar 03 14:59:53 volumio squeezeboxserver[759]: identity: E938298D6905@volumio-AirPlay Mar 03 14:59:53 volumio squeezeboxserver[759]: type: _raop._tcp.local Mar 03 14:59:53 volumio squeezeboxserver[759]: ip: 192.168.219.109 Mar 03 14:59:53 volumio squeezeboxserver[759]: port: 34733 Mar 03 14:59:53 volumio squeezeboxserver[759]: with TXT: Mar 03 14:59:53 volumio squeezeboxserver[759]: tp=UDP Mar 03 14:59:53 volumio squeezeboxserver[759]: sm=false Mar 03 14:59:53 volumio squeezeboxserver[759]: sv=false Mar 03 14:59:53 volumio squeezeboxserver[759]: ek=1 Mar 03 14:59:53 volumio squeezeboxserver[759]: et=0,1 Mar 03 14:59:53 volumio squeezeboxserver[759]: md=0,1,2 Mar 03 14:59:53 volumio squeezeboxserver[759]: cn=0,1 Mar 03 14:59:53 volumio squeezeboxserver[759]: ch=2 Mar 03 14:59:53 volumio squeezeboxserver[759]: ss=16 Mar 03 14:59:53 volumio squeezeboxserver[759]: sr=44100 Mar 03 14:59:53 volumio squeezeboxserver[759]: pw=false Mar 03 14:59:53 volumio squeezeboxserver[759]: vn=3 Mar 03 14:59:53 volumio squeezeboxserver[759]: txtvers=1 Mar 03 14:59:53 volumio squeezeboxserver[759]: am=shairtunes2 Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.498] mDNSsearchCallback:691 [0x195a80]: changed ip:port 192.168.219.109:5000 Mar 03 14:59:53 volumio go-librespot[2852]: time="2025-03-03T14:59:53+09:00" level=debug msg="completed keyexchange" Mar 03 14:59:53 volumio mpd[2887]: Mar 03 14:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 14:59:53 volumio systemd[1]: Started Music Player Daemon. Mar 03 14:59:53 volumio sudo[2845]: pam_unix(sudo:session): session closed for user root Mar 03 14:59:53 volumio volumio[892]: error: updateQueue error: null Mar 03 14:59:53 volumio volumio[2849]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 03 14:59:53 volumio squeezeboxserver[759]: [14:59:53.749] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.18.0.1:5000 Mar 03 14:59:53 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 14:59:53 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 14:59:54 volumio volumio[892]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=debug msg="completed challenge" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=info msg="authenticated AP as 31axxd6o6cxmc34vml3ywsr25uym" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=info msg="authenticated Login5 as 31axxd6o6cxmc34vml3ywsr25uym" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=debug msg="initializing zeroconf session, username: 31axxd6o6cxmc34vml3ywsr25uym" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=debug msg="dealer connection opened" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=trace msg="starting accesspoint recv loop" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=trace msg="received accesspoint ping" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=trace msg="starting dealer recv loop" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=debug msg="received connection id: MjU4YTllMWEtYzI0OS00MzhjLTg4NGItZjFkNTI3OGVlNDNkK2RlYWxlcit0Y3A6Ly8wYWM5NDgxOC5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArNTg3QUJDMTU2QTVGMzIwNDgxMzQyQkM1QTdBMTA3QzBBRTQ3REM0OEE0QjQzNjY1MTFEN0E4MTkzMTcwOUNFNw==" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=debug msg="put connect state because NEW_DEVICE" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=debug msg="update volume to 65535/65535" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 03 14:59:54 volumio squeezeboxserver[759]: [14:59:54.477] mDNSsearchCallback:691 [0x195a80]: changed ip:port 192.168.219.109:5000 Mar 03 14:59:54 volumio squeezeboxserver[759]: [14:59:54.477] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.17.0.1:5000 Mar 03 14:59:54 volumio squeezeboxserver[759]: [14:59:54.477] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.18.0.1:5000 Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 03 14:59:54 volumio go-librespot[2852]: time="2025-03-03T14:59:54+09:00" level=trace msg="received accesspoint pong ack" Mar 03 14:59:54 volumio sudo[2981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pi,password=nas,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.219.105/MUSE /mnt/NAS/MUSE Mar 03 14:59:54 volumio sudo[2981]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 14:59:54 volumio kernel: CIFS: Attempting to mount //192.168.219.105/MUSE Mar 03 14:59:55 volumio volumio[892]: info: [now-playing] OpenWeatherMap API key obtained. Mar 03 14:59:55 volumio go-librespot[2852]: time="2025-03-03T14:59:55+09:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 03 14:59:55 volumio go-librespot[2852]: time="2025-03-03T14:59:55+09:00" level=trace msg="emitting websocket event: volume" Mar 03 14:59:55 volumio volumio[892]: info: go-librespot daemon successfully initialized Mar 03 14:59:56 volumio volumio[892]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=37.55655&lon=127.03164: Error: Response error: 401 - Unauthorized Mar 03 14:59:56 volumio volumio[892]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Mar 03 14:59:56 volumio volumio[892]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Mar 03 14:59:56 volumio volumio[892]: at async Promise.all (index 1) Mar 03 14:59:56 volumio volumio[892]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Mar 03 14:59:56 volumio volumio[892]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Mar 03 14:59:56 volumio volumio[892]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Mar 03 14:59:56 volumio volumio[892]: info: [now-playing] Fetching API key... Mar 03 14:59:56 volumio volumio[892]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=37.55655&lon=127.03164: Error: Response error: 401 - Unauthorized Mar 03 14:59:56 volumio volumio[892]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Mar 03 14:59:56 volumio volumio[892]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Mar 03 14:59:56 volumio volumio[892]: at async Promise.all (index 0) Mar 03 14:59:56 volumio squeezeboxserver[759]: [14:59:56.492] mDNSsearchCallback:691 [0x195a80]: changed ip:port 192.168.219.109:5000 Mar 03 14:59:56 volumio squeezeboxserver[759]: [14:59:56.492] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.17.0.1:5000 Mar 03 14:59:56 volumio squeezeboxserver[759]: [14:59:56.492] mDNSsearchCallback:691 [0x195a80]: changed ip:port 172.18.0.1:5000 Mar 03 14:59:57 volumio volumio[892]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 03 14:59:58 volumio volumio-remote-updater[642]: [2025-03-03 14:59:58] [connect] Successful connection Mar 03 14:59:58 volumio volumio-remote-updater[642]: [2025-03-03 14:59:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740981598 101 Mar 03 14:59:58 volumio volumio[892]: 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: 4 Mar 03 14:59:58 volumio volumio[892]: info: Initializing connection to go-librespot Websocket Mar 03 14:59:58 volumio go-librespot[2852]: time="2025-03-03T14:59:58+09:00" level=debug msg="new websocket client" Mar 03 14:59:58 volumio volumio[892]: info: Connection to go-librespot Websocket established Mar 03 14:59:59 volumio systemd[1]: systemd-fsckd.service: Succeeded. Mar 03 14:59:59 volumio volumio[892]: info: [now-playing] OpenWeatherMap API key obtained. Mar 03 14:59:59 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 14:59:59 volumio volumio[892]: info: Discovery: Getting this device information Mar 03 14:59:59 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 14:59:59 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 14:59:59 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 15:00:00 volumio volumio[2849]: ......................................................................++++ Mar 03 15:00:00 volumio volumio[892]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=37.55655&lon=127.03164: Error: Response error: 401 - Unauthorized Mar 03 15:00:00 volumio volumio[892]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Mar 03 15:00:00 volumio volumio[892]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Mar 03 15:00:00 volumio volumio[892]: at async Promise.all (index 0) Mar 03 15:00:00 volumio volumio[892]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Mar 03 15:00:00 volumio volumio[892]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Mar 03 15:00:00 volumio volumio[892]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Mar 03 15:00:00 volumio volumio[892]: (node:892) UnhandledPromiseRejectionWarning: Error: Response error: 401 - Unauthorized Mar 03 15:00:00 volumio volumio[892]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Mar 03 15:00:00 volumio volumio[892]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Mar 03 15:00:00 volumio volumio[892]: at async Promise.all (index 0) Mar 03 15:00:00 volumio volumio[892]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Mar 03 15:00:00 volumio volumio[892]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Mar 03 15:00:00 volumio volumio[892]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Mar 03 15:00:00 volumio volumio[892]: (Use `node --trace-warnings ...` to show where the warning was created) Mar 03 15:00:00 volumio volumio[892]: (node:892) 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) Mar 03 15:00:00 volumio volumio[892]: (node:892) [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. Mar 03 15:00:00 volumio volumio[892]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=37.55655&lon=127.03164: Error: Response error: 401 - Unauthorized Mar 03 15:00:00 volumio volumio[892]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Mar 03 15:00:00 volumio volumio[892]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Mar 03 15:00:00 volumio volumio[892]: at async Promise.all (index 1) Mar 03 15:00:01 volumio sudo[2981]: pam_unix(sudo:session): session closed for user root Mar 03 15:00:01 volumio volumio[892]: info: Cannot mount NAS MUSE at system boot, trial number 2 ,retrying in 5 seconds Mar 03 15:00:01 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 03 15:00:01 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 03 15:00:01 volumio volumio[892]: info: Adding plugin bluetooth to MyMusic Plugins Mar 03 15:00:01 volumio volumio[892]: info: Adding plugin multiroom to MyMusic Plugins Mar 03 15:00:01 volumio volumio[892]: info: Adding plugin metavolumio to MyMusic Plugins Mar 03 15:00:01 volumio volumio[892]: info: Adding plugin cd_controller to MyMusic Plugins Mar 03 15:00:01 volumio volumio[892]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 03 15:00:01 volumio volumio[892]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 03 15:00:01 volumio volumio[892]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 03 15:00:01 volumio volumio[2849]: ...........++++ Mar 03 15:00:01 volumio volumio[2849]: e is 65537 (0x010001) Mar 03 15:00:01 volumio volumio[2849]: writing RSA key Mar 03 15:00:01 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Mar 03 15:00:02 volumio volumio[892]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 03 15:00:02 volumio volumio[892]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 03 15:00:02 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 15:00:02 volumio volumio[892]: info: Starting MyVolumio Remote Streaming Endpoints Mar 03 15:00:02 volumio volumio[892]: info: MyVolumio login type: Token Mar 03 15:00:02 volumio volumio[892]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 03 15:00:02 volumio volumio[892]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 03 15:00:03 volumio volumio[892]: info: Starting Streaming Service Transparent Proxy Mar 03 15:00:03 volumio volumio[892]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 03 15:00:03 volumio volumio[892]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 03 15:00:03 volumio volumio[892]: info: Streaming services startup Mar 03 15:00:03 volumio volumio[892]: info: Starting Streaming Daemon Mar 03 15:00:03 volumio sudo[3165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 03 15:00:03 volumio sudo[3165]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 15:00:03 volumio sudo[3165]: pam_unix(sudo:session): session closed for user root Mar 03 15:00:03 volumio volumio[892]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 03 15:00:03 volumio volumio[892]: info: Getting Spotify volume Mar 03 15:00:03 volumio volumio[892]: error: Cannot start Volumio Streaming Daemon Mar 03 15:00:03 volumio volumio[892]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 03 15:00:03 volumio volumio[892]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 03 15:00:03 volumio volumio[892]: info: Spotify volume: 100 Mar 03 15:00:03 volumio volumio[892]: verbose: New Socket.io Connection to localhost: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 Mar 03 15:00:03 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 15:00:03 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 15:00:03 volumio volumio[892]: info: Discovery: Getting this device information Mar 03 15:00:03 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:03 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:03 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 15:00:03 volumio volumio[892]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 03 15:00:04 volumio volumio[892]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Mar 03 15:00:04 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Mar 03 15:00:04 volumio volumio[892]: info: Listing playlists Mar 03 15:00:04 volumio volumio[892]: info: CoreCommandRouter::volumioGetQueue Mar 03 15:00:04 volumio volumio[892]: info: CoreStateMachine::getQueue Mar 03 15:00:04 volumio volumio[892]: info: CorePlayQueue::getQueue Mar 03 15:00:04 volumio volumio[892]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 15:00:04 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 15:00:04 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:04 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:04 volumio volumio[892]: verbose: New Socket.io Connection to 192.168.219.109:3000 from 192.168.219.100 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Mar 03 15:00:04 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:04 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:04 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 03 15:00:04 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 03 15:00:04 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:04 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:04 volumio volumio[892]: error: MyVolumio Custom Token format not valid, refreshing it Mar 03 15:00:04 volumio volumio[892]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=37.55655&lon=127.03164: Error: Response error: 401 - Unauthorized Mar 03 15:00:04 volumio volumio[892]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Mar 03 15:00:04 volumio volumio[892]: at runMicrotasks () Mar 03 15:00:04 volumio volumio[892]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Mar 03 15:00:04 volumio volumio[892]: at async Promise.all (index 1) Mar 03 15:00:04 volumio volumio[892]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Mar 03 15:00:04 volumio volumio[892]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Mar 03 15:00:04 volumio volumio[892]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Mar 03 15:00:04 volumio volumio[892]: info: [now-playing] Fetching API key... Mar 03 15:00:04 volumio volumio[892]: STREAMING PROXY: Starting server on port 3245 Mar 03 15:00:04 volumio volumio[892]: Node JS runtime: 14 Mar 03 15:00:04 volumio volumio[892]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=37.55655&lon=127.03164: Error: Response error: 401 - Unauthorized Mar 03 15:00:04 volumio volumio[892]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Mar 03 15:00:04 volumio volumio[892]: at runMicrotasks () Mar 03 15:00:04 volumio volumio[892]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Mar 03 15:00:04 volumio volumio[892]: at async Promise.all (index 0) Mar 03 15:00:05 volumio sudo[3176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 03 15:00:05 volumio sudo[3176]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 15:00:05 volumio sudo[3176]: pam_unix(sudo:session): session closed for user root Mar 03 15:00:05 volumio sudo[3179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 03 15:00:05 volumio sudo[3179]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 15:00:05 volumio sudo[3179]: pam_unix(sudo:session): session closed for user root Mar 03 15:00:05 volumio volumio[892]: info: MyVolumio login type: Token Mar 03 15:00:05 volumio volumio[892]: verbose: New Socket.io Connection to 192.168.219.109 from 192.168.219.100 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 03 15:00:05 volumio sudo[3182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 03 15:00:05 volumio sudo[3182]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 15:00:05 volumio sudo[3182]: pam_unix(sudo:session): session closed for user root Mar 03 15:00:05 volumio sudo[3185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 03 15:00:05 volumio sudo[3185]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 15:00:05 volumio sudo[3185]: pam_unix(sudo:session): session closed for user root Mar 03 15:00:05 volumio volumio[892]: verbose: New Socket.io Connection to 192.168.219.109 from 192.168.219.100 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:05 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 03 15:00:05 volumio volumio[892]: info: Received Get System Info Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 15:00:05 volumio volumio[892]: info: Discovery: Getting this device information Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:05 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:05 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:05 volumio volumio[892]: info: Listing playlists Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 03 15:00:05 volumio volumio[892]: verbose: New Socket.io Connection to localhost: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: 10 Mar 03 15:00:05 volumio volumio[892]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Mar 03 15:00:05 volumio volumio[892]: info: Listing playlists Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::volumioGetQueue Mar 03 15:00:05 volumio volumio[892]: info: CoreStateMachine::getQueue Mar 03 15:00:05 volumio volumio[892]: info: CorePlayQueue::getQueue Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:05 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:05 volumio volumio[892]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Mar 03 15:00:05 volumio volumio[892]: info: Listing playlists Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::volumioGetQueue Mar 03 15:00:05 volumio volumio[892]: info: CoreStateMachine::getQueue Mar 03 15:00:05 volumio volumio[892]: info: CorePlayQueue::getQueue Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:05 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 03 15:00:06 volumio sudo[3188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pi,password=nas,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.219.105/MUSE /mnt/NAS/MUSE Mar 03 15:00:06 volumio sudo[3188]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 15:00:06 volumio kernel: CIFS: Attempting to mount //192.168.219.105/MUSE Mar 03 15:00:06 volumio volumio[892]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 03 15:00:07 volumio volumio[892]: info: MyVolumio token set successfully Mar 03 15:00:07 volumio volumio[892]: info: MYVOLUMIO: Adding device Mar 03 15:00:07 volumio volumio[892]: info: MYVOLUMIO: Evaluating Server Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 15:00:07 volumio volumio[892]: info: Discovery: Getting this device information Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:07 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 03 15:00:07 volumio volumio[892]: info: MyVolumio status changed Mar 03 15:00:07 volumio volumio[892]: info: Streaming services startup Mar 03 15:00:07 volumio volumio[892]: info: Starting Streaming Daemon Mar 03 15:00:07 volumio volumio[892]: info: Removing browser output: myVolumio user plan is not superstar Mar 03 15:00:07 volumio volumio[892]: info: Removing audio output: Mar 03 15:00:07 volumio volumio[892]: info: Stoppping Tunnel 1 Mar 03 15:00:07 volumio sudo[3213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 03 15:00:07 volumio sudo[3213]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 03 15:00:07 volumio volumio[892]: info: Received Get System Info Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 15:00:07 volumio volumio[892]: info: Discovery: Getting this device information Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:07 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 15:00:07 volumio sudo[3213]: pam_unix(sudo:session): session closed for user root Mar 03 15:00:07 volumio sudo[3216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 03 15:00:07 volumio volumio[892]: error: Cannot start Volumio Streaming Daemon Mar 03 15:00:07 volumio volumio[892]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 03 15:00:07 volumio volumio[892]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 03 15:00:07 volumio sudo[3216]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 03 15:00:07 volumio sudo[3216]: pam_unix(sudo:session): session closed for user root Mar 03 15:00:07 volumio volumio[892]: info: Remote SSH Stopped Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::volumioGetQueue Mar 03 15:00:07 volumio volumio[892]: info: CoreStateMachine::getQueue Mar 03 15:00:07 volumio volumio[892]: info: CorePlayQueue::getQueue Mar 03 15:00:07 volumio volumio[892]: info: Setting Geolocation for MyVolumio to as1 Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 15:00:07 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 15:00:08 volumio volumio[892]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 03 15:00:08 volumio volumio[892]: info: Updating MyVolumio device info Mar 03 15:00:08 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 15:00:08 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 15:00:08 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 15:00:09 volumio volumio[892]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 03 15:00:10 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 03 15:00:10 volumio volumio[892]: info: Received Get System Info Mar 03 15:00:10 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 15:00:10 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 15:00:10 volumio volumio[892]: info: Discovery: Getting this device information Mar 03 15:00:10 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:10 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:10 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 15:00:11 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 03 15:00:11 volumio volumio[892]: info: Received Get System Info Mar 03 15:00:11 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 15:00:11 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 15:00:11 volumio volumio[892]: info: Discovery: Getting this device information Mar 03 15:00:11 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:11 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:11 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 15:00:11 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 03 15:00:11 volumio volumio[892]: info: Received Get System Info Mar 03 15:00:11 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 15:00:11 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 15:00:11 volumio volumio[892]: info: Discovery: Getting this device information Mar 03 15:00:11 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:11 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:11 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 15:00:12 volumio systemd[1]: systemd-timedated.service: Succeeded. Mar 03 15:00:12 volumio volumio[892]: info: MYVOLUMIO: Adding device Mar 03 15:00:12 volumio volumio[892]: info: MYVOLUMIO: Evaluating Server Mar 03 15:00:12 volumio sudo[3188]: pam_unix(sudo:session): session closed for user root Mar 03 15:00:12 volumio volumio[892]: info: Cannot mount NAS MUSE at system boot, trial number 3 ,retrying in 5 seconds Mar 03 15:00:12 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 03 15:00:12 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 15:00:13 volumio volumio[892]: info: Retrieving Cloud Streaming UI Mar 03 15:00:13 volumio volumio[892]: info: Getting Tidal Cloud Configuration Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 15:00:13 volumio volumio[892]: info: Getting Qobuz Cloud Configuration Mar 03 15:00:13 volumio volumio[892]: info: Asking plugin for UI Config Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 15:00:13 volumio volumio[892]: info: Getting Spotify Cloud Configuration Mar 03 15:00:13 volumio volumio[892]: info: Asking plugin for UI Config Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 15:00:13 volumio volumio[892]: info: Saving Spotify Acccount Mar 03 15:00:13 volumio volumio[892]: info: Got Tidal Cloud Configuration Mar 03 15:00:13 volumio volumio[892]: info: Got it Mar 03 15:00:13 volumio volumio[892]: info: Got it Mar 03 15:00:13 volumio volumio[892]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 15:00:13 volumio volumio[892]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 15:00:13 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 03 15:00:14 volumio volumio[892]: info: Setting Geolocation for MyVolumio to as1 Mar 03 15:00:14 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 15:00:14 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 15:00:14 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 15:00:15 volumio volumio[892]: info: Updating MyVolumio device info Mar 03 15:00:15 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 15:00:15 volumio volumio[892]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 03 15:00:15 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 03 15:00:15 volumio volumio[892]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 03 15:00:17 volumio sudo[3435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pi,password=nas,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.219.105/MUSE /mnt/NAS/MUSE Mar 03 15:00:17 volumio sudo[3435]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 15:00:17 volumio kernel: CIFS: Attempting to mount //192.168.219.105/MUSE Mar 03 15:00:17 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 03 15:00:17 volumio volumio[892]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 03 15:00:17 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:17 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:17 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Mar 03 15:00:17 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 15:00:18 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 03 15:00:20 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 15:00:20 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 15:00:20 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 03 15:00:20 volumio volumio-remote-updater[642]: No test mode Mar 03 15:00:20 volumio volumio-remote-updater[642]: No alpha test mode Mar 03 15:00:20 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 03 15:00:21 volumio volumio[892]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 03 15:00:21 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 03 15:00:22 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 15:00:22 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 03 15:00:22 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 03 15:00:22 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 03 15:00:22 volumio volumio[892]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 15:00:22 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 15:00:23 volumio sudo[3435]: pam_unix(sudo:session): session closed for user root Mar 03 15:00:23 volumio volumio[892]: info: Cannot mount NAS MUSE at system boot, trial number 4 ,retrying in 5 seconds Mar 03 15:00:23 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 03 15:00:23 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Mar 03 15:00:23 volumio volumio[892]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 03 15:00:23 volumio volumio[892]: info: Completed starting MyVolumio Plugin Mar 03 15:00:23 volumio volumio[892]: [Metrics] CommandRouter: 46s 118.30ms Mar 03 15:00:23 volumio volumio[892]: info: CoreCommandRouter::volumiosetStartupVolume Mar 03 15:00:23 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 15:00:23 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 15:00:23 volumio volumio[892]: info: CoreCommandRouter::Close All Modals sent Mar 03 15:00:23 volumio volumio[892]: info: CoreCommandRouter::Close All Modals sent Mar 03 15:00:24 volumio go-librespot[2852]: time="2025-03-03T15:00:24+09:00" level=trace msg="sent dealer ping" Mar 03 15:00:24 volumio go-librespot[2852]: time="2025-03-03T15:00:24+09:00" level=trace msg="received dealer pong" Mar 03 15:00:24 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 03 15:00:24 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 03 15:00:24 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 03 15:00:25 volumio volumio[892]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 03 15:00:25 volumio volumio[892]: info: Received Get System Version Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 15:00:25 volumio volumio[892]: info: Received Get System Info Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 15:00:25 volumio volumio[892]: info: Discovery: Getting this device information Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::volumioGetState Mar 03 15:00:25 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 15:00:25 volumio volumio[892]: info: Retrieving Cloud Streaming UI Mar 03 15:00:25 volumio volumio[892]: info: Getting Tidal Cloud Configuration Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 15:00:25 volumio volumio[892]: info: Getting Qobuz Cloud Configuration Mar 03 15:00:25 volumio volumio[892]: info: Asking plugin for UI Config Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 15:00:25 volumio volumio[892]: info: Getting Spotify Cloud Configuration Mar 03 15:00:25 volumio volumio[892]: info: Asking plugin for UI Config Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 15:00:25 volumio volumio[892]: info: Saving Spotify Acccount Mar 03 15:00:25 volumio volumio[892]: info: Got Tidal Cloud Configuration Mar 03 15:00:25 volumio volumio[892]: info: Got it Mar 03 15:00:25 volumio volumio[892]: info: Got it Mar 03 15:00:25 volumio volumio[892]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 15:00:25 volumio volumio[892]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 15:00:25 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 03 15:00:27 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Mar 03 15:00:27 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand rescan Mar 03 15:00:27 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Mar 03 15:00:27 volumio volumio[892]: info: Mar 03 15:00:27 volumio volumio[892]: ---------------------------- MPD announces state update: update Mar 03 15:00:27 volumio volumio[892]: info: sendMpdCommand rescan took 6 milliseconds Mar 03 15:00:27 volumio volumio[892]: info: ControllerMpd::getState Mar 03 15:00:27 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status Mar 03 15:00:27 volumio volumio[892]: info: sendMpdCommand status took 4 milliseconds Mar 03 15:00:27 volumio volumio[892]: info: sendMpdCommand status took 2 milliseconds Mar 03 15:00:27 volumio volumio[892]: verbose: ControllerMpd::parseState Mar 03 15:00:27 volumio volumio[892]: info: Command Router : Notfying DB Updatetrue Mar 03 15:00:27 volumio volumio[892]: verbose: ControllerMpd::parseState Mar 03 15:00:27 volumio volumio[892]: info: ControllerMpd::pushState Mar 03 15:00:27 volumio volumio[892]: info: CoreCommandRouter::servicePushState Mar 03 15:00:27 volumio volumio[892]: info: CoreStateMachine::pushState Mar 03 15:00:27 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:27 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Mar 03 15:00:27 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:27 volumio volumio[892]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 03 15:00:27 volumio volumio[892]: verbose: CURRENT POSITION 0 Mar 03 15:00:27 volumio volumio[892]: info: CoreStateMachine::syncState stateService stop Mar 03 15:00:27 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus stop Mar 03 15:00:27 volumio volumio[892]: info: CoreStateMachine::pushState Mar 03 15:00:27 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:27 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 15:00:27 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Mar 03 15:00:27 volumio volumio[892]: info: No code Mar 03 15:00:27 volumio volumio[892]: info: CoreStateMachine::pushState Mar 03 15:00:27 volumio volumio[892]: info: CorePlayQueue::getTrack 0 Mar 03 15:00:27 volumio volumio[892]: info: CoreCommandRouter::volumioPushState Mar 03 15:00:27 volumio volumio[892]: info: ------------------------------ 169ms Mar 03 15:00:28 volumio volumio[892]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 15:00:28 volumio volumio[892]: TypeError: Cannot read property 'replace' of undefined Mar 03 15:00:28 volumio volumio[892]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Mar 03 15:00:28 volumio volumio[892]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Mar 03 15:00:28 volumio volumio[892]: at listOnTimeout (internal/timers.js:554:17) Mar 03 15:00:28 volumio volumio[892]: at processTimers (internal/timers.js:497:7) Mar 03 15:00:28 volumio volumio[892]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 15:00:28 volumio sudo[3475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-03 14:59 Mar 03 15:00:28 volumio sudo[3475]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"