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