-- Logs begin at Sat 2020-07-04 15:36:14 UTC, end at Mon 2025-05-19 15:25:22 UTC. -- May 19 15:24:59 bahusmusic systemd[1]: Time has been changed May 19 15:24:59 bahusmusic volumio-remote-updater[618]: [2025-05-19 15:24:59] [info] asio async_connect error: system:111 (Connection refused) May 19 15:24:59 bahusmusic volumio-remote-updater[618]: [2025-05-19 15:24:59] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 19 15:24:59 bahusmusic volumio-remote-updater[618]: [2025-05-19 15:24:59] [error] handle_connect error: Underlying Transport Error May 19 15:25:00 bahusmusic wireless.js[805]: trying... May 19 15:25:00 bahusmusic sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 15:25:00 bahusmusic sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 15:25:00 bahusmusic sudo[1236]: pam_unix(sudo:session): session closed for user root May 19 15:25:01 bahusmusic wireless.js[805]: trying... May 19 15:25:01 bahusmusic sudo[1245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 15:25:01 bahusmusic sudo[1245]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 15:25:01 bahusmusic sudo[1245]: pam_unix(sudo:session): session closed for user root May 19 15:25:02 bahusmusic wireless.js[805]: Overtime, starting plan B May 19 15:25:02 bahusmusic systemd[1]: Started Wireless Services. May 19 15:25:02 bahusmusic systemd[1]: Starting Volumio Backend Module... May 19 15:25:02 bahusmusic systemd[1]: Started Volumio Backend Module. May 19 15:25:02 bahusmusic systemd[1]: Starting Volumio Streaming Daemon... May 19 15:25:02 bahusmusic systemd[1]: Started Volumio Streaming Daemon. May 19 15:25:02 bahusmusic systemd[1]: Starting Multi-User System. May 19 15:25:02 bahusmusic systemd[1]: Reached target Multi-User System. May 19 15:25:02 bahusmusic systemd[1]: Starting Graphical Interface. May 19 15:25:02 bahusmusic systemd[1]: Reached target Graphical Interface. May 19 15:25:02 bahusmusic systemd[1]: Starting Update UTMP about System Runlevel Changes... May 19 15:25:02 bahusmusic systemd[1]: Started Update UTMP about System Runlevel Changes. May 19 15:25:02 bahusmusic systemd[1]: Startup finished in 9.218s (kernel) + 33.949s (userspace) = 43.167s. May 19 15:25:02 bahusmusic volumio-streaming-daemon[1255]: ############################ May 19 15:25:02 bahusmusic volumio-streaming-daemon[1255]: # Volumio Streaming Daemon # May 19 15:25:02 bahusmusic volumio-streaming-daemon[1255]: # Running on port 7777 # May 19 15:25:02 bahusmusic volumio-streaming-daemon[1255]: ############################ May 19 15:25:02 bahusmusic volumio-streaming-daemon[1255]: Environment: production May 19 15:25:04 bahusmusic volumio[1254]: info: ------------------------------------------- May 19 15:25:04 bahusmusic volumio[1254]: info: ----- Volumio2 ---- May 19 15:25:04 bahusmusic volumio[1254]: info: ------------------------------------------- May 19 15:25:04 bahusmusic volumio[1254]: info: ----- System startup ---- May 19 15:25:04 bahusmusic volumio[1254]: info: ------------------------------------------- May 19 15:25:04 bahusmusic volumio[1254]: info: MYVOLUMIO Environment detected May 19 15:25:05 bahusmusic volumio[1254]: info: Plugin folders cleanup May 19 15:25:05 bahusmusic volumio[1254]: info: Scanning into folder /volumio/app/plugins/ May 19 15:25:05 bahusmusic volumio[1254]: info: Scanning category audio_interface May 19 15:25:05 bahusmusic volumio[1254]: info: Scanning category miscellanea May 19 15:25:05 bahusmusic volumio[1254]: info: Scanning category music_service May 19 15:25:05 bahusmusic volumio[1254]: info: Scanning category plugins.json May 19 15:25:05 bahusmusic volumio[1254]: info: Scanning category system_controller May 19 15:25:05 bahusmusic volumio[1254]: info: Scanning category user_interface May 19 15:25:05 bahusmusic volumio[1254]: info: Scanning into folder /data/plugins/ May 19 15:25:05 bahusmusic volumio[1254]: info: Scanning category audio_interface May 19 15:25:05 bahusmusic volumio[1254]: info: Scanning category miscellanea May 19 15:25:05 bahusmusic volumio[1254]: info: Scanning category music_service May 19 15:25:05 bahusmusic volumio[1254]: info: Plugin folders cleanup completed May 19 15:25:05 bahusmusic volumio[1254]: info: ------------------------------------------- May 19 15:25:05 bahusmusic volumio[1254]: info: ----- Core plugins startup ---- May 19 15:25:05 bahusmusic volumio[1254]: info: ------------------------------------------- May 19 15:25:05 bahusmusic volumio[1254]: info: Loading plugins from folder /volumio/app/plugins/ May 19 15:25:05 bahusmusic volumio[1254]: info: Adding plugin upnp to MyMusic Plugins May 19 15:25:05 bahusmusic volumio[1254]: info: Adding plugin airplay_emulation to MyMusic Plugins May 19 15:25:05 bahusmusic volumio[1254]: info: Adding plugin upnp_browser to MyMusic Plugins May 19 15:25:05 bahusmusic volumio[1254]: info: Loading plugins from folder /data/plugins/ May 19 15:25:05 bahusmusic volumio[1254]: info: Loading plugin "system"... May 19 15:25:05 bahusmusic volumio[1254]: info: Loading plugin "appearance"... May 19 15:25:06 bahusmusic wpa_supplicant[943]: wlan0: Trying to associate with SSID 'BAHUS' May 19 15:25:06 bahusmusic volumio[1254]: info: Loading plugin "network"... May 19 15:25:06 bahusmusic volumio[1254]: info: Loading plugin "services"... May 19 15:25:06 bahusmusic volumio[1254]: info: Loading plugin "alsa_controller"... May 19 15:25:06 bahusmusic volumio[1254]: info: Loading plugin "wizard"... May 19 15:25:06 bahusmusic volumio[1254]: info: Loading plugin "volumio_command_line_client"... May 19 15:25:06 bahusmusic volumio[1254]: info: Loading plugin "upnp"... May 19 15:25:06 bahusmusic volumio[1254]: info: [1747668306525] Starting Upmpd Daemon May 19 15:25:06 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 19 15:25:06 bahusmusic volumio[1254]: info: Loading plugin "my_music"... May 19 15:25:06 bahusmusic volumio[1254]: info: Loading plugin "mpd"... May 19 15:25:06 bahusmusic volumio[1254]: info: Loading plugin "upnp_browser"... May 19 15:25:07 bahusmusic volumio[1254]: info: Loading plugin "networkfs"... May 19 15:25:07 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 19 15:25:07 bahusmusic volumio[1254]: info: Loading plugin "alarm-clock"... May 19 15:25:07 bahusmusic volumio[1254]: info: Loading plugin "airplay_emulation"... May 19 15:25:07 bahusmusic volumio[1254]: info: Starting Shairport Sync May 19 15:25:07 bahusmusic volumio[1254]: info: Loading plugin "last_100"... May 19 15:25:07 bahusmusic volumio[1254]: info: Loading plugin "webradio"... May 19 15:25:07 bahusmusic volumio[1254]: info: Loading plugin "i2s_dacs"... May 19 15:25:07 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 15:25:07 bahusmusic volumio[1254]: info: Loading plugin "volumiodiscovery"... May 19 15:25:07 bahusmusic volumio[1254]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 19 15:25:07 bahusmusic node[1254]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 19 15:25:07 bahusmusic volumio[1254]: *** WARNING *** Please fix your application to use the native API of Avahi! May 19 15:25:07 bahusmusic node[1254]: *** WARNING *** Please fix your application to use the native API of Avahi! May 19 15:25:07 bahusmusic volumio[1254]: *** WARNING *** For more information see May 19 15:25:07 bahusmusic node[1254]: *** WARNING *** For more information see May 19 15:25:07 bahusmusic volumio[1254]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 19 15:25:07 bahusmusic node[1254]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 19 15:25:07 bahusmusic volumio[1254]: *** WARNING *** Please fix your application to use the native API of Avahi! May 19 15:25:07 bahusmusic node[1254]: *** WARNING *** Please fix your application to use the native API of Avahi! May 19 15:25:07 bahusmusic volumio[1254]: *** WARNING *** For more information see May 19 15:25:07 bahusmusic node[1254]: *** WARNING *** For more information see May 19 15:25:07 bahusmusic volumio[1254]: info: Applying required configuration parameters for plugin volumiodiscovery May 19 15:25:07 bahusmusic volumio[1254]: Discovery: StartAdv! undefined May 19 15:25:07 bahusmusic volumio[1254]: Discovery: Started advertising... bahusmusic - undefined May 19 15:25:07 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 19 15:25:07 bahusmusic volumio[1254]: info: Plugin volparametriceq is not enabled May 19 15:25:07 bahusmusic volumio[1254]: info: Loading plugin "auto_play"... May 19 15:25:07 bahusmusic volumio[1254]: info: ControllerAutoPlay - connecting mpd on host: localhost; port: 6600 May 19 15:25:07 bahusmusic volumio[1254]: info: Loading plugin "outputs"... May 19 15:25:07 bahusmusic volumio[1254]: info: Loading plugin "albumart"... May 19 15:25:07 bahusmusic volumio[1254]: info: Plugin example_plugin is not enabled May 19 15:25:07 bahusmusic volumio[1254]: info: Loading plugin "inputs"... May 19 15:25:07 bahusmusic volumio[1254]: info: Loading plugin "updater_comm"... May 19 15:25:08 bahusmusic volumio[1254]: info: Plugin mpdemulation is not enabled May 19 15:25:08 bahusmusic volumio[1254]: info: Loading plugin "rest_api"... May 19 15:25:08 bahusmusic volumio[1254]: info: Loading plugin "websocket"... May 19 15:25:08 bahusmusic volumio[1254]: info: Loading plugin "80s80s"... May 19 15:25:08 bahusmusic volumio[1254]: Forking 3 albumart workers May 19 15:25:08 bahusmusic volumio[1254]: Starting albumart workers May 19 15:25:08 bahusmusic volumio[1254]: Starting albumart workers May 19 15:25:08 bahusmusic volumio[1254]: Starting albumart workers May 19 15:25:08 bahusmusic volumio[1254]: info: Applying required configuration parameters for plugin 80s80s May 19 15:25:08 bahusmusic volumio[1254]: info: [1747668308836] [80s80s] API delay: 30 May 19 15:25:08 bahusmusic volumio[1254]: info: ___________ START PLUGINS ___________ May 19 15:25:08 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 19 15:25:08 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 19 15:25:08 bahusmusic volumio[1254]: info: [1747668308846] CoreMusicLibrary::Adding element Media Servers May 19 15:25:08 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 15:25:08 bahusmusic volumio[1254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 19 15:25:08 bahusmusic volumio[1254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 19 15:25:08 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 19 15:25:08 bahusmusic volumio[1254]: info: [1747668308959] CoreMusicLibrary::Adding element Last_100 May 19 15:25:08 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 15:25:08 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 19 15:25:08 bahusmusic volumio[1254]: info: [1747668308961] CoreMusicLibrary::Adding element Webradio May 19 15:25:08 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 15:25:08 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 19 15:25:08 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 19 15:25:08 bahusmusic volumio[1254]: info: [1747668308983] CoreMusicLibrary::Adding element 80s80s Radio May 19 15:25:08 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 15:25:08 bahusmusic volumio[1254]: info: ------------------------------------------- May 19 15:25:08 bahusmusic volumio[1254]: info: ----- MyVolumio plugins startup ---- May 19 15:25:08 bahusmusic volumio[1254]: info: ------------------------------------------- May 19 15:25:08 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Fetching plans data.... May 19 15:25:08 bahusmusic volumio[1254]: info: Loading i18n strings for locale en May 19 15:25:08 bahusmusic volumio[1254]: Updating browse sources language May 19 15:25:08 bahusmusic volumio[1254]: Cannot find translation for sourceMedia Servers May 19 15:25:08 bahusmusic volumio[1254]: Cannot find translation for source80s80s Radio May 19 15:25:09 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 15:25:09 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 19 15:25:09 bahusmusic volumio[1254]: info: CoreCommandRouter::initPlayerControls May 19 15:25:09 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 15:25:09 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards May 19 15:25:09 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 15:25:09 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 15:25:09 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 15:25:09 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 15:25:09 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 15:25:09 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 19 15:25:09 bahusmusic volumio[1254]: info: BOOT COMPLETED May 19 15:25:09 bahusmusic volumio[1254]: [Metrics] CommandRouter: 4s 547.53ms May 19 15:25:09 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 15:25:09 bahusmusic kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 19 15:25:09 bahusmusic wpa_supplicant[943]: wlan0: Associated with 78:9a:18:cc:b3:5b May 19 15:25:09 bahusmusic wpa_supplicant[943]: wlan0: WPA: Key negotiation completed with 78:9a:18:cc:b3:5b [PTK=CCMP GTK=CCMP] May 19 15:25:09 bahusmusic wpa_supplicant[943]: wlan0: CTRL-EVENT-CONNECTED - Connection to 78:9a:18:cc:b3:5b completed [id=0 id_str=] May 19 15:25:09 bahusmusic dhcpcd[1059]: wlan0: carrier acquired May 19 15:25:09 bahusmusic dhcpcd[1059]: wlan0: rebinding lease of 192.168.76.202 May 19 15:25:09 bahusmusic dhcpcd[1059]: wlan0: leased 192.168.76.202 for 600 seconds May 19 15:25:09 bahusmusic avahi-daemon[680]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.76.202. May 19 15:25:09 bahusmusic dhcpcd[1059]: eth0: adding route to 192.168.1.0/24 May 19 15:25:09 bahusmusic dhcpcd[1059]: eth0: adding default route via 192.168.1.1 May 19 15:25:09 bahusmusic dhcpcd[1059]: wlan0: adding host route to 192.168.76.202 via 127.0.0.1 May 19 15:25:09 bahusmusic dhcpcd[1059]: wlan0: adding route to 192.168.76.0/22 May 19 15:25:09 bahusmusic dhcpcd[1059]: wlan0: adding default route via 192.168.76.1 May 19 15:25:09 bahusmusic avahi-daemon[680]: New relevant interface wlan0.IPv4 for mDNS. May 19 15:25:09 bahusmusic avahi-daemon[680]: Registering new address record for 192.168.76.202 on wlan0.IPv4. May 19 15:25:09 bahusmusic dhcpcd[1059]: eth0: removing route to 192.168.1.0/24 May 19 15:25:09 bahusmusic dhcpcd[1059]: eth0: removing default route via 192.168.1.1 May 19 15:25:09 bahusmusic volumio-remote-updater[618]: [2025-05-19 15:25:09] [connect] Successful connection May 19 15:25:11 bahusmusic ntpd[745]: Listen normally on 4 wlan0 192.168.76.202 UDP 123 May 19 15:25:11 bahusmusic ntpd[745]: peers refreshed May 19 15:25:14 bahusmusic volumio[1254]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::Close All Modals sent May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::Close All Modals sent May 19 15:25:14 bahusmusic volumio[1254]: Express server listening on port 3000 May 19 15:25:14 bahusmusic volumio[1254]: [Metrics] WebUI: 10s 219.85ms May 19 15:25:14 bahusmusic volumio[1254]: Volumio Calling Home May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::resetVolumioState May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::getcurrentVolume May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioRetrievevolume May 19 15:25:14 bahusmusic volumio[1254]: info: Setting Device type: Raspberry PI May 19 15:25:14 bahusmusic volumio[1254]: info: MPD running with PID685 ,establishing connection May 19 15:25:14 bahusmusic volumio-remote-updater[618]: [2025-05-19 15:25:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1747668309 101 May 19 15:25:14 bahusmusic volumio[1254]: info: May 19 15:25:14 bahusmusic volumio[1254]: ---------------------------- Client requests Volume 30 May 19 15:25:14 bahusmusic volumio[1254]: info: VolumeController::SetAlsaVolume30 May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::pushState May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::getState May 19 15:25:14 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 0 May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioPushState May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 15:25:14 bahusmusic volumio[1254]: info: VolumeController:: Volume=100 Mute =false May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::pushState May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::getState May 19 15:25:14 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 0 May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioPushState May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::updateTrackBlock May 19 15:25:14 bahusmusic volumio[1254]: info: CorePlayQueue::getTrackBlock May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioRetrievevolume May 19 15:25:14 bahusmusic volumio[1254]: info: Reloading queue from file May 19 15:25:14 bahusmusic volumio[1254]: info: May 19 15:25:14 bahusmusic volumio[1254]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory May 19 15:25:14 bahusmusic volumio[1254]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) May 19 15:25:14 bahusmusic volumio[1254]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) May 19 15:25:14 bahusmusic volumio[1254]: at emitOne (events.js:116:13) May 19 15:25:14 bahusmusic volumio[1254]: at Socket.emit (events.js:211:7) May 19 15:25:14 bahusmusic volumio[1254]: at addChunk (_stream_readable.js:263:12) May 19 15:25:14 bahusmusic volumio[1254]: at readableAddChunk (_stream_readable.js:246:13) May 19 15:25:14 bahusmusic volumio[1254]: at Socket.Readable.push (_stream_readable.js:208:10) May 19 15:25:14 bahusmusic volumio[1254]: at Pipe.onread (net.js:607:20) May 19 15:25:14 bahusmusic volumio[1254]: info: Setting volume on startup at 30 May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::setRepeat true single undefined May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::pushState May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::getState May 19 15:25:14 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 0 May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioPushState May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::setRandom true May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::pushState May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::getState May 19 15:25:14 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 0 May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioPushState May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 15:25:14 bahusmusic volumio[1254]: info: Starting Shairport Sync May 19 15:25:14 bahusmusic volumio[1254]: info: Starting Shairport Sync May 19 15:25:14 bahusmusic volumio[1254]: Discovery: adding a762100e-bc28-4384-965d-876f831acd4b May 19 15:25:14 bahusmusic volumio[1254]: info: mDNS: Found device bahusmusic May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioGetState May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::getState May 19 15:25:14 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 0 May 19 15:25:14 bahusmusic sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 19 15:25:14 bahusmusic sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 19 15:25:14 bahusmusic sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 15:25:14 bahusmusic sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 15:25:14 bahusmusic sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 15:25:14 bahusmusic systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 19 15:25:14 bahusmusic sudo[1411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 15:25:14 bahusmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 19 15:25:14 bahusmusic sudo[1399]: pam_unix(sudo:session): session closed for user root May 19 15:25:14 bahusmusic sudo[1397]: pam_unix(sudo:session): session closed for user root May 19 15:25:14 bahusmusic volumio[1254]: info: VolumeController:: Volume=30 Mute =false May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::pushState May 19 15:25:14 bahusmusic sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::getState May 19 15:25:14 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 0 May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioPushState May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 15:25:14 bahusmusic sudo[1411]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 15:25:14 bahusmusic sudo[1406]: pam_unix(sudo:session): session closed for user root May 19 15:25:14 bahusmusic sudo[1411]: pam_unix(sudo:session): session closed for user root May 19 15:25:14 bahusmusic sudo[1429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 15:25:14 bahusmusic sudo[1429]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 15:25:14 bahusmusic sudo[1429]: pam_unix(sudo:session): session closed for user root May 19 15:25:14 bahusmusic volumio[1254]: info: Shairport-Sync Started May 19 15:25:14 bahusmusic volumio[1254]: Error adding Membership: Error: addMembership EINVAL May 19 15:25:14 bahusmusic volumio[1254]: info: Shairport-Sync Started May 19 15:25:14 bahusmusic volumio[1254]: Discovery: this is already registered, a762100e-bc28-4384-965d-876f831acd4b May 19 15:25:14 bahusmusic volumio[1254]: info: mDNS: Found device bahusmusic May 19 15:25:14 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioGetState May 19 15:25:14 bahusmusic volumio[1254]: info: CoreStateMachine::getState May 19 15:25:14 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 0 May 19 15:25:15 bahusmusic sudo[1453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 15:25:15 bahusmusic sudo[1453]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 15:25:15 bahusmusic sudo[1456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 15:25:15 bahusmusic sudo[1456]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 15:25:15 bahusmusic sudo[1453]: pam_unix(sudo:session): session closed for user root May 19 15:25:15 bahusmusic sudo[1456]: pam_unix(sudo:session): session closed for user root May 19 15:25:15 bahusmusic volumio[1254]: info: ControllerAutoPlay - mpd ready May 19 15:25:15 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioGetState May 19 15:25:15 bahusmusic volumio[1254]: info: CoreStateMachine::getState May 19 15:25:15 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 0 May 19 15:25:15 bahusmusic sudo[1471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 15:25:15 bahusmusic sudo[1471]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 15:25:15 bahusmusic sudo[1471]: pam_unix(sudo:session): session closed for user root May 19 15:25:15 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 19 15:25:15 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 19 15:25:16 bahusmusic sudo[1482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 15:25:16 bahusmusic sudo[1482]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 15:25:16 bahusmusic sudo[1482]: pam_unix(sudo:session): session closed for user root May 19 15:25:16 bahusmusic sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 19 15:25:16 bahusmusic sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 15:25:16 bahusmusic systemd[1]: Starting UPnP Renderer front-end to MPD... May 19 15:25:16 bahusmusic systemd[1]: Started UPnP Renderer front-end to MPD. May 19 15:25:16 bahusmusic sudo[1485]: pam_unix(sudo:session): session closed for user root May 19 15:25:16 bahusmusic volumio[1254]: info: Upmpdcli Daemon Started May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 19 15:25:17 bahusmusic volumio[1254]: info: Adding plugin bluetooth to MyMusic Plugins May 19 15:25:17 bahusmusic volumio[1254]: info: Adding plugin cd_controller to MyMusic Plugins May 19 15:25:17 bahusmusic volumio[1254]: info: Adding plugin smart_inputs to MyMusic Plugins May 19 15:25:17 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 19 15:25:19 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 19 15:25:20 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 19 15:25:20 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 19 15:25:20 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 15:25:20 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 15:25:20 bahusmusic volumio[1254]: info: Starting MyVolumio Remote Streaming Endpoints May 19 15:25:20 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 19 15:25:20 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 19 15:25:20 bahusmusic volumio[1254]: info: Streaming services startup May 19 15:25:20 bahusmusic volumio[1254]: info: Starting Streaming Daemon May 19 15:25:20 bahusmusic volumio[1254]: info: MyVolumio not started May 19 15:25:20 bahusmusic volumio[1254]: info: Initializing device activation check May 19 15:25:20 bahusmusic sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 19 15:25:20 bahusmusic sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 15:25:20 bahusmusic volumio[1254]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 19 15:25:20 bahusmusic systemd[1]: Stopping Volumio Streaming Daemon... May 19 15:25:20 bahusmusic systemd[1]: Starting Volumio Streaming Daemon... May 19 15:25:20 bahusmusic systemd[1]: Started Volumio Streaming Daemon. May 19 15:25:20 bahusmusic sudo[1519]: pam_unix(sudo:session): session closed for user root May 19 15:25:20 bahusmusic volumio[1254]: info: ControllerAutoPlay - getting queue May 19 15:25:20 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioGetQueue May 19 15:25:20 bahusmusic volumio[1254]: info: CoreStateMachine::getQueue May 19 15:25:20 bahusmusic volumio[1254]: info: CorePlayQueue::getQueue May 19 15:25:20 bahusmusic volumio[1254]: info: ControllerAutoPlay - start playing -> queue is not empty May 19 15:25:20 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioPlay May 19 15:25:20 bahusmusic volumio[1254]: UNSET VOLATILE May 19 15:25:20 bahusmusic volumio[1254]: info: CoreStateMachine::play index undefined May 19 15:25:20 bahusmusic volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined May 19 15:25:20 bahusmusic volumio-streaming-daemon[1525]: ############################ May 19 15:25:20 bahusmusic volumio-streaming-daemon[1525]: # Volumio Streaming Daemon # May 19 15:25:20 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 0 May 19 15:25:20 bahusmusic volumio[1254]: info: CoreStateMachine::startPlaybackTimer May 19 15:25:20 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 0 May 19 15:25:20 bahusmusic volumio[1254]: info: [1747668320269] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/mj/mp3-192/volumio May 19 15:25:20 bahusmusic volumio[1254]: info: [1747668320270] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=156&count=2 May 19 15:25:20 bahusmusic volumio-streaming-daemon[1525]: # Running on port 7777 # May 19 15:25:20 bahusmusic volumio-streaming-daemon[1525]: ############################ May 19 15:25:20 bahusmusic volumio-streaming-daemon[1525]: Environment: production May 19 15:25:20 bahusmusic volumio[1254]: info: [1747668320703] [80s80s] received new event containing 2 songs. May 19 15:25:20 bahusmusic volumio[1254]: info: ControllerMpd::sendMpdCommand stop May 19 15:25:20 bahusmusic volumio[1254]: info: sending command... May 19 15:25:20 bahusmusic volumio[1254]: info: parsing response... May 19 15:25:20 bahusmusic volumio[1254]: info: ControllerMpd::sendMpdCommand clear May 19 15:25:20 bahusmusic volumio[1254]: info: sending command... May 19 15:25:20 bahusmusic volumio[1254]: info: May 19 15:25:20 bahusmusic volumio[1254]: ---------------------------- MPD announces system playlist update May 19 15:25:20 bahusmusic volumio[1254]: info: Ignoring MPD Status Update May 19 15:25:20 bahusmusic volumio[1254]: info: parsing response... May 19 15:25:20 bahusmusic volumio[1254]: info: [1747668320713] [80s80s] adding url: http://streams.80s80s.de/mj/mp3-192/volumio May 19 15:25:20 bahusmusic volumio[1254]: info: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/mj/mp3-192/volumio" May 19 15:25:20 bahusmusic volumio[1254]: info: sending command... May 19 15:25:20 bahusmusic volumio[1254]: info: May 19 15:25:20 bahusmusic volumio[1254]: info: May 19 15:25:20 bahusmusic volumio[1254]: ---------------------------- MPD announces system playlist update May 19 15:25:20 bahusmusic volumio[1254]: info: Ignoring MPD Status Update May 19 15:25:20 bahusmusic volumio[1254]: info: ------------------------------ 6ms May 19 15:25:20 bahusmusic volumio[1254]: info: parsing response... May 19 15:25:20 bahusmusic volumio[1254]: info: ControllerMpd::sendMpdCommand play May 19 15:25:20 bahusmusic volumio[1254]: info: sending command... May 19 15:25:20 bahusmusic volumio[1254]: info: ------------------------------ 7ms May 19 15:25:20 bahusmusic volumio[1254]: info: parsing response... May 19 15:25:20 bahusmusic volumio[1254]: info: [1747668320724] [80s80s] Pushing the next song state: Michael Jackson - Slave to the Rhythm May 19 15:25:20 bahusmusic volumio[1254]: info: CoreStateMachine::getState May 19 15:25:20 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 0 May 19 15:25:20 bahusmusic volumio[1254]: info: CoreCommandRouter::servicePushState May 19 15:25:20 bahusmusic volumio[1254]: info: CoreStateMachine::syncState May 19 15:25:20 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 0 May 19 15:25:20 bahusmusic volumio[1254]: info: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music115/v4/b4/59/c9/b459c9f4-16b4-0b8b-94e1-275b79634776/source/600x600bb.jpg","name":"Michael Jackson - Slave to the Rhythm","title":"Slave to the Rhythm","artist":"80s80s Michael Jackson","album":"","streaming":true,"disableUiControls":true,"duration":"263","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 19 15:25:20 bahusmusic volumio[1254]: info: CURRENT POSITION 0 May 19 15:25:20 bahusmusic volumio[1254]: info: CoreStateMachine::syncState stateService play May 19 15:25:20 bahusmusic volumio[1254]: info: CoreStateMachine::syncState currentStatus stop May 19 15:25:20 bahusmusic volumio[1254]: info: CoreStateMachine::pushState May 19 15:25:20 bahusmusic volumio[1254]: info: CoreStateMachine::getState May 19 15:25:20 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 0 May 19 15:25:20 bahusmusic volumio[1254]: info: CoreCommandRouter::volumioPushState May 19 15:25:20 bahusmusic volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 15:25:20 bahusmusic volumio[1254]: info: [1747668320747] [80s80s] getContentOfUrl started with url http://ntp-a4.nict.go.jp/cgi-bin/json May 19 15:25:20 bahusmusic sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 15:25:20 bahusmusic sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 15:25:20 bahusmusic sudo[1538]: pam_unix(sudo:session): session closed for user root May 19 15:25:20 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 0 May 19 15:25:20 bahusmusic volumio[1254]: info: CorePlayQueue::getTrack 18 May 19 15:25:21 bahusmusic volumio[1254]: info: [1747668321910] [80s80s] Failed to query api, status code: 404 May 19 15:25:21 bahusmusic volumio[1254]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 15:25:21 bahusmusic volumio[1254]: ReferenceError: station is not defined May 19 15:25:21 bahusmusic volumio[1254]: at ClientRequest.http.get (/data/plugins/music_service/80s80s/index.js:478:26) May 19 15:25:21 bahusmusic volumio[1254]: at Object.onceWrapper (events.js:315:30) May 19 15:25:21 bahusmusic volumio[1254]: at emitOne (events.js:116:13) May 19 15:25:21 bahusmusic volumio[1254]: at ClientRequest.emit (events.js:211:7) May 19 15:25:21 bahusmusic volumio[1254]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:551:21) May 19 15:25:21 bahusmusic volumio[1254]: at HTTPParser.parserOnHeadersComplete (_http_common.js:115:23) May 19 15:25:21 bahusmusic volumio[1254]: at Socket.socketOnData (_http_client.js:440:20) May 19 15:25:21 bahusmusic volumio[1254]: at emitOne (events.js:116:13) May 19 15:25:21 bahusmusic volumio[1254]: at Socket.emit (events.js:211:7) May 19 15:25:21 bahusmusic volumio[1254]: at addChunk (_stream_readable.js:263:12) May 19 15:25:21 bahusmusic volumio[1254]: at readableAddChunk (_stream_readable.js:250:11) May 19 15:25:21 bahusmusic volumio[1254]: at Socket.Readable.push (_stream_readable.js:208:10) May 19 15:25:21 bahusmusic volumio[1254]: at TCP.onread (net.js:607:20) May 19 15:25:21 bahusmusic volumio[1254]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 15:25:22 bahusmusic kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 19 15:25:22 bahusmusic sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-19 15:24 May 19 15:25:22 bahusmusic sudo[1557]: 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="ba22215fabbe766d668749499a1c25fcefd90f0a" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="043dcaee624f27d121213b83af11a9f2feac0945" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Mar 18 01:32:43 CET 2020" VOLUMIO_VERSION="2.729" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="5a0b29326faa8f5d0f5fe95d9e58c069"