-- Logs begin at Fri 2021-09-03 11:41:24 UTC, end at Sun 2024-08-25 12:12:59 UTC. -- Aug 25 12:11:00 volumio systemd[1]: Time has been changed Aug 25 12:11:01 volumio volumio-remote-updater[550]: [2024-08-25 12:11:01] [connect] Successful connection Aug 25 12:11:02 volumio volumio[1001]: info: ControllerPodcast::constructor Aug 25 12:11:03 volumio volumio[1001]: info: Loading plugin "spop"... Aug 25 12:11:04 volumio volumio[1001]: info: Loading plugin "volspotconnect2"... Aug 25 12:11:04 volumio sudo[1071]: pam_unix(sudo:session): session closed for user root Aug 25 12:11:04 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Aug 25 12:11:04 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Aug 25 12:11:05 volumio volumio[1001]: info: ___________ START PLUGINS ___________ Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 12:11:05 volumio volumio[1001]: info: [1724587865189] CoreMusicLibrary::Adding element Media Servers Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:11:05 volumio volumio[1001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 12:11:05 volumio volumio[1001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 12:11:05 volumio volumio[1001]: info: [1724587865312] CoreMusicLibrary::Adding element Last_100 Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 12:11:05 volumio volumio[1001]: info: [1724587865319] CoreMusicLibrary::Adding element Webradio Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 12:11:05 volumio volumio[1001]: info: [1724587865340] CoreMusicLibrary::Adding element Podcast Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:11:05 volumio volumio[1001]: [SpotifyConnect] Creating VLS config file Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:11:05 volumio volumio[1001]: [SpotifyConnect] Starting metadata listener Aug 25 12:11:05 volumio sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Aug 25 12:11:05 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:11:05 volumio volumio[1001]: info: ------------------------------------------- Aug 25 12:11:05 volumio volumio[1001]: info: ----- MyVolumio plugins startup ---- Aug 25 12:11:05 volumio volumio[1001]: info: ------------------------------------------- Aug 25 12:11:05 volumio volumio[1001]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 25 12:11:05 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Aug 25 12:11:05 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Aug 25 12:11:05 volumio systemd[1]: Starting Spotify Daemon... Aug 25 12:11:05 volumio systemd[1]: Started Spotify Daemon. Aug 25 12:11:05 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root Aug 25 12:11:05 volumio sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Aug 25 12:11:05 volumio volumio[1001]: info: Loading i18n strings for locale ja Aug 25 12:11:05 volumio sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:11:05 volumio volumio[1001]: Updating browse sources language Aug 25 12:11:05 volumio volumio[1001]: Cannot find translation for sourceMedia Servers Aug 25 12:11:05 volumio volumio[1001]: Cannot find translation for sourcePodcast Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:11:05 volumio systemd[1]: Starting Volspotconnect2 Daemon... Aug 25 12:11:05 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 25 12:11:05 volumio sudo[1150]: pam_unix(sudo:session): session closed for user root Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::initPlayerControls Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 12:11:05 volumio volumio[1001]: info: BOOT COMPLETED Aug 25 12:11:05 volumio volumio[1001]: [Metrics] CommandRouter: 12s 657.81ms Aug 25 12:11:05 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:11:05 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 25 12:11:05 volumio volumio[1151]: 2024-08-25 12:11:05 [SPTF] 12:11:05.648 I [offline_authorizer.cpp:297] Unable to login offline: no such user Aug 25 12:11:05 volumio volumio[1151]: 2024-08-25 12:11:05 [SPTF] 12:11:05.649 I [ap:1752] Connecting to AP ap.spotify.com:4070 Aug 25 12:11:05 volumio volumio[1159]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Aug 25 12:11:05 volumio volumio[1159]: Reading Config from "volspotify.toml" Aug 25 12:11:05 volumio volumio[1159]: [Vollibrespot] : Using Alsa backend with device: plughw:2 Aug 25 12:11:05 volumio volumio[1151]: 2024-08-25 12:11:05 [SPTF] 12:11:05.707 I [ap:1226] Connected to AP: 104.199.240.237:4070 Aug 25 12:11:05 volumio volumio[1159]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Aug 25 12:11:05 volumio volumio[1151]: 2024-08-25 12:11:05 [SPTF] 12:11:05.911 E [ap:3953] Connection error: 409 Aug 25 12:11:05 volumio volumio[1151]: 2024-08-25 12:11:05 [WARN] Login failed: User needs a premium account Aug 25 12:11:05 volumio volumio[1151]: 2024-08-25 12:11:05 [ERR ] Could not get the playlist container. Aug 25 12:11:05 volumio systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Aug 25 12:11:05 volumio systemd[1]: Unit spop.service entered failed state. Aug 25 12:11:06 volumio ifup[533]: dhcpcd[579]: timed out Aug 25 12:11:06 volumio dhcpcd[579]: timed out Aug 25 12:11:06 volumio dhcpcd[579]: exited Aug 25 12:11:06 volumio ifup[533]: dhcpcd[579]: exited Aug 25 12:11:06 volumio ifup[533]: Failed to bring up eth0. Aug 25 12:11:10 volumio volumio[1001]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 25 12:11:10 volumio volumio[1001]: info: CoreCommandRouter::Close All Modals sent Aug 25 12:11:10 volumio volumio[1001]: info: CoreCommandRouter::Close All Modals sent Aug 25 12:11:10 volumio volumio[1001]: Express server listening on port 3000 Aug 25 12:11:10 volumio volumio[1001]: [Metrics] WebUI: 18s 707.20ms Aug 25 12:11:10 volumio volumio[1001]: Volumio Calling Home Aug 25 12:11:10 volumio volumio[1001]: info: CoreStateMachine::resetVolumioState Aug 25 12:11:10 volumio volumio[1001]: info: CoreStateMachine::getcurrentVolume Aug 25 12:11:10 volumio volumio[1001]: info: CoreCommandRouter::volumioRetrievevolume Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:11:11 volumio sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 12:11:11 volumio sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 12:11:11 volumio sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:11:11 volumio sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:11:11 volumio sudo[1186]: pam_unix(sudo:session): session closed for user root Aug 25 12:11:11 volumio sudo[1188]: pam_unix(sudo:session): session closed for user root Aug 25 12:11:11 volumio volumio[1001]: info: Setting Device type: Raspberry PI Aug 25 12:11:11 volumio volumio[1001]: [SpotifyConnect] Vollibrespot Daemon service started! Aug 25 12:11:11 volumio volumio[1001]: [Metrics] SpotifyConnect: 5s 843.20ms Aug 25 12:11:11 volumio volumio[1001]: info: SpopD Daemon Started Aug 25 12:11:11 volumio volumio[1001]: info: Cannot mount NAS Volumio at system boot, trial number 1 ,retrying in 5 seconds Aug 25 12:11:11 volumio volumio[1001]: error: Cannot initialize MPD Connection: MPD is not running Aug 25 12:11:11 volumio volumio[1001]: info: VolumeController:: Volume=51 Mute =false Aug 25 12:11:11 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 25 12:11:11 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:11:11 volumio volumio[1001]: info: CoreStateMachine::updateTrackBlock Aug 25 12:11:11 volumio volumio[1001]: info: CorePlayQueue::getTrackBlock Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::volumioRetrievevolume Aug 25 12:11:11 volumio volumio[1001]: info: Reloading queue from file Aug 25 12:11:11 volumio volumio[1001]: info: CoreStateMachine::setRepeat true single undefined Aug 25 12:11:11 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 25 12:11:11 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:11:11 volumio volumio[1001]: info: CoreStateMachine::setRandom false Aug 25 12:11:11 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 25 12:11:11 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:11:11 volumio volumio[1001]: Discovery: Changing my name to volumio CINGHIALE is undefined Aug 25 12:11:11 volumio avahi-daemon[567]: Withdrawing workstation service for wlan0. Aug 25 12:11:11 volumio avahi-daemon[567]: Withdrawing workstation service for eth0. Aug 25 12:11:11 volumio avahi-daemon[567]: Withdrawing workstation service for lo. Aug 25 12:11:11 volumio avahi-daemon[567]: Host name conflict, retrying with volumio-2 Aug 25 12:11:11 volumio avahi-daemon[567]: Registering new address record for 192.168.11.7 on wlan0.IPv4. Aug 25 12:11:11 volumio avahi-daemon[567]: Registering HINFO record with values 'ARMV7L'/'LINUX'. Aug 25 12:11:11 volumio volumio[1001]: info: mDNS: A device disapperared from network Aug 25 12:11:11 volumio volumio[1001]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Aug 25 12:11:11 volumio volumio[1001]: info: Starting Shairport Sync Aug 25 12:11:11 volumio sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 12:11:11 volumio volumio[1001]: info: Starting Shairport Sync Aug 25 12:11:11 volumio sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:11:11 volumio sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 25 12:11:11 volumio sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:11:11 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 25 12:11:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 25 12:11:11 volumio sudo[1217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 12:11:11 volumio sudo[1217]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:11:11 volumio volumio[1001]: info: VolumeController:: Volume=51 Mute =false Aug 25 12:11:11 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 25 12:11:11 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:11:11 volumio volumio[1001]: Discovery: adding 1d1da114-df90-4fdd-b3db-7ace96709810 Aug 25 12:11:11 volumio volumio[1001]: info: mDNS: Found device Volumio-2 Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Aug 25 12:11:11 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:11:11 volumio sudo[1208]: pam_unix(sudo:session): session closed for user root Aug 25 12:11:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 25 12:11:11 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Aug 25 12:11:11 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 25 12:11:11 volumio sudo[1210]: pam_unix(sudo:session): session closed for user root Aug 25 12:11:11 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 25 12:11:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 25 12:11:11 volumio sudo[1217]: pam_unix(sudo:session): session closed for user root Aug 25 12:11:11 volumio volumio[1001]: info: Shairport-Sync Started Aug 25 12:11:11 volumio volumio[1001]: Error adding Membership: Error: addMembership EINVAL Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 25 12:11:11 volumio systemd[1]: Starting Music Player Daemon... Aug 25 12:11:11 volumio volumio[1001]: info: Upmpdcli Daemon Started Aug 25 12:11:11 volumio volumio[1001]: info: Shairport-Sync Started Aug 25 12:11:11 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Aug 25 12:11:11 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:11:11 volumio mpd[1257]: /usr/bin/mpd: error while loading shared libraries: libbcm_host.so: cannot open shared object file: No such file or directory Aug 25 12:11:11 volumio systemd[1]: mpd.service: main process exited, code=exited, status=127/n/a Aug 25 12:11:11 volumio systemd[1]: Failed to start Music Player Daemon. Aug 25 12:11:11 volumio systemd[1]: Unit mpd.service entered failed state. Aug 25 12:11:12 volumio systemd[1]: Starting Music Player Daemon... Aug 25 12:11:12 volumio volumio[1001]: Discovery: this is already registered, 1d1da114-df90-4fdd-b3db-7ace96709810 Aug 25 12:11:12 volumio volumio[1001]: info: mDNS: Found device Volumio-2 Aug 25 12:11:12 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Aug 25 12:11:12 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:11:12 volumio mpd[1259]: /usr/bin/mpd: error while loading shared libraries: libbcm_host.so: cannot open shared object file: No such file or directory Aug 25 12:11:12 volumio systemd[1]: mpd.service: main process exited, code=exited, status=127/n/a Aug 25 12:11:12 volumio systemd[1]: Failed to start Music Player Daemon. Aug 25 12:11:12 volumio systemd[1]: Unit mpd.service entered failed state. Aug 25 12:11:12 volumio systemd[1]: Starting Music Player Daemon... Aug 25 12:11:12 volumio mpd[1260]: /usr/bin/mpd: error while loading shared libraries: libbcm_host.so: cannot open shared object file: No such file or directory Aug 25 12:11:12 volumio systemd[1]: mpd.service: main process exited, code=exited, status=127/n/a Aug 25 12:11:12 volumio systemd[1]: Failed to start Music Player Daemon. Aug 25 12:11:12 volumio systemd[1]: Unit mpd.service entered failed state. Aug 25 12:11:12 volumio systemd[1]: Starting Music Player Daemon... Aug 25 12:11:12 volumio mpd[1262]: /usr/bin/mpd: error while loading shared libraries: libbcm_host.so: cannot open shared object file: No such file or directory Aug 25 12:11:12 volumio volumio[1001]: info: Volumio called home Aug 25 12:11:12 volumio systemd[1]: mpd.service: main process exited, code=exited, status=127/n/a Aug 25 12:11:12 volumio systemd[1]: Failed to start Music Player Daemon. Aug 25 12:11:12 volumio systemd[1]: Unit mpd.service entered failed state. Aug 25 12:11:12 volumio systemd[1]: Starting Music Player Daemon... Aug 25 12:11:12 volumio mpd[1264]: /usr/bin/mpd: error while loading shared libraries: libbcm_host.so: cannot open shared object file: No such file or directory Aug 25 12:11:12 volumio systemd[1]: mpd.service: main process exited, code=exited, status=127/n/a Aug 25 12:11:12 volumio systemd[1]: Failed to start Music Player Daemon. Aug 25 12:11:12 volumio systemd[1]: Unit mpd.service entered failed state. Aug 25 12:11:12 volumio systemd[1]: Starting Music Player Daemon... Aug 25 12:11:12 volumio systemd[1]: mpd.service start request repeated too quickly, refusing to start. Aug 25 12:11:12 volumio systemd[1]: Failed to start Music Player Daemon. Aug 25 12:11:12 volumio systemd[1]: Unit mpd.socket entered failed state. Aug 25 12:11:12 volumio avahi-daemon[567]: Server startup complete. Host name is volumio-2.local. Local service cookie is 3385595606. Aug 25 12:11:12 volumio volumio[1001]: error: Upnp client error: Error: read ECONNRESET Aug 25 12:11:12 volumio volumio[1001]: info: mDNS: A device disapperared from network Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 25 12:11:14 volumio volumio[1001]: info: Adding plugin bluetooth to MyMusic Plugins Aug 25 12:11:14 volumio volumio[1001]: info: Adding plugin cd_controller to MyMusic Plugins Aug 25 12:11:14 volumio volumio[1001]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 25 12:11:14 volumio volumio[1001]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 25 12:11:14 volumio volumio[1001]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 25 12:11:15 volumio volumio[1001]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 25 12:11:15 volumio volumio[1001]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 25 12:11:15 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:11:15 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:11:15 volumio volumio[1001]: info: Starting MyVolumio Remote Streaming Endpoints Aug 25 12:11:15 volumio volumio[1001]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 25 12:11:15 volumio volumio[1001]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 25 12:11:15 volumio volumio[1001]: info: Streaming services startup Aug 25 12:11:15 volumio volumio[1001]: info: Starting Streaming Daemon Aug 25 12:11:15 volumio sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 25 12:11:15 volumio sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:11:15 volumio systemd[1]: Stopping Volumio Streaming Daemon... Aug 25 12:11:15 volumio volumio[1001]: info: MyVolumio not started Aug 25 12:11:15 volumio volumio[1001]: info: Initializing device activation check Aug 25 12:11:15 volumio volumio[1001]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 25 12:11:15 volumio systemd[1]: Starting Volumio Streaming Daemon... Aug 25 12:11:15 volumio sudo[1268]: pam_unix(sudo:session): session closed for user root Aug 25 12:11:15 volumio systemd[1]: Started Volumio Streaming Daemon. Aug 25 12:11:15 volumio volumio-streaming-daemon[1278]: ############################ Aug 25 12:11:15 volumio volumio-streaming-daemon[1278]: # Volumio Streaming Daemon # Aug 25 12:11:15 volumio volumio-streaming-daemon[1278]: # Running on port 7777 # Aug 25 12:11:15 volumio volumio-streaming-daemon[1278]: ############################ Aug 25 12:11:15 volumio volumio-streaming-daemon[1278]: Environment: production Aug 25 12:11:16 volumio volumio-remote-updater[550]: [2024-08-25 12:11:16] [connect] Successful connection Aug 25 12:11:16 volumio volumio-remote-updater[550]: [2024-08-25 12:11:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1724587876 101 Aug 25 12:11:16 volumio volumio[1001]: info: Connecting to daemon Aug 25 12:11:16 volumio volumio[1001]: error: SPOP command error: Error: connect ECONNREFUSED 127.0.0.1:6602 Aug 25 12:11:16 volumio volumio[1001]: error: SPOP status error: Error: connect ECONNREFUSED 127.0.0.1:6602 Aug 25 12:11:16 volumio volumio[1001]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Aug 25 12:11:16 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:11:16 volumio sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.11.32/NAS /mnt/NAS/Volumio Aug 25 12:11:16 volumio sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:11:16 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Aug 25 12:11:17 volumio volumio[1001]: info: New Spotify access token = BQBNJplhzUdYICsf1JKeC0Hz0ZM2D77NQypNEGYeE2uriyy3hB-xseikcdb2DQe4S6fXPMar3_GkE9m4nAMyuwFUTLp9OZC85tDrFqvrDo29kI-reSu8hMDABYWtkAmh_6Jjw0jZ82Be0AvIU2qfSpy8tRCJKuMPobj2T9UjwNsN7ibXnKF6bFKRWHhWK_jOQeFxzL_nZWfeIzrJaDyU5-hKxfBOU77vC23V Aug 25 12:11:17 volumio volumio[1001]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 25 12:11:17 volumio volumio[1001]: Discovery: adding 1d1da114-df90-4fdd-b3db-7ace96709810 Aug 25 12:11:17 volumio volumio[1001]: info: mDNS: Found device Volumio Aug 25 12:11:17 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Aug 25 12:11:17 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:11:17 volumio volumio-streaming-daemon[1278]: Environment: production Aug 25 12:11:17 volumio volumio[1001]: info: Fetching Streaming Services browse cache Aug 25 12:11:22 volumio sudo[1288]: pam_unix(sudo:session): session closed for user root Aug 25 12:11:22 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Aug 25 12:11:22 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Aug 25 12:11:22 volumio volumio[1001]: info: Cannot mount NAS Volumio at system boot, trial number 2 ,retrying in 5 seconds Aug 25 12:11:27 volumio sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.11.32/NAS /mnt/NAS/Volumio Aug 25 12:11:27 volumio sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:11:27 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Aug 25 12:11:33 volumio sudo[1302]: pam_unix(sudo:session): session closed for user root Aug 25 12:11:33 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Aug 25 12:11:33 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Aug 25 12:11:33 volumio volumio[1001]: info: Cannot mount NAS Volumio at system boot, trial number 3 ,retrying in 5 seconds Aug 25 12:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 12:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:11:38 volumio sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.11.32/NAS /mnt/NAS/Volumio Aug 25 12:11:38 volumio sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:11:38 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Aug 25 12:11:43 volumio volumio[1001]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 25 12:11:45 volumio sudo[1329]: pam_unix(sudo:session): session closed for user root Aug 25 12:11:45 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Aug 25 12:11:45 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Aug 25 12:11:45 volumio volumio[1001]: info: Cannot mount NAS Volumio at system boot, trial number 4 ,retrying in 5 seconds Aug 25 12:11:50 volumio sudo[1342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.11.32/NAS /mnt/NAS/Volumio Aug 25 12:11:50 volumio sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:11:50 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Aug 25 12:11:56 volumio sudo[1342]: pam_unix(sudo:session): session closed for user root Aug 25 12:11:56 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Aug 25 12:11:56 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Aug 25 12:11:56 volumio volumio[1001]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Aug 25 12:11:58 volumio volumio[1159]: [Vollibrespot] : couldn't parse packet from V4(192.168.11.9:5353): query type 65 is invalid Aug 25 12:11:59 volumio volumio[1159]: [Vollibrespot] : couldn't parse packet from V4(192.168.11.9:5353): query type 65 is invalid Aug 25 12:12:00 volumio sudo[1353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 12:12:00 volumio sudo[1353]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:12:00 volumio sudo[1353]: pam_unix(sudo:session): session closed for user root Aug 25 12:12:00 volumio sudo[1356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 12:12:00 volumio sudo[1356]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:12:00 volumio sudo[1356]: pam_unix(sudo:session): session closed for user root Aug 25 12:12:00 volumio volumio[1159]: [Vollibrespot] : couldn't parse packet from V4(192.168.11.9:5353): query type 65 is invalid Aug 25 12:12:01 volumio sudo[1371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 12:12:01 volumio sudo[1371]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:12:01 volumio sudo[1371]: pam_unix(sudo:session): session closed for user root Aug 25 12:12:01 volumio sudo[1374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 12:12:01 volumio sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:12:01 volumio sudo[1374]: pam_unix(sudo:session): session closed for user root Aug 25 12:12:01 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Aug 25 12:12:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:12:01 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 12:12:01 volumio volumio[1001]: info: Listing playlists Aug 25 12:12:01 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 25 12:12:01 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 25 12:12:01 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 25 12:12:01 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 12:12:01 volumio volumio[1159]: [Vollibrespot] : couldn't parse packet from V4(192.168.11.9:5353): query type 65 is invalid Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 25 12:12:07 volumio volumio[1001]: info: Setting Remote Device Volume: http://192.168.11.7 Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 25 12:12:07 volumio volumio[1001]: info: Setting Remote Device Volume: http://192.168.11.7 Aug 25 12:12:07 volumio volumio[1001]: info: Aug 25 12:12:07 volumio volumio[1001]: ---------------------------- Client requests Volume 43 Aug 25 12:12:07 volumio volumio[1001]: info: VolumeController::SetAlsaVolume43 Aug 25 12:12:07 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 25 12:12:07 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:12:07 volumio volumio[1001]: info: Done setting volume on: Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 25 12:12:07 volumio volumio[1001]: info: Setting Remote Device Volume: http://192.168.11.7 Aug 25 12:12:07 volumio volumio[1001]: info: Aug 25 12:12:07 volumio volumio[1001]: ---------------------------- Client requests Volume 39 Aug 25 12:12:07 volumio volumio[1001]: info: VolumeController::SetAlsaVolume39 Aug 25 12:12:07 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 25 12:12:07 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:12:07 volumio volumio[1001]: info: Done setting volume on: Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 25 12:12:07 volumio volumio[1001]: info: Setting Remote Device Volume: http://192.168.11.7 Aug 25 12:12:07 volumio volumio[1001]: info: Aug 25 12:12:07 volumio volumio[1001]: ---------------------------- Client requests Volume 36 Aug 25 12:12:07 volumio volumio[1001]: info: VolumeController::SetAlsaVolume36 Aug 25 12:12:07 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 25 12:12:07 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:12:07 volumio volumio[1001]: info: Done setting volume on: Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 25 12:12:07 volumio volumio[1001]: info: Setting Remote Device Volume: http://192.168.11.7 Aug 25 12:12:07 volumio volumio[1001]: info: Aug 25 12:12:07 volumio volumio[1001]: ---------------------------- Client requests Volume 33 Aug 25 12:12:07 volumio volumio[1001]: info: VolumeController::SetAlsaVolume33 Aug 25 12:12:07 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 25 12:12:07 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:12:07 volumio volumio[1001]: info: Done setting volume on: Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 25 12:12:07 volumio volumio[1001]: info: Setting Remote Device Volume: http://192.168.11.7 Aug 25 12:12:07 volumio volumio[1001]: info: Aug 25 12:12:07 volumio volumio[1001]: ---------------------------- Client requests Volume 31 Aug 25 12:12:07 volumio volumio[1001]: info: VolumeController::SetAlsaVolume31 Aug 25 12:12:07 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 25 12:12:07 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:12:07 volumio volumio[1001]: info: Done setting volume on: Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Aug 25 12:12:07 volumio volumio[1001]: info: Setting Remote Device Volume: http://192.168.11.7 Aug 25 12:12:07 volumio volumio[1001]: info: Aug 25 12:12:07 volumio volumio[1001]: ---------------------------- Client requests Volume 29 Aug 25 12:12:07 volumio volumio[1001]: info: VolumeController::SetAlsaVolume29 Aug 25 12:12:07 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 25 12:12:07 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:12:07 volumio volumio[1001]: info: Done setting volume on: Aug 25 12:12:07 volumio volumio[1001]: info: Aug 25 12:12:07 volumio volumio[1001]: ---------------------------- Client requests Volume 27 Aug 25 12:12:07 volumio volumio[1001]: info: VolumeController::SetAlsaVolume27 Aug 25 12:12:07 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 25 12:12:07 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 25 12:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:12:07 volumio volumio[1001]: info: Done setting volume on: Aug 25 12:12:09 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Aug 25 12:12:09 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:12:09 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 12:12:09 volumio volumio[1001]: info: Listing playlists Aug 25 12:12:18 volumio volumio[1001]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 25 12:12:18 volumio volumio[1159]: [Vollibrespot] : couldn't parse packet from V4(192.168.11.9:5353): query type 65 is invalid Aug 25 12:12:19 volumio sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 12:12:19 volumio sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:12:19 volumio sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 12:12:19 volumio sudo[1400]: pam_unix(sudo:session): session closed for user root Aug 25 12:12:19 volumio sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:12:19 volumio sudo[1402]: pam_unix(sudo:session): session closed for user root Aug 25 12:12:19 volumio sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 12:12:19 volumio sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:12:19 volumio sudo[1420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 12:12:19 volumio sudo[1418]: pam_unix(sudo:session): session closed for user root Aug 25 12:12:19 volumio sudo[1420]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:12:19 volumio sudo[1420]: pam_unix(sudo:session): session closed for user root Aug 25 12:12:19 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Aug 25 12:12:19 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:12:19 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 12:12:19 volumio volumio[1001]: info: Listing playlists Aug 25 12:12:19 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 25 12:12:19 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 25 12:12:19 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 25 12:12:19 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 12:12:19 volumio volumio[1159]: [Vollibrespot] : couldn't parse packet from V4(192.168.11.9:5353): query type 65 is invalid Aug 25 12:12:27 volumio volumio[1159]: [Vollibrespot] : couldn't parse packet from V4(192.168.11.9:5353): query type 65 is invalid Aug 25 12:12:28 volumio sudo[1440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 12:12:28 volumio sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:12:28 volumio sudo[1442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 12:12:28 volumio sudo[1440]: pam_unix(sudo:session): session closed for user root Aug 25 12:12:28 volumio sudo[1442]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:12:28 volumio sudo[1442]: pam_unix(sudo:session): session closed for user root Aug 25 12:12:28 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Aug 25 12:12:28 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 25 12:12:28 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 12:12:28 volumio volumio[1001]: info: Listing playlists Aug 25 12:12:28 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 25 12:12:28 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 25 12:12:28 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 25 12:12:28 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 12:12:28 volumio volumio[1159]: [Vollibrespot] : couldn't parse packet from V4(192.168.11.9:5353): query type 65 is invalid Aug 25 12:12:29 volumio volumio[1001]: info: CoreCommandRouter::volumioGetQueue Aug 25 12:12:29 volumio volumio[1001]: info: CoreStateMachine::getQueue Aug 25 12:12:29 volumio volumio[1001]: info: CorePlayQueue::getQueue Aug 25 12:12:33 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Aug 25 12:12:33 volumio volumio[1001]: UNSET VOLATILE Aug 25 12:12:33 volumio volumio[1001]: info: CoreStateMachine::play index 3 Aug 25 12:12:33 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 12:12:33 volumio volumio[1001]: info: CoreStateMachine::stop Aug 25 12:12:33 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 12:12:33 volumio volumio[1001]: UNSET VOLATILE Aug 25 12:12:33 volumio volumio[1001]: info: CoreStateMachine::play index undefined Aug 25 12:12:33 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 12:12:33 volumio volumio[1001]: info: CorePlayQueue::getTrack 3 Aug 25 12:12:33 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Aug 25 12:12:33 volumio volumio[1001]: info: CorePlayQueue::getTrack 3 Aug 25 12:12:33 volumio volumio[1001]: info: [1724587953148] ControllerSpop::clearAddPlayTrack Aug 25 12:12:33 volumio volumio[1001]: info: {"uri":"spotify:track:281OFBoVjNPkbgvqYjsQt0","service":"spop","name":"スプライト・フォー・ユー","artist":"Satoko Shibata","album":"愛の休日","type":"song","duration":154,"tracknumber":1,"albumart":"https://i.scdn.co/image/ab67616d0000b273b0369a7f81d64c7d29f26a5c","samplerate":"320Kbps","bitdepth":"16 bit","trackType":"spotify"} Aug 25 12:12:33 volumio volumio[1001]: info: [1724587953150] ControllerSpop::sendSpopCommand Aug 25 12:12:33 volumio volumio[1001]: info: ADDING DEFER FOR COMMAND uplay Aug 25 12:12:42 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Aug 25 12:12:42 volumio volumio[1001]: UNSET VOLATILE Aug 25 12:12:42 volumio volumio[1001]: info: CoreStateMachine::play index undefined Aug 25 12:12:42 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 12:12:42 volumio volumio[1001]: info: CorePlayQueue::getTrack 3 Aug 25 12:12:42 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Aug 25 12:12:42 volumio volumio[1001]: info: CorePlayQueue::getTrack 3 Aug 25 12:12:42 volumio volumio[1001]: info: [1724587962232] ControllerSpop::clearAddPlayTrack Aug 25 12:12:42 volumio volumio[1001]: info: {"uri":"spotify:track:281OFBoVjNPkbgvqYjsQt0","service":"spop","name":"スプライト・フォー・ユー","artist":"Satoko Shibata","album":"愛の休日","type":"song","duration":154,"tracknumber":1,"albumart":"https://i.scdn.co/image/ab67616d0000b273b0369a7f81d64c7d29f26a5c","samplerate":"320Kbps","bitdepth":"16 bit","trackType":"spotify"} Aug 25 12:12:42 volumio volumio[1001]: info: [1724587962233] ControllerSpop::sendSpopCommand Aug 25 12:12:42 volumio volumio[1001]: info: ADDING DEFER FOR COMMAND uplay Aug 25 12:12:46 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 12:12:46 volumio volumio[1001]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 25 12:12:46 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 25 12:12:46 volumio volumio[1001]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Aug 25 12:12:46 volumio volumio[1001]: info: Received Get System Version Aug 25 12:12:46 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 12:12:50 volumio volumio[1001]: info: VolumeController::SetAlsaVolume40 Aug 25 12:12:50 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 25 12:12:50 volumio volumio[1001]: info: CorePlayQueue::getTrack 3 Aug 25 12:12:50 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 25 12:12:50 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:12:50 volumio volumio[1001]: info: VolumeController::SetAlsaVolume35 Aug 25 12:12:50 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 25 12:12:50 volumio volumio[1001]: info: CorePlayQueue::getTrack 3 Aug 25 12:12:50 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 25 12:12:50 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:12:51 volumio volumio[1001]: info: VolumeController::SetAlsaVolume30 Aug 25 12:12:51 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 25 12:12:51 volumio volumio[1001]: info: CorePlayQueue::getTrack 3 Aug 25 12:12:51 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 25 12:12:51 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:12:54 volumio volumio[1159]: [Vollibrespot] : couldn't parse packet from V4(192.168.11.9:5353): query type 65 is invalid Aug 25 12:12:55 volumio volumio[1159]: [Vollibrespot] : couldn't parse packet from V4(192.168.11.9:5353): query type 65 is invalid Aug 25 12:12:55 volumio volumio[1001]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 12:12:55 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:12:55 volumio volumio[1001]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 25 12:12:56 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 12:12:58 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 25 12:12:58 volumio volumio[1001]: info: CURURI: albums:// Aug 25 12:12:58 volumio volumio[1001]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 12:12:58 volumio volumio[1001]: TypeError: Cannot read property 'sendCommand' of undefined Aug 25 12:12:58 volumio volumio[1001]: at /volumio/app/plugins/music_service/mpd/index.js:2938:22 Aug 25 12:12:58 volumio volumio[1001]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Aug 25 12:12:58 volumio volumio[1001]: at process._tickCallback (internal/process/next_tick.js:180:9) Aug 25 12:12:58 volumio volumio[1001]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 12:12:59 volumio sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-25 12:11 Aug 25 12:12:59 volumio sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="cd11dc738d0d82822d0aa776d0729686b82e3758" VOLUMIO_FE_VERSION="77db3ca2a7f5a7023ece8c5b86e997d58abdace6" VOLUMIO_BE_VERSION="748352701ebc6ec2b59443fcac1ec9aea1f276df" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Jul 2 12:45:33 CEST 2021" VOLUMIO_VERSION="2.907" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="858e764292794952fe9c14f4c1868f5a"