-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-05-24 23:25:03 CEST. -- May 24 23:24:00 ghettoblaster volumio[2739]: info: Output device has changed, restarting Shairport Sync May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:00 ghettoblaster sudo[2834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 23:24:00 ghettoblaster sudo[2834]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:00 ghettoblaster sudo[2834]: pam_unix(sudo:session): session closed for user root May 24 23:24:00 ghettoblaster sudo[2836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 23:24:00 ghettoblaster sudo[2836]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:00 ghettoblaster systemd[1]: Stopping Music Player Daemon... May 24 23:24:00 ghettoblaster volumio[2739]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:00 ghettoblaster volumio[2739]: info: ___________ START PLUGINS ___________ May 24 23:24:00 ghettoblaster volumio[2739]: info: ControllerMpd::onStart: Initializing MPD May 24 23:24:00 ghettoblaster volumio[2739]: info: Creating MPD Configuration file May 24 23:24:00 ghettoblaster systemd[1]: mpd.service: Succeeded. May 24 23:24:00 ghettoblaster systemd[1]: Stopped Music Player Daemon. May 24 23:24:00 ghettoblaster sudo[2844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:00 ghettoblaster volumio[2739]: info: [1748121840111] CoreMusicLibrary::Adding element Media Servers May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:00 ghettoblaster systemd[1]: Starting Music Player Daemon... May 24 23:24:00 ghettoblaster sudo[2844]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:00 ghettoblaster sudo[2844]: pam_unix(sudo:session): session closed for user root May 24 23:24:00 ghettoblaster sudo[2847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 23:24:00 ghettoblaster sudo[2847]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:00 ghettoblaster systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 24 23:24:00 ghettoblaster systemd[1]: mpd.service: Succeeded. May 24 23:24:00 ghettoblaster systemd[1]: Stopped Music Player Daemon. May 24 23:24:00 ghettoblaster systemd[1]: Starting Music Player Daemon... May 24 23:24:00 ghettoblaster volumio[2739]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:00 ghettoblaster sudo[2851]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 23:24:00 ghettoblaster sudo[2851]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:00 ghettoblaster sudo[2851]: pam_unix(sudo:session): session closed for user root May 24 23:24:00 ghettoblaster volumio[2739]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:00 ghettoblaster volumio[2739]: info: [1748121840289] CoreMusicLibrary::Adding element Last_100 May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:00 ghettoblaster volumio[2739]: info: [1748121840291] CoreMusicLibrary::Adding element Webradio May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:00 ghettoblaster volumio[2739]: info: Initializing BBC Radios May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: Loading i18n strings for locale en May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:00 ghettoblaster volumio[2739]: info: Volumio Calling Home May 24 23:24:00 ghettoblaster sudo[2896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service May 24 23:24:00 ghettoblaster sudo[2896]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:00 ghettoblaster sudo[2898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service May 24 23:24:00 ghettoblaster sudo[2898]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:00 ghettoblaster sudo[2902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 24 23:24:00 ghettoblaster sudo[2902]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:00 ghettoblaster systemd[1]: Reloading. May 24 23:24:00 ghettoblaster sudo[2896]: pam_unix(sudo:session): session closed for user root May 24 23:24:00 ghettoblaster volumio[2739]: Unhandled rejection Error: No sockets available, cannot start. May 24 23:24:00 ghettoblaster volumio[2739]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) May 24 23:24:00 ghettoblaster volumio[2739]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) May 24 23:24:00 ghettoblaster volumio[2739]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 May 24 23:24:00 ghettoblaster volumio[2739]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) May 24 23:24:00 ghettoblaster volumio[2739]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) May 24 23:24:00 ghettoblaster volumio[2739]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) May 24 23:24:00 ghettoblaster volumio[2739]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) May 24 23:24:00 ghettoblaster volumio[2739]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) May 24 23:24:00 ghettoblaster volumio[2739]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) May 24 23:24:00 ghettoblaster volumio[2739]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) May 24 23:24:00 ghettoblaster volumio[2739]: at HashMap. (/volumio/app/pluginmanager.js:496:31) May 24 23:24:00 ghettoblaster volumio[2739]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 24 23:24:00 ghettoblaster volumio[2739]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 24 23:24:00 ghettoblaster volumio[2739]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) May 24 23:24:00 ghettoblaster volumio[2739]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) May 24 23:24:00 ghettoblaster volumio[2739]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 24 23:24:00 ghettoblaster volumio[2739]: info: touch_display: No backlight interface detected. May 24 23:24:00 ghettoblaster volumio[2739]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 24 23:24:00 ghettoblaster volumio[2739]: info: MPD Permissions set May 24 23:24:00 ghettoblaster volumio[2739]: info: MPD Permissions set May 24 23:24:00 ghettoblaster volumio[2739]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:00 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:00] [connect] Successful connection May 24 23:24:00 ghettoblaster volumio[2739]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 24 23:24:00 ghettoblaster sudo[2927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 24 23:24:00 ghettoblaster sudo[2927]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:00 ghettoblaster sudo[2927]: pam_unix(sudo:session): session closed for user root May 24 23:24:00 ghettoblaster volumio[2739]: info: Starting Shairport Sync May 24 23:24:00 ghettoblaster sudo[2929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf May 24 23:24:00 ghettoblaster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 23:24:00 ghettoblaster sudo[2929]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:00 ghettoblaster sudo[2929]: pam_unix(sudo:session): session closed for user root May 24 23:24:00 ghettoblaster volumio[2739]: info: Starting Shairport Sync May 24 23:24:00 ghettoblaster sudo[2933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:00 ghettoblaster sudo[2933]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:00 ghettoblaster volumio[2739]: info: Starting Shairport Sync May 24 23:24:00 ghettoblaster sudo[2935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:00 ghettoblaster sudo[2935]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:00 ghettoblaster volumio[2739]: info: CoreCommandRouter::volumioGetState May 24 23:24:00 ghettoblaster volumio[2739]: info: CorePlayQueue::getTrack 0 May 24 23:24:00 ghettoblaster sudo[2938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:00 ghettoblaster sudo[2938]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:00 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748121840 101 May 24 23:24:00 ghettoblaster volumio[2739]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 24 23:24:01 ghettoblaster volumio[2739]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. May 24 23:24:01 ghettoblaster volumio[2739]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 24 23:24:01 ghettoblaster mpd[2857]: May 24 23:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 24 23:24:01 ghettoblaster systemd[1]: Started Music Player Daemon. May 24 23:24:01 ghettoblaster sudo[2898]: pam_unix(sudo:session): session closed for user root May 24 23:24:01 ghettoblaster sudo[2836]: pam_unix(sudo:session): session closed for user root May 24 23:24:01 ghettoblaster sudo[2847]: pam_unix(sudo:session): session closed for user root May 24 23:24:01 ghettoblaster volumio[2739]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 24 23:24:01 ghettoblaster systemd[1]: Reloading. May 24 23:24:01 ghettoblaster volumio[2739]: error: MPD error: The expression evaluated to a falsy value: May 24 23:24:01 ghettoblaster volumio[2739]: assert.ok(self.idling) May 24 23:24:01 ghettoblaster volumio[2739]: error: The expression evaluated to a falsy value: May 24 23:24:01 ghettoblaster volumio[2739]: assert.ok(self.idling) May 24 23:24:01 ghettoblaster volumio[2739]: info: MPD running with PID2857 May 24 23:24:01 ghettoblaster volumio[2739]: ,establishing connection May 24 23:24:01 ghettoblaster volumio[2739]: error: updateQueue error: null May 24 23:24:01 ghettoblaster volumio[2739]: error: updateQueue error: null May 24 23:24:01 ghettoblaster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 23:24:01 ghettoblaster sudo[2902]: pam_unix(sudo:session): session closed for user root May 24 23:24:01 ghettoblaster volumio[2739]: info: touch_display: systemctl daemon-reload succeeded. May 24 23:24:01 ghettoblaster systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 24 23:24:01 ghettoblaster systemd[1]: shairport-sync.service: Succeeded. May 24 23:24:01 ghettoblaster systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 24 23:24:01 ghettoblaster systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 24 23:24:01 ghettoblaster sudo[2938]: pam_unix(sudo:session): session closed for user root May 24 23:24:01 ghettoblaster sudo[2935]: pam_unix(sudo:session): session closed for user root May 24 23:24:01 ghettoblaster sudo[2933]: pam_unix(sudo:session): session closed for user root May 24 23:24:01 ghettoblaster sudo[2959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 24 23:24:01 ghettoblaster sudo[2959]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:01 ghettoblaster volumio[2739]: info: Shairport-Sync Started May 24 23:24:01 ghettoblaster volumio[2739]: Error adding Membership: Error: addMembership EINVAL May 24 23:24:01 ghettoblaster volumio[2739]: info: Shairport-Sync Started May 24 23:24:01 ghettoblaster volumio[2739]: info: Shairport-Sync Started May 24 23:24:01 ghettoblaster sudo[2959]: pam_unix(sudo:session): session closed for user root May 24 23:24:01 ghettoblaster volumio[2739]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 24 23:24:01 ghettoblaster volumio[2739]: info: touch_display: Volumio Kiosk started. May 24 23:24:01 ghettoblaster volumio[2739]: info: CoreCommandRouter::volumioGetState May 24 23:24:01 ghettoblaster volumio[2739]: info: CorePlayQueue::getTrack 0 May 24 23:24:01 ghettoblaster volumio[2739]: info: Completed starting Core Plugins May 24 23:24:01 ghettoblaster volumio[2739]: info: ------------------------------------------- May 24 23:24:01 ghettoblaster volumio[2739]: info: ----- MyVolumio plugins startup ---- May 24 23:24:01 ghettoblaster volumio[2739]: info: ------------------------------------------- May 24 23:24:01 ghettoblaster volumio[2739]: info: [MyVolumio PluginManager] Fetching plans data.... May 24 23:24:01 ghettoblaster volumio[2739]: info: CoreCommandRouter::volumioGetState May 24 23:24:01 ghettoblaster volumio[2739]: info: CorePlayQueue::getTrack 0 May 24 23:24:01 ghettoblaster volumio[2739]: info: touch_display: X display number found: 0 May 24 23:24:01 ghettoblaster volumio[2739]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 24 23:24:01 ghettoblaster volumio[2739]: info: touch_display: X display number found: 0 May 24 23:24:02 ghettoblaster volumio[2739]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 23:24:02 ghettoblaster volumio[2739]: TypeError: Cannot read property 'sort' of null May 24 23:24:02 ghettoblaster volumio[2739]: at /data/plugins/user_interface/touch_display/index.js:1599:48 May 24 23:24:02 ghettoblaster volumio[2739]: at ChildProcess.exithandler (child_process.js:299:7) May 24 23:24:02 ghettoblaster volumio[2739]: at ChildProcess.emit (events.js:315:20) May 24 23:24:02 ghettoblaster volumio[2739]: at maybeClose (internal/child_process.js:1048:16) May 24 23:24:02 ghettoblaster volumio[2739]: at Socket. (internal/child_process.js:439:11) May 24 23:24:02 ghettoblaster volumio[2739]: at Socket.emit (events.js:315:20) May 24 23:24:02 ghettoblaster volumio[2739]: at Pipe. (net.js:673:12) May 24 23:24:02 ghettoblaster volumio[2739]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 23:24:02 ghettoblaster sudo[2979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 23:23 May 24 23:24:02 ghettoblaster sudo[2979]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:02 ghettoblaster sudo[2979]: pam_unix(sudo:session): session closed for user root May 24 23:24:02 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 24 23:24:02 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 24 23:24:02 ghettoblaster systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 24 23:24:02 ghettoblaster systemd[1]: volumio.service: Failed with result 'exit-code'. May 24 23:24:02 ghettoblaster systemd[1]: Started dynamicswap service. May 24 23:24:02 ghettoblaster systemd[1]: dynamicswap.service: Succeeded. May 24 23:24:02 ghettoblaster systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 24 23:24:02 ghettoblaster systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. May 24 23:24:02 ghettoblaster systemd[1]: Started dynamicswap service. May 24 23:24:02 ghettoblaster systemd[1]: Stopped Volumio Backend Module. May 24 23:24:02 ghettoblaster systemd[1]: Started Volumio Backend Module. May 24 23:24:02 ghettoblaster systemd[1]: dynamicswap.service: Succeeded. May 24 23:24:03 ghettoblaster volumio[2993]: info: ------------------------------------------- May 24 23:24:03 ghettoblaster volumio[2993]: info: ----- Volumio3 ---- May 24 23:24:03 ghettoblaster volumio[2993]: info: ------------------------------------------- May 24 23:24:03 ghettoblaster volumio[2993]: info: ----- System startup ---- May 24 23:24:03 ghettoblaster volumio[2993]: info: ------------------------------------------- May 24 23:24:03 ghettoblaster volumio[2993]: info: MYVOLUMIO Environment detected May 24 23:24:03 ghettoblaster volumio[2993]: info: Plugin folders cleanup May 24 23:24:03 ghettoblaster volumio[2993]: info: Scanning into folder /volumio/app/plugins/ May 24 23:24:03 ghettoblaster volumio[2993]: info: Scanning category audio_interface May 24 23:24:03 ghettoblaster volumio[2993]: info: Scanning category miscellanea May 24 23:24:03 ghettoblaster volumio[2993]: info: Scanning category music_service May 24 23:24:03 ghettoblaster volumio[2993]: info: Scanning category plugins.json May 24 23:24:03 ghettoblaster volumio[2993]: info: Scanning category system_controller May 24 23:24:03 ghettoblaster volumio[2993]: info: Scanning category user_interface May 24 23:24:03 ghettoblaster volumio[2993]: info: Scanning into folder /data/plugins/ May 24 23:24:03 ghettoblaster volumio[2993]: info: Scanning category user_interface May 24 23:24:03 ghettoblaster volumio[2993]: info: Plugin folders cleanup completed May 24 23:24:03 ghettoblaster volumio[2993]: info: ------------------------------------------- May 24 23:24:03 ghettoblaster volumio[2993]: info: ----- Core plugins startup ---- May 24 23:24:03 ghettoblaster volumio[2993]: info: ------------------------------------------- May 24 23:24:03 ghettoblaster volumio[2993]: info: Loading plugins from folder /volumio/app/plugins/ May 24 23:24:03 ghettoblaster volumio[2993]: info: Adding plugin upnp to MyMusic Plugins May 24 23:24:03 ghettoblaster volumio[2993]: info: Adding plugin airplay_emulation to MyMusic Plugins May 24 23:24:03 ghettoblaster volumio[2993]: info: Adding plugin upnp_browser to MyMusic Plugins May 24 23:24:03 ghettoblaster volumio[2993]: info: Loading plugins from folder /data/plugins/ May 24 23:24:03 ghettoblaster volumio[2993]: info: Loading plugin "system"... May 24 23:24:04 ghettoblaster volumio[2993]: info: Loading plugin "appearance"... May 24 23:24:04 ghettoblaster volumio[2993]: info: Loading plugin "network"... May 24 23:24:04 ghettoblaster volumio[2993]: info: Refreshing Cached IP Addresses May 24 23:24:04 ghettoblaster sudo[3019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 23:24:04 ghettoblaster sudo[3019]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:04 ghettoblaster sudo[3019]: pam_unix(sudo:session): session closed for user root May 24 23:24:04 ghettoblaster sudo[3021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 23:24:04 ghettoblaster sudo[3021]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:04 ghettoblaster volumio[2993]: info: Loading plugin "services"... May 24 23:24:04 ghettoblaster sudo[3021]: pam_unix(sudo:session): session closed for user root May 24 23:24:04 ghettoblaster volumio[2993]: info: Loading plugin "alsa_controller"... May 24 23:24:04 ghettoblaster volumio[2993]: warn: Unable to locate the audio output device HiFiBerry DAC. Please configure a valid output device. May 24 23:24:04 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:04 ghettoblaster volumio[2993]: info: Loading plugin "wizard"... May 24 23:24:04 ghettoblaster volumio[2993]: info: Loading plugin "networkfs"... May 24 23:24:04 ghettoblaster volumio[2993]: info: Starting Udev Watcher for removable devices May 24 23:24:04 ghettoblaster volumio[2993]: info: Ignoring mount for partition: boot May 24 23:24:04 ghettoblaster volumio[2993]: info: Ignoring mount for partition: volumio May 24 23:24:04 ghettoblaster volumio[2993]: info: Ignoring mount for partition: volumio_data May 24 23:24:04 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:04 ghettoblaster volumio[2993]: info: Loading plugin "volumio_command_line_client"... May 24 23:24:04 ghettoblaster volumio[2993]: info: Loading plugin "upnp"... May 24 23:24:04 ghettoblaster volumio[2993]: info: [1748121844720] Starting Upmpd Daemon May 24 23:24:04 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:04 ghettoblaster volumio[2993]: info: Loading plugin "my_music"... May 24 23:24:04 ghettoblaster volumio[2993]: info: Loading plugin "mpd"... May 24 23:24:04 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Fetching time from Volumio... May 24 23:24:04 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Date not found in response May 24 23:24:04 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Retrying in 5 seconds... May 24 23:24:04 ghettoblaster volumio[2993]: info: Loading plugin "upnp_browser"... May 24 23:24:05 ghettoblaster volumio[2993]: info: Loading plugin "alarm-clock"... May 24 23:24:05 ghettoblaster volumio[2993]: info: Loading plugin "airplay_emulation"... May 24 23:24:05 ghettoblaster volumio[2993]: info: Starting Shairport Sync May 24 23:24:05 ghettoblaster volumio[2993]: info: Loading plugin "last_100"... May 24 23:24:05 ghettoblaster volumio[2993]: info: Loading plugin "webradio"... May 24 23:24:05 ghettoblaster volumio[2993]: info: Loading plugin "i2s_dacs"... May 24 23:24:05 ghettoblaster volumio[2993]: info: Loading plugin "volumiodiscovery"... May 24 23:24:05 ghettoblaster volumio[2993]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 23:24:05 ghettoblaster volumio[2993]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:05 ghettoblaster node[2993]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 23:24:05 ghettoblaster volumio[2993]: *** WARNING *** For more information see May 24 23:24:05 ghettoblaster volumio[2993]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 23:24:05 ghettoblaster volumio[2993]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:05 ghettoblaster volumio[2993]: *** WARNING *** For more information see May 24 23:24:05 ghettoblaster node[2993]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:05 ghettoblaster node[2993]: *** WARNING *** For more information see May 24 23:24:05 ghettoblaster node[2993]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 23:24:05 ghettoblaster node[2993]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:05 ghettoblaster node[2993]: *** WARNING *** For more information see May 24 23:24:05 ghettoblaster volumio[2993]: info: Applying required configuration parameters for plugin volumiodiscovery May 24 23:24:05 ghettoblaster volumio[2993]: info: Discovery: Started advertising with name: GhettoBlaster May 24 23:24:05 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:05 ghettoblaster volumio[2993]: info: Loading plugin "outputs"... May 24 23:24:05 ghettoblaster volumio[2993]: info: Loading plugin "albumart"... May 24 23:24:05 ghettoblaster volumio[2993]: info: Plugin example_plugin is not enabled May 24 23:24:05 ghettoblaster volumio[2993]: info: Loading plugin "inputs"... May 24 23:24:05 ghettoblaster volumio[2993]: info: Loading plugin "updater_comm"... May 24 23:24:05 ghettoblaster volumio[2993]: info: Plugin mpdemulation is not enabled May 24 23:24:05 ghettoblaster volumio[2993]: info: Loading plugin "rest_api"... May 24 23:24:05 ghettoblaster volumio[2993]: info: Loading plugin "websocket"... May 24 23:24:05 ghettoblaster volumio[2993]: info: Starting Socket.io Server version 2.3.0 May 24 23:24:05 ghettoblaster volumio[2993]: info: Loading plugin "touch_display"... May 24 23:24:05 ghettoblaster volumio[2993]: Forking 3 albumart workers May 24 23:24:05 ghettoblaster volumio[2993]: info: Applying required configuration parameters for plugin touch_display May 24 23:24:05 ghettoblaster volumio[2993]: info: Loading i18n strings for locale en May 24 23:24:05 ghettoblaster volumio[2993]: Updating browse sources language May 24 23:24:05 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::initPlayerControls May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: Express server listening on port 3000 May 24 23:24:06 ghettoblaster volumio[2993]: [Metrics] WebUI: 2s 799.49ms May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreStateMachine::resetVolumioState May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreStateMachine::getcurrentVolume May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::volumioRetrievevolume May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreStateMachine::pushState May 24 23:24:06 ghettoblaster volumio[2993]: info: CorePlayQueue::getTrack 0 May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::volumioPushState May 24 23:24:06 ghettoblaster volumio[2993]: Starting albumart workers May 24 23:24:06 ghettoblaster volumio[2993]: Starting albumart workers May 24 23:24:06 ghettoblaster volumio[2993]: info: Cannot read play queue from file May 24 23:24:06 ghettoblaster volumio[2993]: Starting albumart workers May 24 23:24:06 ghettoblaster volumio[2993]: info: Volumio Network Manager: Network status updated: 0 May 24 23:24:06 ghettoblaster volumio[2993]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreStateMachine::setRepeat null single undefined May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreStateMachine::pushState May 24 23:24:06 ghettoblaster volumio[2993]: info: CorePlayQueue::getTrack 0 May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::volumioPushState May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreStateMachine::setRandom null May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreStateMachine::pushState May 24 23:24:06 ghettoblaster volumio[2993]: info: CorePlayQueue::getTrack 0 May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::volumioPushState May 24 23:24:06 ghettoblaster volumio[2993]: info: Setting Device type: Raspberry PI May 24 23:24:06 ghettoblaster volumio[2993]: info: Completed loading Core Plugins May 24 23:24:06 ghettoblaster volumio[2993]: info: Preparing to generate the ALSA configuration file May 24 23:24:06 ghettoblaster volumio[2993]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 24 23:24:06 ghettoblaster volumio[2993]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 May 24 23:24:06 ghettoblaster volumio[2993]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 24 23:24:06 ghettoblaster volumio[2993]: info: Asound.conf file unchanged, so no further update is needed May 24 23:24:06 ghettoblaster volumio[2993]: info: Output device has changed, restarting MPD May 24 23:24:06 ghettoblaster sudo[3089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 23:24:06 ghettoblaster sudo[3089]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:06 ghettoblaster volumio[2993]: info: Output device has changed, restarting Shairport Sync May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:06 ghettoblaster sudo[3089]: pam_unix(sudo:session): session closed for user root May 24 23:24:06 ghettoblaster sudo[3099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 23:24:06 ghettoblaster sudo[3099]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:06 ghettoblaster systemd[1]: Stopping Music Player Daemon... May 24 23:24:06 ghettoblaster volumio[2993]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:06 ghettoblaster systemd[1]: mpd.service: Succeeded. May 24 23:24:06 ghettoblaster systemd[1]: Stopped Music Player Daemon. May 24 23:24:06 ghettoblaster volumio[2993]: info: ___________ START PLUGINS ___________ May 24 23:24:06 ghettoblaster volumio[2993]: info: ControllerMpd::onStart: Initializing MPD May 24 23:24:06 ghettoblaster volumio[2993]: info: Creating MPD Configuration file May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:06 ghettoblaster volumio[2993]: info: [1748121846646] CoreMusicLibrary::Adding element Media Servers May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:06 ghettoblaster systemd[1]: Starting Music Player Daemon... May 24 23:24:06 ghettoblaster sudo[3118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 23:24:06 ghettoblaster sudo[3118]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:06 ghettoblaster sudo[3118]: pam_unix(sudo:session): session closed for user root May 24 23:24:06 ghettoblaster sudo[3120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 23:24:06 ghettoblaster sudo[3120]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:06 ghettoblaster systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 24 23:24:06 ghettoblaster systemd[1]: mpd.service: Succeeded. May 24 23:24:06 ghettoblaster systemd[1]: Stopped Music Player Daemon. May 24 23:24:06 ghettoblaster systemd[1]: Starting Music Player Daemon... May 24 23:24:06 ghettoblaster volumio[2993]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:06 ghettoblaster volumio[2993]: info: [1748121846739] CoreMusicLibrary::Adding element Last_100 May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:06 ghettoblaster volumio[2993]: info: [1748121846741] CoreMusicLibrary::Adding element Webradio May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:06 ghettoblaster volumio[2993]: info: Initializing BBC Radios May 24 23:24:06 ghettoblaster sudo[3126]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 23:24:06 ghettoblaster sudo[3126]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:06 ghettoblaster sudo[3126]: pam_unix(sudo:session): session closed for user root May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:06 ghettoblaster volumio[2993]: info: Loading i18n strings for locale en May 24 23:24:06 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:06 ghettoblaster volumio[2993]: info: Volumio Calling Home May 24 23:24:06 ghettoblaster sudo[3146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service May 24 23:24:06 ghettoblaster sudo[3146]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:06 ghettoblaster sudo[3148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service May 24 23:24:06 ghettoblaster sudo[3148]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:06 ghettoblaster sudo[3152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 24 23:24:06 ghettoblaster sudo[3152]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:06 ghettoblaster systemd[1]: Reloading. May 24 23:24:07 ghettoblaster volumio[2993]: Unhandled rejection Error: No sockets available, cannot start. May 24 23:24:07 ghettoblaster volumio[2993]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) May 24 23:24:07 ghettoblaster volumio[2993]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) May 24 23:24:07 ghettoblaster volumio[2993]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 May 24 23:24:07 ghettoblaster volumio[2993]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) May 24 23:24:07 ghettoblaster volumio[2993]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) May 24 23:24:07 ghettoblaster volumio[2993]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) May 24 23:24:07 ghettoblaster volumio[2993]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) May 24 23:24:07 ghettoblaster volumio[2993]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) May 24 23:24:07 ghettoblaster volumio[2993]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) May 24 23:24:07 ghettoblaster volumio[2993]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) May 24 23:24:07 ghettoblaster volumio[2993]: at HashMap. (/volumio/app/pluginmanager.js:496:31) May 24 23:24:07 ghettoblaster volumio[2993]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 24 23:24:07 ghettoblaster volumio[2993]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 24 23:24:07 ghettoblaster volumio[2993]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) May 24 23:24:07 ghettoblaster volumio[2993]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) May 24 23:24:07 ghettoblaster volumio[2993]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 24 23:24:07 ghettoblaster volumio[2993]: info: touch_display: No backlight interface detected. May 24 23:24:07 ghettoblaster volumio[2993]: info: MPD Permissions set May 24 23:24:07 ghettoblaster volumio[2993]: info: MPD Permissions set May 24 23:24:07 ghettoblaster volumio[2993]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 24 23:24:07 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:07 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:07 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:07 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:07 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:07 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:07 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:07 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:07 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:07 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:07 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:07 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:07 ghettoblaster volumio[2993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:07 ghettoblaster volumio[2993]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 24 23:24:07 ghettoblaster sudo[3176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 24 23:24:07 ghettoblaster sudo[3176]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:07 ghettoblaster volumio[2993]: info: Starting Shairport Sync May 24 23:24:07 ghettoblaster sudo[3176]: pam_unix(sudo:session): session closed for user root May 24 23:24:07 ghettoblaster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 23:24:07 ghettoblaster sudo[3178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf May 24 23:24:07 ghettoblaster sudo[3178]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:07 ghettoblaster sudo[3178]: pam_unix(sudo:session): session closed for user root May 24 23:24:07 ghettoblaster volumio[2993]: info: Starting Shairport Sync May 24 23:24:07 ghettoblaster volumio[2993]: info: Starting Shairport Sync May 24 23:24:07 ghettoblaster sudo[3184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:07 ghettoblaster sudo[3184]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:07 ghettoblaster sudo[3182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:07 ghettoblaster sudo[3182]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:07 ghettoblaster sudo[3186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:07 ghettoblaster sudo[3186]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:07 ghettoblaster volumio[2993]: info: CoreCommandRouter::volumioGetState May 24 23:24:07 ghettoblaster volumio[2993]: info: CorePlayQueue::getTrack 0 May 24 23:24:07 ghettoblaster volumio[2993]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. May 24 23:24:07 ghettoblaster volumio[2993]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 24 23:24:07 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:07] [connect] Successful connection May 24 23:24:07 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748121847 101 May 24 23:24:07 ghettoblaster volumio[2993]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 24 23:24:07 ghettoblaster systemd[1]: Reloading. May 24 23:24:07 ghettoblaster sudo[3148]: pam_unix(sudo:session): session closed for user root May 24 23:24:07 ghettoblaster volumio[2993]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 24 23:24:07 ghettoblaster mpd[3133]: May 24 23:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 24 23:24:07 ghettoblaster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 23:24:08 ghettoblaster systemd[1]: Started Music Player Daemon. May 24 23:24:08 ghettoblaster sudo[3120]: pam_unix(sudo:session): session closed for user root May 24 23:24:08 ghettoblaster sudo[3152]: pam_unix(sudo:session): session closed for user root May 24 23:24:08 ghettoblaster sudo[3099]: pam_unix(sudo:session): session closed for user root May 24 23:24:08 ghettoblaster volumio[2993]: info: touch_display: systemctl daemon-reload succeeded. May 24 23:24:08 ghettoblaster systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 24 23:24:08 ghettoblaster systemd[1]: shairport-sync.service: Succeeded. May 24 23:24:08 ghettoblaster systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 24 23:24:08 ghettoblaster sudo[3208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 24 23:24:08 ghettoblaster sudo[3208]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:08 ghettoblaster sudo[3146]: pam_unix(sudo:session): session closed for user root May 24 23:24:08 ghettoblaster volumio[2993]: error: MPD error: The expression evaluated to a falsy value: May 24 23:24:08 ghettoblaster volumio[2993]: assert.ok(self.idling) May 24 23:24:08 ghettoblaster volumio[2993]: error: The expression evaluated to a falsy value: May 24 23:24:08 ghettoblaster volumio[2993]: assert.ok(self.idling) May 24 23:24:08 ghettoblaster volumio[2993]: info: MPD running with PID3133 May 24 23:24:08 ghettoblaster volumio[2993]: ,establishing connection May 24 23:24:08 ghettoblaster volumio[2993]: error: MPD error: The expression evaluated to a falsy value: May 24 23:24:08 ghettoblaster volumio[2993]: assert.ok(self.idling) May 24 23:24:08 ghettoblaster volumio[2993]: error: The expression evaluated to a falsy value: May 24 23:24:08 ghettoblaster volumio[2993]: assert.ok(self.idling) May 24 23:24:08 ghettoblaster volumio[2993]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 24 23:24:08 ghettoblaster volumio[2993]: error: updateQueue error: null May 24 23:24:08 ghettoblaster systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 24 23:24:08 ghettoblaster sudo[3182]: pam_unix(sudo:session): session closed for user root May 24 23:24:08 ghettoblaster sudo[3186]: pam_unix(sudo:session): session closed for user root May 24 23:24:08 ghettoblaster sudo[3184]: pam_unix(sudo:session): session closed for user root May 24 23:24:08 ghettoblaster volumio[2993]: info: Shairport-Sync Started May 24 23:24:08 ghettoblaster volumio[2993]: Error adding Membership: Error: addMembership EINVAL May 24 23:24:08 ghettoblaster volumio[2993]: info: Shairport-Sync Started May 24 23:24:08 ghettoblaster volumio[2993]: info: Shairport-Sync Started May 24 23:24:08 ghettoblaster sudo[3208]: pam_unix(sudo:session): session closed for user root May 24 23:24:08 ghettoblaster volumio[2993]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 24 23:24:08 ghettoblaster volumio[2993]: info: touch_display: Volumio Kiosk started. May 24 23:24:08 ghettoblaster volumio[2993]: info: CoreCommandRouter::volumioGetState May 24 23:24:08 ghettoblaster volumio[2993]: info: CorePlayQueue::getTrack 0 May 24 23:24:08 ghettoblaster volumio[2993]: info: Completed starting Core Plugins May 24 23:24:08 ghettoblaster volumio[2993]: info: ------------------------------------------- May 24 23:24:08 ghettoblaster volumio[2993]: info: ----- MyVolumio plugins startup ---- May 24 23:24:08 ghettoblaster volumio[2993]: info: ------------------------------------------- May 24 23:24:08 ghettoblaster volumio[2993]: info: [MyVolumio PluginManager] Fetching plans data.... May 24 23:24:08 ghettoblaster volumio[2993]: info: CoreCommandRouter::volumioGetState May 24 23:24:08 ghettoblaster volumio[2993]: info: CorePlayQueue::getTrack 0 May 24 23:24:08 ghettoblaster volumio[2993]: info: touch_display: X display number found: 0 May 24 23:24:08 ghettoblaster volumio[2993]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 24 23:24:08 ghettoblaster volumio[2993]: info: touch_display: X display number found: 0 May 24 23:24:08 ghettoblaster volumio[2993]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 23:24:08 ghettoblaster volumio[2993]: TypeError: Cannot read property 'sort' of null May 24 23:24:08 ghettoblaster volumio[2993]: at /data/plugins/user_interface/touch_display/index.js:1599:48 May 24 23:24:08 ghettoblaster volumio[2993]: at ChildProcess.exithandler (child_process.js:299:7) May 24 23:24:08 ghettoblaster volumio[2993]: at ChildProcess.emit (events.js:315:20) May 24 23:24:08 ghettoblaster volumio[2993]: at maybeClose (internal/child_process.js:1048:16) May 24 23:24:08 ghettoblaster volumio[2993]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) May 24 23:24:08 ghettoblaster volumio[2993]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 23:24:08 ghettoblaster sudo[3228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 23:23 May 24 23:24:08 ghettoblaster sudo[3228]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:08 ghettoblaster sudo[3228]: pam_unix(sudo:session): session closed for user root May 24 23:24:09 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 24 23:24:09 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 24 23:24:09 ghettoblaster systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 24 23:24:09 ghettoblaster systemd[1]: volumio.service: Failed with result 'exit-code'. May 24 23:24:09 ghettoblaster systemd[1]: Started dynamicswap service. May 24 23:24:09 ghettoblaster systemd[1]: dynamicswap.service: Succeeded. May 24 23:24:09 ghettoblaster systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 24 23:24:09 ghettoblaster systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. May 24 23:24:09 ghettoblaster systemd[1]: Stopped Volumio Backend Module. May 24 23:24:09 ghettoblaster systemd[1]: Started Volumio Backend Module. May 24 23:24:09 ghettoblaster systemd[1]: Started dynamicswap service. May 24 23:24:09 ghettoblaster systemd[1]: dynamicswap.service: Succeeded. May 24 23:24:09 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Fetching time from Volumio... May 24 23:24:09 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Date not found in response May 24 23:24:09 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Retrying in 5 seconds... May 24 23:24:10 ghettoblaster volumio[3241]: info: ------------------------------------------- May 24 23:24:10 ghettoblaster volumio[3241]: info: ----- Volumio3 ---- May 24 23:24:10 ghettoblaster volumio[3241]: info: ------------------------------------------- May 24 23:24:10 ghettoblaster volumio[3241]: info: ----- System startup ---- May 24 23:24:10 ghettoblaster volumio[3241]: info: ------------------------------------------- May 24 23:24:10 ghettoblaster volumio[3241]: info: MYVOLUMIO Environment detected May 24 23:24:10 ghettoblaster volumio[3241]: info: Plugin folders cleanup May 24 23:24:10 ghettoblaster volumio[3241]: info: Scanning into folder /volumio/app/plugins/ May 24 23:24:10 ghettoblaster volumio[3241]: info: Scanning category audio_interface May 24 23:24:10 ghettoblaster volumio[3241]: info: Scanning category miscellanea May 24 23:24:10 ghettoblaster volumio[3241]: info: Scanning category music_service May 24 23:24:10 ghettoblaster volumio[3241]: info: Scanning category plugins.json May 24 23:24:10 ghettoblaster volumio[3241]: info: Scanning category system_controller May 24 23:24:10 ghettoblaster volumio[3241]: info: Scanning category user_interface May 24 23:24:10 ghettoblaster volumio[3241]: info: Scanning into folder /data/plugins/ May 24 23:24:10 ghettoblaster volumio[3241]: info: Scanning category user_interface May 24 23:24:10 ghettoblaster volumio[3241]: info: Plugin folders cleanup completed May 24 23:24:10 ghettoblaster volumio[3241]: info: ------------------------------------------- May 24 23:24:10 ghettoblaster volumio[3241]: info: ----- Core plugins startup ---- May 24 23:24:10 ghettoblaster volumio[3241]: info: ------------------------------------------- May 24 23:24:10 ghettoblaster volumio[3241]: info: Loading plugins from folder /volumio/app/plugins/ May 24 23:24:10 ghettoblaster volumio[3241]: info: Adding plugin upnp to MyMusic Plugins May 24 23:24:10 ghettoblaster volumio[3241]: info: Adding plugin airplay_emulation to MyMusic Plugins May 24 23:24:10 ghettoblaster volumio[3241]: info: Adding plugin upnp_browser to MyMusic Plugins May 24 23:24:10 ghettoblaster volumio[3241]: info: Loading plugins from folder /data/plugins/ May 24 23:24:10 ghettoblaster volumio[3241]: info: Loading plugin "system"... May 24 23:24:10 ghettoblaster volumio[3241]: info: Loading plugin "appearance"... May 24 23:24:11 ghettoblaster volumio[3241]: info: Loading plugin "network"... May 24 23:24:11 ghettoblaster volumio[3241]: info: Refreshing Cached IP Addresses May 24 23:24:11 ghettoblaster sudo[3273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 23:24:11 ghettoblaster sudo[3273]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:11 ghettoblaster sudo[3273]: pam_unix(sudo:session): session closed for user root May 24 23:24:11 ghettoblaster sudo[3275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 23:24:11 ghettoblaster sudo[3275]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:11 ghettoblaster volumio[3241]: info: Loading plugin "services"... May 24 23:24:11 ghettoblaster sudo[3275]: pam_unix(sudo:session): session closed for user root May 24 23:24:11 ghettoblaster volumio[3241]: info: Loading plugin "alsa_controller"... May 24 23:24:11 ghettoblaster volumio[3241]: warn: Unable to locate the audio output device HiFiBerry DAC. Please configure a valid output device. May 24 23:24:11 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:11 ghettoblaster volumio[3241]: info: Loading plugin "wizard"... May 24 23:24:11 ghettoblaster volumio[3241]: info: Loading plugin "networkfs"... May 24 23:24:11 ghettoblaster volumio[3241]: info: Starting Udev Watcher for removable devices May 24 23:24:11 ghettoblaster volumio[3241]: info: Ignoring mount for partition: boot May 24 23:24:11 ghettoblaster volumio[3241]: info: Ignoring mount for partition: volumio May 24 23:24:11 ghettoblaster volumio[3241]: info: Ignoring mount for partition: volumio_data May 24 23:24:11 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:11 ghettoblaster volumio[3241]: info: Loading plugin "volumio_command_line_client"... May 24 23:24:11 ghettoblaster volumio[3241]: info: Loading plugin "upnp"... May 24 23:24:11 ghettoblaster volumio[3241]: info: [1748121851500] Starting Upmpd Daemon May 24 23:24:11 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:11 ghettoblaster volumio[3241]: info: Loading plugin "my_music"... May 24 23:24:11 ghettoblaster volumio[3241]: info: Loading plugin "mpd"... May 24 23:24:11 ghettoblaster volumio[3241]: info: Loading plugin "upnp_browser"... May 24 23:24:11 ghettoblaster volumio[3241]: info: Loading plugin "alarm-clock"... May 24 23:24:11 ghettoblaster volumio[3241]: info: Loading plugin "airplay_emulation"... May 24 23:24:11 ghettoblaster volumio[3241]: info: Starting Shairport Sync May 24 23:24:11 ghettoblaster volumio[3241]: info: Loading plugin "last_100"... May 24 23:24:11 ghettoblaster volumio[3241]: info: Loading plugin "webradio"... May 24 23:24:12 ghettoblaster volumio[3241]: info: Loading plugin "i2s_dacs"... May 24 23:24:12 ghettoblaster volumio[3241]: info: Loading plugin "volumiodiscovery"... May 24 23:24:12 ghettoblaster volumio[3241]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 23:24:12 ghettoblaster volumio[3241]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:12 ghettoblaster node[3241]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 23:24:12 ghettoblaster volumio[3241]: *** WARNING *** For more information see May 24 23:24:12 ghettoblaster volumio[3241]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 23:24:12 ghettoblaster volumio[3241]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:12 ghettoblaster volumio[3241]: *** WARNING *** For more information see May 24 23:24:12 ghettoblaster node[3241]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:12 ghettoblaster node[3241]: *** WARNING *** For more information see May 24 23:24:12 ghettoblaster node[3241]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 23:24:12 ghettoblaster node[3241]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:12 ghettoblaster node[3241]: *** WARNING *** For more information see May 24 23:24:12 ghettoblaster volumio[3241]: info: Applying required configuration parameters for plugin volumiodiscovery May 24 23:24:12 ghettoblaster volumio[3241]: info: Discovery: Started advertising with name: GhettoBlaster May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:12 ghettoblaster volumio[3241]: info: Loading plugin "outputs"... May 24 23:24:12 ghettoblaster volumio[3241]: info: Loading plugin "albumart"... May 24 23:24:12 ghettoblaster volumio[3241]: info: Plugin example_plugin is not enabled May 24 23:24:12 ghettoblaster volumio[3241]: info: Loading plugin "inputs"... May 24 23:24:12 ghettoblaster volumio[3241]: info: Loading plugin "updater_comm"... May 24 23:24:12 ghettoblaster volumio[3241]: info: Plugin mpdemulation is not enabled May 24 23:24:12 ghettoblaster volumio[3241]: info: Loading plugin "rest_api"... May 24 23:24:12 ghettoblaster volumio[3241]: info: Loading plugin "websocket"... May 24 23:24:12 ghettoblaster volumio[3241]: info: Starting Socket.io Server version 2.3.0 May 24 23:24:12 ghettoblaster volumio[3241]: info: Loading plugin "touch_display"... May 24 23:24:12 ghettoblaster volumio[3241]: Forking 3 albumart workers May 24 23:24:12 ghettoblaster volumio[3241]: info: Applying required configuration parameters for plugin touch_display May 24 23:24:12 ghettoblaster volumio[3241]: info: Loading i18n strings for locale en May 24 23:24:12 ghettoblaster volumio[3241]: Updating browse sources language May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::initPlayerControls May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:12 ghettoblaster volumio[3241]: Express server listening on port 3000 May 24 23:24:12 ghettoblaster volumio[3241]: [Metrics] WebUI: 2s 705.78ms May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreStateMachine::resetVolumioState May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreStateMachine::getcurrentVolume May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::volumioRetrievevolume May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreStateMachine::pushState May 24 23:24:12 ghettoblaster volumio[3241]: info: CorePlayQueue::getTrack 0 May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 23:24:12 ghettoblaster volumio[3241]: info: CoreCommandRouter::volumioPushState May 24 23:24:12 ghettoblaster volumio[3241]: info: Cannot read play queue from file May 24 23:24:12 ghettoblaster volumio[3241]: info: Volumio Network Manager: Network status updated: 0 May 24 23:24:12 ghettoblaster volumio[3241]: Starting albumart workers May 24 23:24:12 ghettoblaster volumio[3241]: Starting albumart workers May 24 23:24:12 ghettoblaster volumio[3241]: Starting albumart workers May 24 23:24:12 ghettoblaster volumio[3241]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreStateMachine::setRepeat null single undefined May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreStateMachine::pushState May 24 23:24:13 ghettoblaster volumio[3241]: info: CorePlayQueue::getTrack 0 May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::volumioPushState May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreStateMachine::setRandom null May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreStateMachine::pushState May 24 23:24:13 ghettoblaster volumio[3241]: info: CorePlayQueue::getTrack 0 May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::volumioPushState May 24 23:24:13 ghettoblaster volumio[3241]: info: Setting Device type: Raspberry PI May 24 23:24:13 ghettoblaster volumio[3241]: info: Completed loading Core Plugins May 24 23:24:13 ghettoblaster volumio[3241]: info: Preparing to generate the ALSA configuration file May 24 23:24:13 ghettoblaster volumio[3241]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 24 23:24:13 ghettoblaster volumio[3241]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 May 24 23:24:13 ghettoblaster volumio[3241]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 24 23:24:13 ghettoblaster volumio[3241]: info: Asound.conf file unchanged, so no further update is needed May 24 23:24:13 ghettoblaster volumio[3241]: info: Output device has changed, restarting MPD May 24 23:24:13 ghettoblaster sudo[3333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 23:24:13 ghettoblaster sudo[3333]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:13 ghettoblaster volumio[3241]: info: Output device has changed, restarting Shairport Sync May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:13 ghettoblaster sudo[3333]: pam_unix(sudo:session): session closed for user root May 24 23:24:13 ghettoblaster sudo[3336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 23:24:13 ghettoblaster sudo[3336]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:13 ghettoblaster systemd[1]: Stopping Music Player Daemon... May 24 23:24:13 ghettoblaster systemd[1]: mpd.service: Succeeded. May 24 23:24:13 ghettoblaster systemd[1]: Stopped Music Player Daemon. May 24 23:24:13 ghettoblaster volumio[3241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:13 ghettoblaster volumio[3241]: info: ___________ START PLUGINS ___________ May 24 23:24:13 ghettoblaster volumio[3241]: info: ControllerMpd::onStart: Initializing MPD May 24 23:24:13 ghettoblaster volumio[3241]: info: Creating MPD Configuration file May 24 23:24:13 ghettoblaster systemd[1]: Starting Music Player Daemon... May 24 23:24:13 ghettoblaster sudo[3343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 23:24:13 ghettoblaster sudo[3343]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:13 ghettoblaster volumio[3241]: info: [1748121853329] CoreMusicLibrary::Adding element Media Servers May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:13 ghettoblaster sudo[3343]: pam_unix(sudo:session): session closed for user root May 24 23:24:13 ghettoblaster sudo[3346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 23:24:13 ghettoblaster sudo[3346]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:13 ghettoblaster sudo[3341]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 23:24:13 ghettoblaster sudo[3341]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:13 ghettoblaster sudo[3341]: pam_unix(sudo:session): session closed for user root May 24 23:24:13 ghettoblaster systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 24 23:24:13 ghettoblaster systemd[1]: mpd.service: Succeeded. May 24 23:24:13 ghettoblaster systemd[1]: Stopped Music Player Daemon. May 24 23:24:13 ghettoblaster systemd[1]: Starting Music Player Daemon... May 24 23:24:13 ghettoblaster volumio[3241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:13 ghettoblaster sudo[3351]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 23:24:13 ghettoblaster sudo[3351]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:13 ghettoblaster sudo[3351]: pam_unix(sudo:session): session closed for user root May 24 23:24:13 ghettoblaster volumio[3241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:13 ghettoblaster volumio[3241]: info: [1748121853493] CoreMusicLibrary::Adding element Last_100 May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:13 ghettoblaster volumio[3241]: info: [1748121853495] CoreMusicLibrary::Adding element Webradio May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:13 ghettoblaster volumio[3241]: info: Initializing BBC Radios May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: Loading i18n strings for locale en May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:13 ghettoblaster volumio[3241]: info: Volumio Calling Home May 24 23:24:13 ghettoblaster sudo[3395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service May 24 23:24:13 ghettoblaster sudo[3395]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:13 ghettoblaster sudo[3397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service May 24 23:24:13 ghettoblaster sudo[3397]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:13 ghettoblaster sudo[3400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 24 23:24:13 ghettoblaster sudo[3400]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:13 ghettoblaster sudo[3395]: pam_unix(sudo:session): session closed for user root May 24 23:24:13 ghettoblaster systemd[1]: Reloading. May 24 23:24:13 ghettoblaster volumio[3241]: Unhandled rejection Error: No sockets available, cannot start. May 24 23:24:13 ghettoblaster volumio[3241]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) May 24 23:24:13 ghettoblaster volumio[3241]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) May 24 23:24:13 ghettoblaster volumio[3241]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 May 24 23:24:13 ghettoblaster volumio[3241]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) May 24 23:24:13 ghettoblaster volumio[3241]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) May 24 23:24:13 ghettoblaster volumio[3241]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) May 24 23:24:13 ghettoblaster volumio[3241]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) May 24 23:24:13 ghettoblaster volumio[3241]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) May 24 23:24:13 ghettoblaster volumio[3241]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) May 24 23:24:13 ghettoblaster volumio[3241]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) May 24 23:24:13 ghettoblaster volumio[3241]: at HashMap. (/volumio/app/pluginmanager.js:496:31) May 24 23:24:13 ghettoblaster volumio[3241]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 24 23:24:13 ghettoblaster volumio[3241]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 24 23:24:13 ghettoblaster volumio[3241]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) May 24 23:24:13 ghettoblaster volumio[3241]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) May 24 23:24:13 ghettoblaster volumio[3241]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 24 23:24:13 ghettoblaster volumio[3241]: info: touch_display: No backlight interface detected. May 24 23:24:13 ghettoblaster volumio[3241]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 24 23:24:13 ghettoblaster volumio[3241]: info: MPD Permissions set May 24 23:24:13 ghettoblaster volumio[3241]: info: MPD Permissions set May 24 23:24:13 ghettoblaster volumio[3241]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:13 ghettoblaster volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:14 ghettoblaster volumio[3241]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 24 23:24:14 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:14] [connect] Successful connection May 24 23:24:14 ghettoblaster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 23:24:14 ghettoblaster sudo[3451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 24 23:24:14 ghettoblaster sudo[3451]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:14 ghettoblaster sudo[3451]: pam_unix(sudo:session): session closed for user root May 24 23:24:14 ghettoblaster volumio[3241]: info: Starting Shairport Sync May 24 23:24:14 ghettoblaster sudo[3454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf May 24 23:24:14 ghettoblaster sudo[3454]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:14 ghettoblaster volumio[3241]: info: Starting Shairport Sync May 24 23:24:14 ghettoblaster sudo[3454]: pam_unix(sudo:session): session closed for user root May 24 23:24:14 ghettoblaster sudo[3457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:14 ghettoblaster volumio[3241]: info: Starting Shairport Sync May 24 23:24:14 ghettoblaster sudo[3457]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:14 ghettoblaster sudo[3459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:14 ghettoblaster sudo[3459]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:14 ghettoblaster volumio[3241]: info: CoreCommandRouter::volumioGetState May 24 23:24:14 ghettoblaster volumio[3241]: info: CorePlayQueue::getTrack 0 May 24 23:24:14 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748121854 101 May 24 23:24:14 ghettoblaster volumio[3241]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 24 23:24:14 ghettoblaster volumio[3241]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 24 23:24:14 ghettoblaster volumio[3241]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. May 24 23:24:14 ghettoblaster sudo[3462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:14 ghettoblaster sudo[3462]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:14 ghettoblaster sudo[3400]: pam_unix(sudo:session): session closed for user root May 24 23:24:14 ghettoblaster volumio[3241]: info: touch_display: systemctl daemon-reload succeeded. May 24 23:24:14 ghettoblaster systemd[1]: Reloading. May 24 23:24:14 ghettoblaster sudo[3466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 24 23:24:14 ghettoblaster sudo[3466]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:14 ghettoblaster mpd[3380]: May 24 23:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 24 23:24:14 ghettoblaster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 23:24:14 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Fetching time from Volumio... May 24 23:24:14 ghettoblaster systemd[1]: Started Music Player Daemon. May 24 23:24:14 ghettoblaster sudo[3346]: pam_unix(sudo:session): session closed for user root May 24 23:24:14 ghettoblaster sudo[3397]: pam_unix(sudo:session): session closed for user root May 24 23:24:14 ghettoblaster sudo[3336]: pam_unix(sudo:session): session closed for user root May 24 23:24:14 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Date not found in response May 24 23:24:14 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Retrying in 5 seconds... May 24 23:24:14 ghettoblaster volumio[3241]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 24 23:24:14 ghettoblaster systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 24 23:24:14 ghettoblaster systemd[1]: shairport-sync.service: Succeeded. May 24 23:24:14 ghettoblaster systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 24 23:24:14 ghettoblaster systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 24 23:24:14 ghettoblaster sudo[3462]: pam_unix(sudo:session): session closed for user root May 24 23:24:14 ghettoblaster sudo[3457]: pam_unix(sudo:session): session closed for user root May 24 23:24:14 ghettoblaster sudo[3459]: pam_unix(sudo:session): session closed for user root May 24 23:24:14 ghettoblaster sudo[3466]: pam_unix(sudo:session): session closed for user root May 24 23:24:14 ghettoblaster volumio[3241]: error: MPD error: The expression evaluated to a falsy value: May 24 23:24:14 ghettoblaster volumio[3241]: assert.ok(self.idling) May 24 23:24:14 ghettoblaster volumio[3241]: error: The expression evaluated to a falsy value: May 24 23:24:14 ghettoblaster volumio[3241]: assert.ok(self.idling) May 24 23:24:14 ghettoblaster volumio[3241]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 24 23:24:14 ghettoblaster volumio[3241]: info: touch_display: Volumio Kiosk started. May 24 23:24:14 ghettoblaster volumio[3241]: info: CoreCommandRouter::volumioGetState May 24 23:24:14 ghettoblaster volumio[3241]: info: CorePlayQueue::getTrack 0 May 24 23:24:14 ghettoblaster volumio[3241]: info: Completed starting Core Plugins May 24 23:24:14 ghettoblaster volumio[3241]: info: ------------------------------------------- May 24 23:24:14 ghettoblaster volumio[3241]: info: ----- MyVolumio plugins startup ---- May 24 23:24:14 ghettoblaster volumio[3241]: info: ------------------------------------------- May 24 23:24:14 ghettoblaster volumio[3241]: info: [MyVolumio PluginManager] Fetching plans data.... May 24 23:24:14 ghettoblaster volumio[3241]: info: Shairport-Sync Started May 24 23:24:14 ghettoblaster volumio[3241]: Error adding Membership: Error: addMembership EINVAL May 24 23:24:14 ghettoblaster volumio[3241]: info: Shairport-Sync Started May 24 23:24:14 ghettoblaster volumio[3241]: info: Shairport-Sync Started May 24 23:24:14 ghettoblaster volumio[3241]: info: MPD running with PID3380 May 24 23:24:14 ghettoblaster volumio[3241]: ,establishing connection May 24 23:24:14 ghettoblaster volumio[3241]: error: updateQueue error: null May 24 23:24:14 ghettoblaster volumio[3241]: info: CoreCommandRouter::volumioGetState May 24 23:24:14 ghettoblaster volumio[3241]: info: CorePlayQueue::getTrack 0 May 24 23:24:14 ghettoblaster volumio[3241]: error: updateQueue error: null May 24 23:24:14 ghettoblaster volumio[3241]: info: touch_display: X display number found: 0 May 24 23:24:15 ghettoblaster volumio[3241]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 24 23:24:15 ghettoblaster volumio[3241]: info: touch_display: X display number found: 0 May 24 23:24:15 ghettoblaster volumio[3241]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 23:24:15 ghettoblaster volumio[3241]: TypeError: Cannot read property 'sort' of null May 24 23:24:15 ghettoblaster volumio[3241]: at /data/plugins/user_interface/touch_display/index.js:1599:48 May 24 23:24:15 ghettoblaster volumio[3241]: at ChildProcess.exithandler (child_process.js:299:7) May 24 23:24:15 ghettoblaster volumio[3241]: at ChildProcess.emit (events.js:315:20) May 24 23:24:15 ghettoblaster volumio[3241]: at maybeClose (internal/child_process.js:1048:16) May 24 23:24:15 ghettoblaster volumio[3241]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) May 24 23:24:15 ghettoblaster volumio[3241]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 23:24:15 ghettoblaster sudo[3508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 23:23 May 24 23:24:15 ghettoblaster sudo[3508]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:15 ghettoblaster sudo[3508]: pam_unix(sudo:session): session closed for user root May 24 23:24:15 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:15] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 24 23:24:15 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:15] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 24 23:24:15 ghettoblaster systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 24 23:24:15 ghettoblaster systemd[1]: volumio.service: Failed with result 'exit-code'. May 24 23:24:15 ghettoblaster systemd[1]: Started dynamicswap service. May 24 23:24:15 ghettoblaster systemd[1]: dynamicswap.service: Succeeded. May 24 23:24:15 ghettoblaster systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 24 23:24:15 ghettoblaster systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. May 24 23:24:15 ghettoblaster systemd[1]: Started dynamicswap service. May 24 23:24:15 ghettoblaster systemd[1]: Stopped Volumio Backend Module. May 24 23:24:15 ghettoblaster systemd[1]: Started Volumio Backend Module. May 24 23:24:15 ghettoblaster systemd[1]: dynamicswap.service: Succeeded. May 24 23:24:16 ghettoblaster volumio[3522]: info: ------------------------------------------- May 24 23:24:16 ghettoblaster volumio[3522]: info: ----- Volumio3 ---- May 24 23:24:16 ghettoblaster volumio[3522]: info: ------------------------------------------- May 24 23:24:16 ghettoblaster volumio[3522]: info: ----- System startup ---- May 24 23:24:16 ghettoblaster volumio[3522]: info: ------------------------------------------- May 24 23:24:17 ghettoblaster volumio[3522]: info: MYVOLUMIO Environment detected May 24 23:24:17 ghettoblaster volumio[3522]: info: Plugin folders cleanup May 24 23:24:17 ghettoblaster volumio[3522]: info: Scanning into folder /volumio/app/plugins/ May 24 23:24:17 ghettoblaster volumio[3522]: info: Scanning category audio_interface May 24 23:24:17 ghettoblaster volumio[3522]: info: Scanning category miscellanea May 24 23:24:17 ghettoblaster volumio[3522]: info: Scanning category music_service May 24 23:24:17 ghettoblaster volumio[3522]: info: Scanning category plugins.json May 24 23:24:17 ghettoblaster volumio[3522]: info: Scanning category system_controller May 24 23:24:17 ghettoblaster volumio[3522]: info: Scanning category user_interface May 24 23:24:17 ghettoblaster volumio[3522]: info: Scanning into folder /data/plugins/ May 24 23:24:17 ghettoblaster volumio[3522]: info: Scanning category user_interface May 24 23:24:17 ghettoblaster volumio[3522]: info: Plugin folders cleanup completed May 24 23:24:17 ghettoblaster volumio[3522]: info: ------------------------------------------- May 24 23:24:17 ghettoblaster volumio[3522]: info: ----- Core plugins startup ---- May 24 23:24:17 ghettoblaster volumio[3522]: info: ------------------------------------------- May 24 23:24:17 ghettoblaster volumio[3522]: info: Loading plugins from folder /volumio/app/plugins/ May 24 23:24:17 ghettoblaster volumio[3522]: info: Adding plugin upnp to MyMusic Plugins May 24 23:24:17 ghettoblaster volumio[3522]: info: Adding plugin airplay_emulation to MyMusic Plugins May 24 23:24:17 ghettoblaster volumio[3522]: info: Adding plugin upnp_browser to MyMusic Plugins May 24 23:24:17 ghettoblaster volumio[3522]: info: Loading plugins from folder /data/plugins/ May 24 23:24:17 ghettoblaster volumio[3522]: info: Loading plugin "system"... May 24 23:24:17 ghettoblaster volumio[3522]: info: Loading plugin "appearance"... May 24 23:24:17 ghettoblaster volumio[3522]: info: Loading plugin "network"... May 24 23:24:17 ghettoblaster volumio[3522]: info: Refreshing Cached IP Addresses May 24 23:24:17 ghettoblaster sudo[3548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 23:24:17 ghettoblaster sudo[3548]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:17 ghettoblaster sudo[3548]: pam_unix(sudo:session): session closed for user root May 24 23:24:17 ghettoblaster sudo[3550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 23:24:17 ghettoblaster sudo[3550]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:17 ghettoblaster volumio[3522]: info: Loading plugin "services"... May 24 23:24:17 ghettoblaster sudo[3550]: pam_unix(sudo:session): session closed for user root May 24 23:24:17 ghettoblaster volumio[3522]: info: Loading plugin "alsa_controller"... May 24 23:24:17 ghettoblaster volumio[3522]: warn: Unable to locate the audio output device HiFiBerry DAC. Please configure a valid output device. May 24 23:24:17 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:17 ghettoblaster volumio[3522]: info: Loading plugin "wizard"... May 24 23:24:17 ghettoblaster volumio[3522]: info: Loading plugin "networkfs"... May 24 23:24:17 ghettoblaster volumio[3522]: info: Starting Udev Watcher for removable devices May 24 23:24:18 ghettoblaster volumio[3522]: info: Ignoring mount for partition: boot May 24 23:24:18 ghettoblaster volumio[3522]: info: Ignoring mount for partition: volumio May 24 23:24:18 ghettoblaster volumio[3522]: info: Ignoring mount for partition: volumio_data May 24 23:24:18 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "volumio_command_line_client"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "upnp"... May 24 23:24:18 ghettoblaster volumio[3522]: info: [1748121858012] Starting Upmpd Daemon May 24 23:24:18 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "my_music"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "mpd"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "upnp_browser"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "alarm-clock"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "airplay_emulation"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Starting Shairport Sync May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "last_100"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "webradio"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "i2s_dacs"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "volumiodiscovery"... May 24 23:24:18 ghettoblaster volumio[3522]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 23:24:18 ghettoblaster volumio[3522]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:18 ghettoblaster node[3522]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 23:24:18 ghettoblaster volumio[3522]: *** WARNING *** For more information see May 24 23:24:18 ghettoblaster volumio[3522]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 23:24:18 ghettoblaster volumio[3522]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:18 ghettoblaster volumio[3522]: *** WARNING *** For more information see May 24 23:24:18 ghettoblaster node[3522]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:18 ghettoblaster node[3522]: *** WARNING *** For more information see May 24 23:24:18 ghettoblaster node[3522]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 23:24:18 ghettoblaster node[3522]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:18 ghettoblaster node[3522]: *** WARNING *** For more information see May 24 23:24:18 ghettoblaster volumio[3522]: info: Applying required configuration parameters for plugin volumiodiscovery May 24 23:24:18 ghettoblaster volumio[3522]: info: Discovery: Started advertising with name: GhettoBlaster May 24 23:24:18 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "outputs"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "albumart"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Plugin example_plugin is not enabled May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "inputs"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "updater_comm"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Plugin mpdemulation is not enabled May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "rest_api"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "websocket"... May 24 23:24:18 ghettoblaster volumio[3522]: info: Starting Socket.io Server version 2.3.0 May 24 23:24:18 ghettoblaster volumio[3522]: info: Loading plugin "touch_display"... May 24 23:24:18 ghettoblaster volumio[3522]: Forking 3 albumart workers May 24 23:24:19 ghettoblaster volumio[3522]: info: Applying required configuration parameters for plugin touch_display May 24 23:24:19 ghettoblaster volumio[3522]: info: Loading i18n strings for locale en May 24 23:24:19 ghettoblaster volumio[3522]: Updating browse sources language May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::initPlayerControls May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: Express server listening on port 3000 May 24 23:24:19 ghettoblaster volumio[3522]: [Metrics] WebUI: 2s 739.78ms May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreStateMachine::resetVolumioState May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreStateMachine::getcurrentVolume May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::volumioRetrievevolume May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreStateMachine::pushState May 24 23:24:19 ghettoblaster volumio[3522]: info: CorePlayQueue::getTrack 0 May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::volumioPushState May 24 23:24:19 ghettoblaster volumio[3522]: info: Cannot read play queue from file May 24 23:24:19 ghettoblaster volumio[3522]: info: Volumio Network Manager: Network status updated: 0 May 24 23:24:19 ghettoblaster volumio[3522]: Starting albumart workers May 24 23:24:19 ghettoblaster volumio[3522]: Starting albumart workers May 24 23:24:19 ghettoblaster volumio[3522]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: Starting albumart workers May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreStateMachine::setRepeat null single undefined May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreStateMachine::pushState May 24 23:24:19 ghettoblaster volumio[3522]: info: CorePlayQueue::getTrack 0 May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::volumioPushState May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreStateMachine::setRandom null May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreStateMachine::pushState May 24 23:24:19 ghettoblaster volumio[3522]: info: CorePlayQueue::getTrack 0 May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::volumioPushState May 24 23:24:19 ghettoblaster volumio[3522]: info: Setting Device type: Raspberry PI May 24 23:24:19 ghettoblaster volumio[3522]: info: Completed loading Core Plugins May 24 23:24:19 ghettoblaster volumio[3522]: info: Preparing to generate the ALSA configuration file May 24 23:24:19 ghettoblaster volumio[3522]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 24 23:24:19 ghettoblaster volumio[3522]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 May 24 23:24:19 ghettoblaster volumio[3522]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 24 23:24:19 ghettoblaster volumio[3522]: info: Asound.conf file unchanged, so no further update is needed May 24 23:24:19 ghettoblaster volumio[3522]: info: Output device has changed, restarting MPD May 24 23:24:19 ghettoblaster volumio[3522]: info: Output device has changed, restarting Shairport Sync May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:19 ghettoblaster sudo[3611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 23:24:19 ghettoblaster sudo[3611]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:19 ghettoblaster sudo[3613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 23:24:19 ghettoblaster sudo[3611]: pam_unix(sudo:session): session closed for user root May 24 23:24:19 ghettoblaster sudo[3613]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:19 ghettoblaster volumio[3522]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:19 ghettoblaster volumio[3522]: info: ___________ START PLUGINS ___________ May 24 23:24:19 ghettoblaster volumio[3522]: info: ControllerMpd::onStart: Initializing MPD May 24 23:24:19 ghettoblaster volumio[3522]: info: Creating MPD Configuration file May 24 23:24:19 ghettoblaster systemd[1]: Stopping Music Player Daemon... May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:19 ghettoblaster volumio[3522]: info: [1748121859827] CoreMusicLibrary::Adding element Media Servers May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:19 ghettoblaster sudo[3620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 23:24:19 ghettoblaster systemd[1]: mpd.service: Succeeded. May 24 23:24:19 ghettoblaster systemd[1]: Stopped Music Player Daemon. May 24 23:24:19 ghettoblaster sudo[3620]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:19 ghettoblaster sudo[3622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 23:24:19 ghettoblaster systemd[1]: Starting Music Player Daemon... May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:19 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Fetching time from Volumio... May 24 23:24:19 ghettoblaster sudo[3622]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:19 ghettoblaster sudo[3620]: pam_unix(sudo:session): session closed for user root May 24 23:24:19 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Date not found in response May 24 23:24:19 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Retrying in 5 seconds... May 24 23:24:19 ghettoblaster systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 24 23:24:19 ghettoblaster systemd[1]: mpd.service: Succeeded. May 24 23:24:19 ghettoblaster systemd[1]: Stopped Music Player Daemon. May 24 23:24:19 ghettoblaster systemd[1]: Starting Music Player Daemon... May 24 23:24:19 ghettoblaster volumio[3522]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:19 ghettoblaster volumio[3522]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:19 ghettoblaster volumio[3522]: info: [1748121859973] CoreMusicLibrary::Adding element Last_100 May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:19 ghettoblaster volumio[3522]: info: [1748121859976] CoreMusicLibrary::Adding element Webradio May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:19 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:19 ghettoblaster volumio[3522]: info: Initializing BBC Radios May 24 23:24:20 ghettoblaster sudo[3639]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 23:24:20 ghettoblaster sudo[3639]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:20 ghettoblaster sudo[3639]: pam_unix(sudo:session): session closed for user root May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: Loading i18n strings for locale en May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:20 ghettoblaster volumio[3522]: info: Volumio Calling Home May 24 23:24:20 ghettoblaster sudo[3678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service May 24 23:24:20 ghettoblaster sudo[3678]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:20 ghettoblaster sudo[3680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service May 24 23:24:20 ghettoblaster sudo[3680]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:20 ghettoblaster sudo[3684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 24 23:24:20 ghettoblaster sudo[3684]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:20 ghettoblaster systemd[1]: Reloading. May 24 23:24:20 ghettoblaster volumio[3522]: Unhandled rejection Error: No sockets available, cannot start. May 24 23:24:20 ghettoblaster volumio[3522]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) May 24 23:24:20 ghettoblaster volumio[3522]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) May 24 23:24:20 ghettoblaster volumio[3522]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 May 24 23:24:20 ghettoblaster volumio[3522]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) May 24 23:24:20 ghettoblaster volumio[3522]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) May 24 23:24:20 ghettoblaster volumio[3522]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) May 24 23:24:20 ghettoblaster volumio[3522]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) May 24 23:24:20 ghettoblaster volumio[3522]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) May 24 23:24:20 ghettoblaster volumio[3522]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) May 24 23:24:20 ghettoblaster volumio[3522]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) May 24 23:24:20 ghettoblaster volumio[3522]: at HashMap. (/volumio/app/pluginmanager.js:496:31) May 24 23:24:20 ghettoblaster volumio[3522]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 24 23:24:20 ghettoblaster volumio[3522]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 24 23:24:20 ghettoblaster volumio[3522]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) May 24 23:24:20 ghettoblaster volumio[3522]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) May 24 23:24:20 ghettoblaster volumio[3522]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 24 23:24:20 ghettoblaster volumio[3522]: info: touch_display: No backlight interface detected. May 24 23:24:20 ghettoblaster volumio[3522]: info: MPD Permissions set May 24 23:24:20 ghettoblaster volumio[3522]: info: MPD Permissions set May 24 23:24:20 ghettoblaster volumio[3522]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:20 ghettoblaster volumio[3522]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 24 23:24:20 ghettoblaster sudo[3708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 24 23:24:20 ghettoblaster sudo[3708]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:20 ghettoblaster sudo[3708]: pam_unix(sudo:session): session closed for user root May 24 23:24:20 ghettoblaster volumio[3522]: info: Starting Shairport Sync May 24 23:24:20 ghettoblaster volumio[3522]: info: Starting Shairport Sync May 24 23:24:20 ghettoblaster sudo[3713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:20 ghettoblaster sudo[3713]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:20 ghettoblaster volumio[3522]: info: Starting Shairport Sync May 24 23:24:20 ghettoblaster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 23:24:20 ghettoblaster sudo[3711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf May 24 23:24:20 ghettoblaster sudo[3711]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:20 ghettoblaster sudo[3715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:20 ghettoblaster sudo[3715]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:20 ghettoblaster sudo[3711]: pam_unix(sudo:session): session closed for user root May 24 23:24:20 ghettoblaster sudo[3718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:20 ghettoblaster sudo[3718]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:20 ghettoblaster volumio[3522]: info: CoreCommandRouter::volumioGetState May 24 23:24:20 ghettoblaster volumio[3522]: info: CorePlayQueue::getTrack 0 May 24 23:24:20 ghettoblaster volumio[3522]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. May 24 23:24:20 ghettoblaster volumio[3522]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 24 23:24:20 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:20] [connect] Successful connection May 24 23:24:20 ghettoblaster volumio[3522]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 24 23:24:20 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748121860 101 May 24 23:24:20 ghettoblaster sudo[3680]: pam_unix(sudo:session): session closed for user root May 24 23:24:20 ghettoblaster systemd[1]: Reloading. May 24 23:24:20 ghettoblaster volumio[3522]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 24 23:24:20 ghettoblaster mpd[3655]: May 24 23:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 24 23:24:21 ghettoblaster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 23:24:21 ghettoblaster systemd[1]: Started Music Player Daemon. May 24 23:24:21 ghettoblaster sudo[3684]: pam_unix(sudo:session): session closed for user root May 24 23:24:21 ghettoblaster sudo[3622]: pam_unix(sudo:session): session closed for user root May 24 23:24:21 ghettoblaster volumio[3522]: info: touch_display: systemctl daemon-reload succeeded. May 24 23:24:21 ghettoblaster sudo[3613]: pam_unix(sudo:session): session closed for user root May 24 23:24:21 ghettoblaster sudo[3738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 24 23:24:21 ghettoblaster sudo[3738]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:21 ghettoblaster systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 24 23:24:21 ghettoblaster systemd[1]: shairport-sync.service: Succeeded. May 24 23:24:21 ghettoblaster systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 24 23:24:21 ghettoblaster sudo[3678]: pam_unix(sudo:session): session closed for user root May 24 23:24:21 ghettoblaster volumio[3522]: error: MPD error: The expression evaluated to a falsy value: May 24 23:24:21 ghettoblaster volumio[3522]: assert.ok(self.idling) May 24 23:24:21 ghettoblaster volumio[3522]: error: The expression evaluated to a falsy value: May 24 23:24:21 ghettoblaster volumio[3522]: assert.ok(self.idling) May 24 23:24:21 ghettoblaster volumio[3522]: error: updateQueue error: null May 24 23:24:21 ghettoblaster volumio[3522]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 24 23:24:21 ghettoblaster volumio[3522]: info: MPD running with PID3655 May 24 23:24:21 ghettoblaster volumio[3522]: ,establishing connection May 24 23:24:21 ghettoblaster volumio[3522]: error: updateQueue error: null May 24 23:24:21 ghettoblaster systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 24 23:24:21 ghettoblaster sudo[3713]: pam_unix(sudo:session): session closed for user root May 24 23:24:21 ghettoblaster volumio[3522]: info: Shairport-Sync Started May 24 23:24:21 ghettoblaster sudo[3715]: pam_unix(sudo:session): session closed for user root May 24 23:24:21 ghettoblaster volumio[3522]: Error adding Membership: Error: addMembership EINVAL May 24 23:24:21 ghettoblaster sudo[3718]: pam_unix(sudo:session): session closed for user root May 24 23:24:21 ghettoblaster volumio[3522]: info: Shairport-Sync Started May 24 23:24:21 ghettoblaster volumio[3522]: info: Shairport-Sync Started May 24 23:24:21 ghettoblaster sudo[3738]: pam_unix(sudo:session): session closed for user root May 24 23:24:21 ghettoblaster volumio[3522]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 24 23:24:21 ghettoblaster volumio[3522]: info: touch_display: Volumio Kiosk started. May 24 23:24:21 ghettoblaster volumio[3522]: info: CoreCommandRouter::volumioGetState May 24 23:24:21 ghettoblaster volumio[3522]: info: CorePlayQueue::getTrack 0 May 24 23:24:21 ghettoblaster volumio[3522]: info: Completed starting Core Plugins May 24 23:24:21 ghettoblaster volumio[3522]: info: ------------------------------------------- May 24 23:24:21 ghettoblaster volumio[3522]: info: ----- MyVolumio plugins startup ---- May 24 23:24:21 ghettoblaster volumio[3522]: info: ------------------------------------------- May 24 23:24:21 ghettoblaster volumio[3522]: info: [MyVolumio PluginManager] Fetching plans data.... May 24 23:24:21 ghettoblaster volumio[3522]: info: CoreCommandRouter::volumioGetState May 24 23:24:21 ghettoblaster volumio[3522]: info: CorePlayQueue::getTrack 0 May 24 23:24:21 ghettoblaster volumio[3522]: info: touch_display: X display number found: 0 May 24 23:24:21 ghettoblaster volumio[3522]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 24 23:24:21 ghettoblaster volumio[3522]: info: touch_display: X display number found: 0 May 24 23:24:21 ghettoblaster volumio[3522]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 23:24:21 ghettoblaster volumio[3522]: TypeError: Cannot read property 'sort' of null May 24 23:24:21 ghettoblaster volumio[3522]: at /data/plugins/user_interface/touch_display/index.js:1599:48 May 24 23:24:21 ghettoblaster volumio[3522]: at ChildProcess.exithandler (child_process.js:299:7) May 24 23:24:21 ghettoblaster volumio[3522]: at ChildProcess.emit (events.js:315:20) May 24 23:24:21 ghettoblaster volumio[3522]: at maybeClose (internal/child_process.js:1048:16) May 24 23:24:21 ghettoblaster volumio[3522]: at Socket. (internal/child_process.js:439:11) May 24 23:24:21 ghettoblaster volumio[3522]: at Socket.emit (events.js:315:20) May 24 23:24:21 ghettoblaster volumio[3522]: at Pipe. (net.js:673:12) May 24 23:24:21 ghettoblaster volumio[3522]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 23:24:22 ghettoblaster sudo[3760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 23:23 May 24 23:24:22 ghettoblaster sudo[3760]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:22 ghettoblaster sudo[3760]: pam_unix(sudo:session): session closed for user root May 24 23:24:22 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 24 23:24:22 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 24 23:24:22 ghettoblaster systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 24 23:24:22 ghettoblaster systemd[1]: volumio.service: Failed with result 'exit-code'. May 24 23:24:22 ghettoblaster systemd[1]: Started dynamicswap service. May 24 23:24:22 ghettoblaster systemd[1]: dynamicswap.service: Succeeded. May 24 23:24:22 ghettoblaster systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 24 23:24:22 ghettoblaster systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. May 24 23:24:22 ghettoblaster systemd[1]: Started dynamicswap service. May 24 23:24:22 ghettoblaster systemd[1]: Stopped Volumio Backend Module. May 24 23:24:22 ghettoblaster systemd[1]: Started Volumio Backend Module. May 24 23:24:22 ghettoblaster systemd[1]: dynamicswap.service: Succeeded. May 24 23:24:23 ghettoblaster ntpd[791]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 24 23:24:23 ghettoblaster volumio[3774]: info: ------------------------------------------- May 24 23:24:23 ghettoblaster volumio[3774]: info: ----- Volumio3 ---- May 24 23:24:23 ghettoblaster volumio[3774]: info: ------------------------------------------- May 24 23:24:23 ghettoblaster volumio[3774]: info: ----- System startup ---- May 24 23:24:23 ghettoblaster volumio[3774]: info: ------------------------------------------- May 24 23:24:23 ghettoblaster volumio[3774]: info: MYVOLUMIO Environment detected May 24 23:24:23 ghettoblaster volumio[3774]: info: Plugin folders cleanup May 24 23:24:23 ghettoblaster volumio[3774]: info: Scanning into folder /volumio/app/plugins/ May 24 23:24:23 ghettoblaster volumio[3774]: info: Scanning category audio_interface May 24 23:24:23 ghettoblaster volumio[3774]: info: Scanning category miscellanea May 24 23:24:23 ghettoblaster volumio[3774]: info: Scanning category music_service May 24 23:24:23 ghettoblaster volumio[3774]: info: Scanning category plugins.json May 24 23:24:23 ghettoblaster volumio[3774]: info: Scanning category system_controller May 24 23:24:23 ghettoblaster volumio[3774]: info: Scanning category user_interface May 24 23:24:23 ghettoblaster volumio[3774]: info: Scanning into folder /data/plugins/ May 24 23:24:23 ghettoblaster volumio[3774]: info: Scanning category user_interface May 24 23:24:23 ghettoblaster volumio[3774]: info: Plugin folders cleanup completed May 24 23:24:23 ghettoblaster volumio[3774]: info: ------------------------------------------- May 24 23:24:23 ghettoblaster volumio[3774]: info: ----- Core plugins startup ---- May 24 23:24:23 ghettoblaster volumio[3774]: info: ------------------------------------------- May 24 23:24:23 ghettoblaster volumio[3774]: info: Loading plugins from folder /volumio/app/plugins/ May 24 23:24:23 ghettoblaster volumio[3774]: info: Adding plugin upnp to MyMusic Plugins May 24 23:24:23 ghettoblaster volumio[3774]: info: Adding plugin airplay_emulation to MyMusic Plugins May 24 23:24:23 ghettoblaster volumio[3774]: info: Adding plugin upnp_browser to MyMusic Plugins May 24 23:24:23 ghettoblaster volumio[3774]: info: Loading plugins from folder /data/plugins/ May 24 23:24:23 ghettoblaster volumio[3774]: info: Loading plugin "system"... May 24 23:24:23 ghettoblaster volumio[3774]: info: Loading plugin "appearance"... May 24 23:24:24 ghettoblaster ntpd[791]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 24 23:24:24 ghettoblaster ntpd[791]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 24 23:24:24 ghettoblaster ntpd[791]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "network"... May 24 23:24:24 ghettoblaster volumio[3774]: info: Refreshing Cached IP Addresses May 24 23:24:24 ghettoblaster sudo[3800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 23:24:24 ghettoblaster sudo[3800]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:24 ghettoblaster sudo[3800]: pam_unix(sudo:session): session closed for user root May 24 23:24:24 ghettoblaster sudo[3802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "services"... May 24 23:24:24 ghettoblaster sudo[3802]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "alsa_controller"... May 24 23:24:24 ghettoblaster sudo[3802]: pam_unix(sudo:session): session closed for user root May 24 23:24:24 ghettoblaster volumio[3774]: warn: Unable to locate the audio output device HiFiBerry DAC. Please configure a valid output device. May 24 23:24:24 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "wizard"... May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "networkfs"... May 24 23:24:24 ghettoblaster volumio[3774]: info: Starting Udev Watcher for removable devices May 24 23:24:24 ghettoblaster volumio[3774]: info: Ignoring mount for partition: boot May 24 23:24:24 ghettoblaster volumio[3774]: info: Ignoring mount for partition: volumio May 24 23:24:24 ghettoblaster volumio[3774]: info: Ignoring mount for partition: volumio_data May 24 23:24:24 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "volumio_command_line_client"... May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "upnp"... May 24 23:24:24 ghettoblaster volumio[3774]: info: [1748121864458] Starting Upmpd Daemon May 24 23:24:24 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "my_music"... May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "mpd"... May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "upnp_browser"... May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "alarm-clock"... May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "airplay_emulation"... May 24 23:24:24 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Fetching time from Volumio... May 24 23:24:24 ghettoblaster volumio[3774]: info: Starting Shairport Sync May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "last_100"... May 24 23:24:24 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Date not found in response May 24 23:24:24 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Retrying in 5 seconds... May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "webradio"... May 24 23:24:24 ghettoblaster volumio[3774]: info: Loading plugin "i2s_dacs"... May 24 23:24:25 ghettoblaster volumio[3774]: info: Loading plugin "volumiodiscovery"... May 24 23:24:25 ghettoblaster volumio[3774]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 23:24:25 ghettoblaster volumio[3774]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:25 ghettoblaster node[3774]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 23:24:25 ghettoblaster volumio[3774]: *** WARNING *** For more information see May 24 23:24:25 ghettoblaster volumio[3774]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 23:24:25 ghettoblaster volumio[3774]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:25 ghettoblaster volumio[3774]: *** WARNING *** For more information see May 24 23:24:25 ghettoblaster node[3774]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:25 ghettoblaster node[3774]: *** WARNING *** For more information see May 24 23:24:25 ghettoblaster node[3774]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 23:24:25 ghettoblaster node[3774]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:25 ghettoblaster node[3774]: *** WARNING *** For more information see May 24 23:24:25 ghettoblaster volumio[3774]: info: Applying required configuration parameters for plugin volumiodiscovery May 24 23:24:25 ghettoblaster volumio[3774]: info: Discovery: Started advertising with name: GhettoBlaster May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:25 ghettoblaster volumio[3774]: info: Loading plugin "outputs"... May 24 23:24:25 ghettoblaster volumio[3774]: info: Loading plugin "albumart"... May 24 23:24:25 ghettoblaster volumio[3774]: info: Plugin example_plugin is not enabled May 24 23:24:25 ghettoblaster volumio[3774]: info: Loading plugin "inputs"... May 24 23:24:25 ghettoblaster volumio[3774]: info: Loading plugin "updater_comm"... May 24 23:24:25 ghettoblaster volumio[3774]: info: Plugin mpdemulation is not enabled May 24 23:24:25 ghettoblaster volumio[3774]: info: Loading plugin "rest_api"... May 24 23:24:25 ghettoblaster volumio[3774]: info: Loading plugin "websocket"... May 24 23:24:25 ghettoblaster volumio[3774]: info: Starting Socket.io Server version 2.3.0 May 24 23:24:25 ghettoblaster volumio[3774]: info: Loading plugin "touch_display"... May 24 23:24:25 ghettoblaster volumio[3774]: Forking 3 albumart workers May 24 23:24:25 ghettoblaster volumio[3774]: info: Applying required configuration parameters for plugin touch_display May 24 23:24:25 ghettoblaster volumio[3774]: info: Loading i18n strings for locale en May 24 23:24:25 ghettoblaster volumio[3774]: Updating browse sources language May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::initPlayerControls May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:25 ghettoblaster volumio[3774]: Express server listening on port 3000 May 24 23:24:25 ghettoblaster volumio[3774]: [Metrics] WebUI: 2s 763.35ms May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreStateMachine::resetVolumioState May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreStateMachine::getcurrentVolume May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::volumioRetrievevolume May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreStateMachine::pushState May 24 23:24:25 ghettoblaster volumio[3774]: info: CorePlayQueue::getTrack 0 May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 23:24:25 ghettoblaster volumio[3774]: info: CoreCommandRouter::volumioPushState May 24 23:24:25 ghettoblaster volumio[3774]: info: Cannot read play queue from file May 24 23:24:25 ghettoblaster volumio[3774]: info: Volumio Network Manager: Network status updated: 0 May 24 23:24:25 ghettoblaster volumio[3774]: Starting albumart workers May 24 23:24:25 ghettoblaster volumio[3774]: Starting albumart workers May 24 23:24:25 ghettoblaster volumio[3774]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 24 23:24:26 ghettoblaster volumio[3774]: Starting albumart workers May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreStateMachine::setRepeat null single undefined May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreStateMachine::pushState May 24 23:24:26 ghettoblaster volumio[3774]: info: CorePlayQueue::getTrack 0 May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::volumioPushState May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreStateMachine::setRandom null May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreStateMachine::pushState May 24 23:24:26 ghettoblaster volumio[3774]: info: CorePlayQueue::getTrack 0 May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::volumioPushState May 24 23:24:26 ghettoblaster volumio[3774]: info: Setting Device type: Raspberry PI May 24 23:24:26 ghettoblaster volumio[3774]: info: Completed loading Core Plugins May 24 23:24:26 ghettoblaster volumio[3774]: info: Preparing to generate the ALSA configuration file May 24 23:24:26 ghettoblaster volumio[3774]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 24 23:24:26 ghettoblaster volumio[3774]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 May 24 23:24:26 ghettoblaster volumio[3774]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 24 23:24:26 ghettoblaster volumio[3774]: info: Asound.conf file unchanged, so no further update is needed May 24 23:24:26 ghettoblaster volumio[3774]: info: Output device has changed, restarting MPD May 24 23:24:26 ghettoblaster volumio[3774]: info: Output device has changed, restarting Shairport Sync May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:26 ghettoblaster sudo[3865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 23:24:26 ghettoblaster sudo[3865]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:26 ghettoblaster sudo[3867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 23:24:26 ghettoblaster sudo[3865]: pam_unix(sudo:session): session closed for user root May 24 23:24:26 ghettoblaster sudo[3867]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:26 ghettoblaster systemd[1]: Stopping Music Player Daemon... May 24 23:24:26 ghettoblaster volumio[3774]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:26 ghettoblaster volumio[3774]: info: ___________ START PLUGINS ___________ May 24 23:24:26 ghettoblaster volumio[3774]: info: ControllerMpd::onStart: Initializing MPD May 24 23:24:26 ghettoblaster volumio[3774]: info: Creating MPD Configuration file May 24 23:24:26 ghettoblaster systemd[1]: mpd.service: Succeeded. May 24 23:24:26 ghettoblaster systemd[1]: Stopped Music Player Daemon. May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:26 ghettoblaster volumio[3774]: info: [1748121866317] CoreMusicLibrary::Adding element Media Servers May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:26 ghettoblaster sudo[3875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 23:24:26 ghettoblaster sudo[3875]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:26 ghettoblaster sudo[3875]: pam_unix(sudo:session): session closed for user root May 24 23:24:26 ghettoblaster systemd[1]: Starting Music Player Daemon... May 24 23:24:26 ghettoblaster sudo[3877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 23:24:26 ghettoblaster sudo[3877]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:26 ghettoblaster systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 24 23:24:26 ghettoblaster systemd[1]: mpd.service: Succeeded. May 24 23:24:26 ghettoblaster systemd[1]: Stopped Music Player Daemon. May 24 23:24:26 ghettoblaster systemd[1]: Starting Music Player Daemon... May 24 23:24:26 ghettoblaster volumio[3774]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:26 ghettoblaster sudo[3899]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 23:24:26 ghettoblaster sudo[3899]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:26 ghettoblaster sudo[3899]: pam_unix(sudo:session): session closed for user root May 24 23:24:26 ghettoblaster volumio[3774]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:26 ghettoblaster volumio[3774]: info: [1748121866477] CoreMusicLibrary::Adding element Last_100 May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:26 ghettoblaster volumio[3774]: info: [1748121866480] CoreMusicLibrary::Adding element Webradio May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:26 ghettoblaster volumio[3774]: info: Initializing BBC Radios May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: Loading i18n strings for locale en May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:26 ghettoblaster volumio[3774]: info: Volumio Calling Home May 24 23:24:26 ghettoblaster sudo[3927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service May 24 23:24:26 ghettoblaster sudo[3927]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:26 ghettoblaster sudo[3932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 24 23:24:26 ghettoblaster sudo[3932]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:26 ghettoblaster sudo[3927]: pam_unix(sudo:session): session closed for user root May 24 23:24:26 ghettoblaster sudo[3929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service May 24 23:24:26 ghettoblaster sudo[3929]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:26 ghettoblaster systemd[1]: Reloading. May 24 23:24:26 ghettoblaster volumio[3774]: Unhandled rejection Error: No sockets available, cannot start. May 24 23:24:26 ghettoblaster volumio[3774]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) May 24 23:24:26 ghettoblaster volumio[3774]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) May 24 23:24:26 ghettoblaster volumio[3774]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 May 24 23:24:26 ghettoblaster volumio[3774]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) May 24 23:24:26 ghettoblaster volumio[3774]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) May 24 23:24:26 ghettoblaster volumio[3774]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) May 24 23:24:26 ghettoblaster volumio[3774]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) May 24 23:24:26 ghettoblaster volumio[3774]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) May 24 23:24:26 ghettoblaster volumio[3774]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) May 24 23:24:26 ghettoblaster volumio[3774]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) May 24 23:24:26 ghettoblaster volumio[3774]: at HashMap. (/volumio/app/pluginmanager.js:496:31) May 24 23:24:26 ghettoblaster volumio[3774]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 24 23:24:26 ghettoblaster volumio[3774]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 24 23:24:26 ghettoblaster volumio[3774]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) May 24 23:24:26 ghettoblaster volumio[3774]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) May 24 23:24:26 ghettoblaster volumio[3774]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 24 23:24:26 ghettoblaster volumio[3774]: info: touch_display: No backlight interface detected. May 24 23:24:26 ghettoblaster volumio[3774]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 24 23:24:26 ghettoblaster volumio[3774]: info: MPD Permissions set May 24 23:24:26 ghettoblaster volumio[3774]: info: MPD Permissions set May 24 23:24:26 ghettoblaster volumio[3774]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:26 ghettoblaster volumio[3774]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 24 23:24:26 ghettoblaster sudo[3957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 24 23:24:26 ghettoblaster sudo[3957]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:26 ghettoblaster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 23:24:26 ghettoblaster sudo[3957]: pam_unix(sudo:session): session closed for user root May 24 23:24:26 ghettoblaster volumio[3774]: info: Starting Shairport Sync May 24 23:24:27 ghettoblaster sudo[3960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf May 24 23:24:27 ghettoblaster sudo[3960]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:27 ghettoblaster volumio[3774]: info: Starting Shairport Sync May 24 23:24:27 ghettoblaster sudo[3960]: pam_unix(sudo:session): session closed for user root May 24 23:24:27 ghettoblaster sudo[3963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:27 ghettoblaster sudo[3963]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:27 ghettoblaster volumio[3774]: info: Starting Shairport Sync May 24 23:24:27 ghettoblaster sudo[3965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:27 ghettoblaster sudo[3965]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:27 ghettoblaster volumio[3774]: info: CoreCommandRouter::volumioGetState May 24 23:24:27 ghettoblaster volumio[3774]: info: CorePlayQueue::getTrack 0 May 24 23:24:27 ghettoblaster sudo[3968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:27 ghettoblaster sudo[3968]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:27 ghettoblaster volumio[3774]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. May 24 23:24:27 ghettoblaster volumio[3774]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 24 23:24:27 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:27] [connect] Successful connection May 24 23:24:27 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748121867 101 May 24 23:24:27 ghettoblaster volumio[3774]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 24 23:24:27 ghettoblaster sudo[3932]: pam_unix(sudo:session): session closed for user root May 24 23:24:27 ghettoblaster volumio[3774]: info: touch_display: systemctl daemon-reload succeeded. May 24 23:24:27 ghettoblaster mpd[3904]: May 24 23:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 24 23:24:27 ghettoblaster sudo[3972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 24 23:24:27 ghettoblaster sudo[3972]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:27 ghettoblaster systemd[1]: Started Music Player Daemon. May 24 23:24:27 ghettoblaster sudo[3877]: pam_unix(sudo:session): session closed for user root May 24 23:24:27 ghettoblaster sudo[3867]: pam_unix(sudo:session): session closed for user root May 24 23:24:27 ghettoblaster systemd[1]: Reloading. May 24 23:24:27 ghettoblaster volumio[3774]: error: MPD error: The expression evaluated to a falsy value: May 24 23:24:27 ghettoblaster volumio[3774]: assert.ok(self.idling) May 24 23:24:27 ghettoblaster volumio[3774]: error: The expression evaluated to a falsy value: May 24 23:24:27 ghettoblaster volumio[3774]: assert.ok(self.idling) May 24 23:24:27 ghettoblaster volumio[3774]: info: MPD running with PID3904 May 24 23:24:27 ghettoblaster volumio[3774]: ,establishing connection May 24 23:24:27 ghettoblaster volumio[3774]: error: updateQueue error: null May 24 23:24:27 ghettoblaster volumio[3774]: error: updateQueue error: null May 24 23:24:27 ghettoblaster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 23:24:27 ghettoblaster sudo[3929]: pam_unix(sudo:session): session closed for user root May 24 23:24:27 ghettoblaster volumio[3774]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 24 23:24:27 ghettoblaster systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 24 23:24:27 ghettoblaster systemd[1]: shairport-sync.service: Succeeded. May 24 23:24:27 ghettoblaster systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 24 23:24:27 ghettoblaster systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 24 23:24:27 ghettoblaster sudo[3968]: pam_unix(sudo:session): session closed for user root May 24 23:24:27 ghettoblaster sudo[3963]: pam_unix(sudo:session): session closed for user root May 24 23:24:27 ghettoblaster sudo[3965]: pam_unix(sudo:session): session closed for user root May 24 23:24:27 ghettoblaster volumio[3774]: info: Shairport-Sync Started May 24 23:24:27 ghettoblaster sudo[3972]: pam_unix(sudo:session): session closed for user root May 24 23:24:27 ghettoblaster volumio[3774]: Error adding Membership: Error: addMembership EINVAL May 24 23:24:27 ghettoblaster volumio[3774]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 24 23:24:27 ghettoblaster volumio[3774]: info: touch_display: Volumio Kiosk started. May 24 23:24:27 ghettoblaster volumio[3774]: info: CoreCommandRouter::volumioGetState May 24 23:24:27 ghettoblaster volumio[3774]: info: CorePlayQueue::getTrack 0 May 24 23:24:27 ghettoblaster volumio[3774]: info: Completed starting Core Plugins May 24 23:24:27 ghettoblaster volumio[3774]: info: ------------------------------------------- May 24 23:24:27 ghettoblaster volumio[3774]: info: ----- MyVolumio plugins startup ---- May 24 23:24:27 ghettoblaster volumio[3774]: info: ------------------------------------------- May 24 23:24:27 ghettoblaster volumio[3774]: info: [MyVolumio PluginManager] Fetching plans data.... May 24 23:24:27 ghettoblaster volumio[3774]: info: CoreCommandRouter::volumioGetState May 24 23:24:27 ghettoblaster volumio[3774]: info: CorePlayQueue::getTrack 0 May 24 23:24:27 ghettoblaster volumio[3774]: info: Shairport-Sync Started May 24 23:24:27 ghettoblaster volumio[3774]: info: Shairport-Sync Started May 24 23:24:27 ghettoblaster volumio[3774]: info: touch_display: X display number found: 0 May 24 23:24:28 ghettoblaster volumio[3774]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 24 23:24:28 ghettoblaster volumio[3774]: info: touch_display: X display number found: 0 May 24 23:24:28 ghettoblaster volumio[3774]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 23:24:28 ghettoblaster volumio[3774]: TypeError: Cannot read property 'sort' of null May 24 23:24:28 ghettoblaster volumio[3774]: at /data/plugins/user_interface/touch_display/index.js:1599:48 May 24 23:24:28 ghettoblaster volumio[3774]: at ChildProcess.exithandler (child_process.js:299:7) May 24 23:24:28 ghettoblaster volumio[3774]: at ChildProcess.emit (events.js:315:20) May 24 23:24:28 ghettoblaster volumio[3774]: at maybeClose (internal/child_process.js:1048:16) May 24 23:24:28 ghettoblaster volumio[3774]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) May 24 23:24:28 ghettoblaster volumio[3774]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 23:24:28 ghettoblaster sudo[4009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 23:23 May 24 23:24:28 ghettoblaster sudo[4009]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:28 ghettoblaster sudo[4009]: pam_unix(sudo:session): session closed for user root May 24 23:24:28 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:28] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 24 23:24:28 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:28] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 24 23:24:28 ghettoblaster systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 24 23:24:28 ghettoblaster systemd[1]: volumio.service: Failed with result 'exit-code'. May 24 23:24:28 ghettoblaster systemd[1]: Started dynamicswap service. May 24 23:24:28 ghettoblaster systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 24 23:24:28 ghettoblaster systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. May 24 23:24:28 ghettoblaster systemd[1]: Stopped Volumio Backend Module. May 24 23:24:28 ghettoblaster systemd[1]: Started Volumio Backend Module. May 24 23:24:28 ghettoblaster systemd[1]: dynamicswap.service: Succeeded. May 24 23:24:29 ghettoblaster kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx May 24 23:24:29 ghettoblaster volumio[4017]: info: ------------------------------------------- May 24 23:24:29 ghettoblaster volumio[4017]: info: ----- Volumio3 ---- May 24 23:24:29 ghettoblaster volumio[4017]: info: ------------------------------------------- May 24 23:24:29 ghettoblaster volumio[4017]: info: ----- System startup ---- May 24 23:24:29 ghettoblaster volumio[4017]: info: ------------------------------------------- May 24 23:24:29 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Fetching time from Volumio... May 24 23:24:29 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Date not found in response May 24 23:24:29 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Retrying in 5 seconds... May 24 23:24:30 ghettoblaster volumio[4017]: info: MYVOLUMIO Environment detected May 24 23:24:30 ghettoblaster volumio[4017]: info: Plugin folders cleanup May 24 23:24:30 ghettoblaster volumio[4017]: info: Scanning into folder /volumio/app/plugins/ May 24 23:24:30 ghettoblaster volumio[4017]: info: Scanning category audio_interface May 24 23:24:30 ghettoblaster volumio[4017]: info: Scanning category miscellanea May 24 23:24:30 ghettoblaster volumio[4017]: info: Scanning category music_service May 24 23:24:30 ghettoblaster volumio[4017]: info: Scanning category plugins.json May 24 23:24:30 ghettoblaster volumio[4017]: info: Scanning category system_controller May 24 23:24:30 ghettoblaster volumio[4017]: info: Scanning category user_interface May 24 23:24:30 ghettoblaster volumio[4017]: info: Scanning into folder /data/plugins/ May 24 23:24:30 ghettoblaster volumio[4017]: info: Scanning category user_interface May 24 23:24:30 ghettoblaster volumio[4017]: info: Plugin folders cleanup completed May 24 23:24:30 ghettoblaster volumio[4017]: info: ------------------------------------------- May 24 23:24:30 ghettoblaster volumio[4017]: info: ----- Core plugins startup ---- May 24 23:24:30 ghettoblaster volumio[4017]: info: ------------------------------------------- May 24 23:24:30 ghettoblaster volumio[4017]: info: Loading plugins from folder /volumio/app/plugins/ May 24 23:24:30 ghettoblaster volumio[4017]: info: Adding plugin upnp to MyMusic Plugins May 24 23:24:30 ghettoblaster volumio[4017]: info: Adding plugin airplay_emulation to MyMusic Plugins May 24 23:24:30 ghettoblaster volumio[4017]: info: Adding plugin upnp_browser to MyMusic Plugins May 24 23:24:30 ghettoblaster volumio[4017]: info: Loading plugins from folder /data/plugins/ May 24 23:24:30 ghettoblaster volumio[4017]: info: Loading plugin "system"... May 24 23:24:30 ghettoblaster volumio[4017]: info: Loading plugin "appearance"... May 24 23:24:30 ghettoblaster ifplugd(eth0)[787]: Link beat detected. May 24 23:24:30 ghettoblaster ifplugd(eth0)[787]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 24 23:24:30 ghettoblaster dhcpcd[4066]: DUID 00:01:00:01:2f:a4:b0:2b:2c:cf:67:ad:47:ed May 24 23:24:30 ghettoblaster dhcpcd[4066]: eth0: IAID 67:ad:47:ec May 24 23:24:30 ghettoblaster dhcpcd[4066]: eth0: adding address fe80::2ecf:67ff:fead:47ec May 24 23:24:30 ghettoblaster dhcpcd[4066]: ipv6_addaddr1: Permission denied May 24 23:24:30 ghettoblaster ifplugd(eth0)[787]: client: DUID 00:01:00:01:2f:a4:b0:2b:2c:cf:67:ad:47:ed May 24 23:24:30 ghettoblaster ifplugd(eth0)[787]: client: eth0: IAID 67:ad:47:ec May 24 23:24:30 ghettoblaster ifplugd(eth0)[787]: client: eth0: adding address fe80::2ecf:67ff:fead:47ec May 24 23:24:30 ghettoblaster ifplugd(eth0)[787]: client: ipv6_addaddr1: Permission denied May 24 23:24:30 ghettoblaster dhcpcd[4066]: eth0: soliciting a DHCP lease May 24 23:24:30 ghettoblaster ifplugd(eth0)[787]: client: eth0: soliciting a DHCP lease May 24 23:24:30 ghettoblaster dhcpcd[4066]: eth0: soliciting an IPv6 router May 24 23:24:30 ghettoblaster ifplugd(eth0)[787]: client: eth0: soliciting an IPv6 router May 24 23:24:30 ghettoblaster volumio[4017]: info: Loading plugin "network"... May 24 23:24:30 ghettoblaster volumio[4017]: info: Refreshing Cached IP Addresses May 24 23:24:30 ghettoblaster sudo[4070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 23:24:30 ghettoblaster sudo[4070]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:30 ghettoblaster sudo[4072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 23:24:30 ghettoblaster sudo[4072]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:30 ghettoblaster sudo[4070]: pam_unix(sudo:session): session closed for user root May 24 23:24:30 ghettoblaster volumio[4017]: info: Loading plugin "services"... May 24 23:24:30 ghettoblaster volumio[4017]: info: Loading plugin "alsa_controller"... May 24 23:24:30 ghettoblaster sudo[4072]: pam_unix(sudo:session): session closed for user root May 24 23:24:30 ghettoblaster volumio[4017]: warn: Unable to locate the audio output device HiFiBerry DAC. Please configure a valid output device. May 24 23:24:30 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:30 ghettoblaster volumio[4017]: info: Loading plugin "wizard"... May 24 23:24:30 ghettoblaster volumio[4017]: info: Loading plugin "networkfs"... May 24 23:24:30 ghettoblaster volumio[4017]: info: Starting Udev Watcher for removable devices May 24 23:24:30 ghettoblaster volumio[4017]: info: Ignoring mount for partition: boot May 24 23:24:30 ghettoblaster volumio[4017]: info: Ignoring mount for partition: volumio May 24 23:24:30 ghettoblaster volumio[4017]: info: Ignoring mount for partition: volumio_data May 24 23:24:30 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:30 ghettoblaster volumio[4017]: info: Loading plugin "volumio_command_line_client"... May 24 23:24:30 ghettoblaster volumio[4017]: info: Loading plugin "upnp"... May 24 23:24:30 ghettoblaster volumio[4017]: info: [1748121870843] Starting Upmpd Daemon May 24 23:24:30 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:30 ghettoblaster volumio[4017]: info: Loading plugin "my_music"... May 24 23:24:30 ghettoblaster volumio[4017]: info: Loading plugin "mpd"... May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading plugin "upnp_browser"... May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading plugin "alarm-clock"... May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading plugin "airplay_emulation"... May 24 23:24:31 ghettoblaster volumio[4017]: info: Starting Shairport Sync May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading plugin "last_100"... May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading plugin "webradio"... May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading plugin "i2s_dacs"... May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading plugin "volumiodiscovery"... May 24 23:24:31 ghettoblaster volumio[4017]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 23:24:31 ghettoblaster volumio[4017]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:31 ghettoblaster node[4017]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 23:24:31 ghettoblaster volumio[4017]: *** WARNING *** For more information see May 24 23:24:31 ghettoblaster volumio[4017]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 23:24:31 ghettoblaster volumio[4017]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:31 ghettoblaster volumio[4017]: *** WARNING *** For more information see May 24 23:24:31 ghettoblaster node[4017]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:31 ghettoblaster node[4017]: *** WARNING *** For more information see May 24 23:24:31 ghettoblaster node[4017]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 23:24:31 ghettoblaster node[4017]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:31 ghettoblaster node[4017]: *** WARNING *** For more information see May 24 23:24:31 ghettoblaster volumio[4017]: info: Applying required configuration parameters for plugin volumiodiscovery May 24 23:24:31 ghettoblaster volumio[4017]: info: Discovery: Started advertising with name: GhettoBlaster May 24 23:24:31 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading plugin "outputs"... May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading plugin "albumart"... May 24 23:24:31 ghettoblaster volumio[4017]: info: Plugin example_plugin is not enabled May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading plugin "inputs"... May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading plugin "updater_comm"... May 24 23:24:31 ghettoblaster volumio[4017]: info: Plugin mpdemulation is not enabled May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading plugin "rest_api"... May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading plugin "websocket"... May 24 23:24:31 ghettoblaster volumio[4017]: info: Starting Socket.io Server version 2.3.0 May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading plugin "touch_display"... May 24 23:24:31 ghettoblaster volumio[4017]: Forking 3 albumart workers May 24 23:24:31 ghettoblaster volumio[4017]: info: Applying required configuration parameters for plugin touch_display May 24 23:24:31 ghettoblaster volumio[4017]: info: Loading i18n strings for locale en May 24 23:24:31 ghettoblaster volumio[4017]: Updating browse sources language May 24 23:24:31 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::initPlayerControls May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: Express server listening on port 3000 May 24 23:24:32 ghettoblaster volumio[4017]: [Metrics] WebUI: 2s 694.63ms May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreStateMachine::resetVolumioState May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreStateMachine::getcurrentVolume May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::volumioRetrievevolume May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreStateMachine::pushState May 24 23:24:32 ghettoblaster volumio[4017]: info: CorePlayQueue::getTrack 0 May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::volumioPushState May 24 23:24:32 ghettoblaster volumio[4017]: info: Cannot read play queue from file May 24 23:24:32 ghettoblaster volumio[4017]: info: Volumio Network Manager: Network status updated: 0 May 24 23:24:32 ghettoblaster volumio[4017]: Starting albumart workers May 24 23:24:32 ghettoblaster volumio[4017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 24 23:24:32 ghettoblaster volumio[4017]: Starting albumart workers May 24 23:24:32 ghettoblaster volumio[4017]: Starting albumart workers May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreStateMachine::setRepeat null single undefined May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreStateMachine::pushState May 24 23:24:32 ghettoblaster volumio[4017]: info: CorePlayQueue::getTrack 0 May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::volumioPushState May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreStateMachine::setRandom null May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreStateMachine::pushState May 24 23:24:32 ghettoblaster volumio[4017]: info: CorePlayQueue::getTrack 0 May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::volumioPushState May 24 23:24:32 ghettoblaster volumio[4017]: info: Setting Device type: Raspberry PI May 24 23:24:32 ghettoblaster volumio[4017]: info: Completed loading Core Plugins May 24 23:24:32 ghettoblaster volumio[4017]: info: Preparing to generate the ALSA configuration file May 24 23:24:32 ghettoblaster volumio[4017]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 24 23:24:32 ghettoblaster volumio[4017]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 May 24 23:24:32 ghettoblaster volumio[4017]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 24 23:24:32 ghettoblaster volumio[4017]: info: Asound.conf file unchanged, so no further update is needed May 24 23:24:32 ghettoblaster volumio[4017]: info: Output device has changed, restarting MPD May 24 23:24:32 ghettoblaster sudo[4130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 23:24:32 ghettoblaster volumio[4017]: info: Output device has changed, restarting Shairport Sync May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:32 ghettoblaster sudo[4130]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:32 ghettoblaster sudo[4130]: pam_unix(sudo:session): session closed for user root May 24 23:24:32 ghettoblaster sudo[4132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 23:24:32 ghettoblaster sudo[4132]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:32 ghettoblaster systemd[1]: Stopping Music Player Daemon... May 24 23:24:32 ghettoblaster volumio[4017]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:32 ghettoblaster volumio[4017]: info: ___________ START PLUGINS ___________ May 24 23:24:32 ghettoblaster volumio[4017]: info: ControllerMpd::onStart: Initializing MPD May 24 23:24:32 ghettoblaster volumio[4017]: info: Creating MPD Configuration file May 24 23:24:32 ghettoblaster systemd[1]: mpd.service: Succeeded. May 24 23:24:32 ghettoblaster systemd[1]: Stopped Music Player Daemon. May 24 23:24:32 ghettoblaster sudo[4140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 23:24:32 ghettoblaster sudo[4140]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:32 ghettoblaster sudo[4140]: pam_unix(sudo:session): session closed for user root May 24 23:24:32 ghettoblaster systemd[1]: Starting Music Player Daemon... May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:32 ghettoblaster volumio[4017]: info: [1748121872657] CoreMusicLibrary::Adding element Media Servers May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:32 ghettoblaster sudo[4143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 23:24:32 ghettoblaster sudo[4143]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:32 ghettoblaster systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 24 23:24:32 ghettoblaster systemd[1]: mpd.service: Succeeded. May 24 23:24:32 ghettoblaster systemd[1]: Stopped Music Player Daemon. May 24 23:24:32 ghettoblaster systemd[1]: Starting Music Player Daemon... May 24 23:24:32 ghettoblaster volumio[4017]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:32 ghettoblaster sudo[4156]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 23:24:32 ghettoblaster sudo[4156]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:32 ghettoblaster sudo[4156]: pam_unix(sudo:session): session closed for user root May 24 23:24:32 ghettoblaster volumio[4017]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:32 ghettoblaster volumio[4017]: info: [1748121872822] CoreMusicLibrary::Adding element Last_100 May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:32 ghettoblaster volumio[4017]: info: [1748121872826] CoreMusicLibrary::Adding element Webradio May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:32 ghettoblaster volumio[4017]: info: Initializing BBC Radios May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:32 ghettoblaster volumio[4017]: info: Loading i18n strings for locale en May 24 23:24:32 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:32 ghettoblaster volumio[4017]: info: Volumio Calling Home May 24 23:24:33 ghettoblaster sudo[4192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service May 24 23:24:33 ghettoblaster sudo[4192]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:33 ghettoblaster sudo[4194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service May 24 23:24:33 ghettoblaster sudo[4194]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:33 ghettoblaster sudo[4198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 24 23:24:33 ghettoblaster sudo[4198]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:33 ghettoblaster sudo[4192]: pam_unix(sudo:session): session closed for user root May 24 23:24:33 ghettoblaster systemd[1]: Reloading. May 24 23:24:33 ghettoblaster volumio[4017]: Unhandled rejection Error: No sockets available, cannot start. May 24 23:24:33 ghettoblaster volumio[4017]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) May 24 23:24:33 ghettoblaster volumio[4017]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) May 24 23:24:33 ghettoblaster volumio[4017]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 May 24 23:24:33 ghettoblaster volumio[4017]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) May 24 23:24:33 ghettoblaster volumio[4017]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) May 24 23:24:33 ghettoblaster volumio[4017]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) May 24 23:24:33 ghettoblaster volumio[4017]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) May 24 23:24:33 ghettoblaster volumio[4017]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) May 24 23:24:33 ghettoblaster volumio[4017]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) May 24 23:24:33 ghettoblaster volumio[4017]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) May 24 23:24:33 ghettoblaster volumio[4017]: at HashMap. (/volumio/app/pluginmanager.js:496:31) May 24 23:24:33 ghettoblaster volumio[4017]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 24 23:24:33 ghettoblaster volumio[4017]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 24 23:24:33 ghettoblaster volumio[4017]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) May 24 23:24:33 ghettoblaster volumio[4017]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) May 24 23:24:33 ghettoblaster volumio[4017]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 24 23:24:33 ghettoblaster volumio[4017]: info: touch_display: No backlight interface detected. May 24 23:24:33 ghettoblaster volumio[4017]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 24 23:24:33 ghettoblaster volumio[4017]: info: MPD Permissions set May 24 23:24:33 ghettoblaster volumio[4017]: info: MPD Permissions set May 24 23:24:33 ghettoblaster volumio[4017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 24 23:24:33 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:33 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:33 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:33 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:33 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:33 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:33 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:33 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:33 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:33 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:33 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:33 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:33 ghettoblaster volumio[4017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:33 ghettoblaster volumio[4017]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 24 23:24:33 ghettoblaster sudo[4222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 24 23:24:33 ghettoblaster sudo[4222]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:33 ghettoblaster volumio[4017]: info: Starting Shairport Sync May 24 23:24:33 ghettoblaster sudo[4222]: pam_unix(sudo:session): session closed for user root May 24 23:24:33 ghettoblaster sudo[4224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf May 24 23:24:33 ghettoblaster volumio[4017]: info: Starting Shairport Sync May 24 23:24:33 ghettoblaster sudo[4224]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:33 ghettoblaster sudo[4227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:33 ghettoblaster sudo[4227]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:33 ghettoblaster sudo[4224]: pam_unix(sudo:session): session closed for user root May 24 23:24:33 ghettoblaster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 23:24:33 ghettoblaster volumio[4017]: info: Starting Shairport Sync May 24 23:24:33 ghettoblaster sudo[4231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:33 ghettoblaster sudo[4231]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:33 ghettoblaster volumio[4017]: info: CoreCommandRouter::volumioGetState May 24 23:24:33 ghettoblaster volumio[4017]: info: CorePlayQueue::getTrack 0 May 24 23:24:33 ghettoblaster sudo[4233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:33 ghettoblaster sudo[4233]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:33 ghettoblaster volumio[4017]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. May 24 23:24:33 ghettoblaster volumio[4017]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 24 23:24:33 ghettoblaster dhcpcd[4066]: eth0: offered 192.168.1.122 from 192.168.1.254 May 24 23:24:33 ghettoblaster ifplugd(eth0)[787]: client: eth0: offered 192.168.1.122 from 192.168.1.254 May 24 23:24:33 ghettoblaster dhcpcd[4066]: eth0: probing address 192.168.1.122/24 May 24 23:24:33 ghettoblaster ifplugd(eth0)[787]: client: eth0: probing address 192.168.1.122/24 May 24 23:24:33 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:33] [connect] Successful connection May 24 23:24:33 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748121873 101 May 24 23:24:33 ghettoblaster volumio[4017]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 24 23:24:33 ghettoblaster mpd[4161]: May 24 23:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 24 23:24:33 ghettoblaster systemd[1]: Started Music Player Daemon. May 24 23:24:33 ghettoblaster sudo[4194]: pam_unix(sudo:session): session closed for user root May 24 23:24:33 ghettoblaster sudo[4132]: pam_unix(sudo:session): session closed for user root May 24 23:24:33 ghettoblaster volumio[4017]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 24 23:24:33 ghettoblaster sudo[4143]: pam_unix(sudo:session): session closed for user root May 24 23:24:33 ghettoblaster systemd[1]: Reloading. May 24 23:24:33 ghettoblaster volumio[4017]: error: MPD error: The expression evaluated to a falsy value: May 24 23:24:33 ghettoblaster volumio[4017]: assert.ok(self.idling) May 24 23:24:33 ghettoblaster volumio[4017]: error: The expression evaluated to a falsy value: May 24 23:24:33 ghettoblaster volumio[4017]: assert.ok(self.idling) May 24 23:24:33 ghettoblaster volumio[4017]: error: updateQueue error: null May 24 23:24:33 ghettoblaster volumio[4017]: info: MPD running with PID4161 May 24 23:24:33 ghettoblaster volumio[4017]: ,establishing connection May 24 23:24:33 ghettoblaster volumio[4017]: error: updateQueue error: null May 24 23:24:33 ghettoblaster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 23:24:34 ghettoblaster sudo[4198]: pam_unix(sudo:session): session closed for user root May 24 23:24:34 ghettoblaster volumio[4017]: info: touch_display: systemctl daemon-reload succeeded. May 24 23:24:34 ghettoblaster systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 24 23:24:34 ghettoblaster systemd[1]: shairport-sync.service: Succeeded. May 24 23:24:34 ghettoblaster systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 24 23:24:34 ghettoblaster systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 24 23:24:34 ghettoblaster sudo[4254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 24 23:24:34 ghettoblaster sudo[4227]: pam_unix(sudo:session): session closed for user root May 24 23:24:34 ghettoblaster sudo[4254]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:34 ghettoblaster sudo[4233]: pam_unix(sudo:session): session closed for user root May 24 23:24:34 ghettoblaster sudo[4231]: pam_unix(sudo:session): session closed for user root May 24 23:24:34 ghettoblaster volumio[4017]: info: Shairport-Sync Started May 24 23:24:34 ghettoblaster volumio[4017]: Error adding Membership: Error: addMembership EINVAL May 24 23:24:34 ghettoblaster volumio[4017]: info: Shairport-Sync Started May 24 23:24:34 ghettoblaster volumio[4017]: info: Shairport-Sync Started May 24 23:24:34 ghettoblaster sudo[4254]: pam_unix(sudo:session): session closed for user root May 24 23:24:34 ghettoblaster volumio[4017]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 24 23:24:34 ghettoblaster volumio[4017]: info: touch_display: Volumio Kiosk started. May 24 23:24:34 ghettoblaster volumio[4017]: info: CoreCommandRouter::volumioGetState May 24 23:24:34 ghettoblaster volumio[4017]: info: CorePlayQueue::getTrack 0 May 24 23:24:34 ghettoblaster volumio[4017]: info: Completed starting Core Plugins May 24 23:24:34 ghettoblaster volumio[4017]: info: ------------------------------------------- May 24 23:24:34 ghettoblaster volumio[4017]: info: ----- MyVolumio plugins startup ---- May 24 23:24:34 ghettoblaster volumio[4017]: info: ------------------------------------------- May 24 23:24:34 ghettoblaster volumio[4017]: info: [MyVolumio PluginManager] Fetching plans data.... May 24 23:24:34 ghettoblaster volumio[4017]: info: CoreCommandRouter::volumioGetState May 24 23:24:34 ghettoblaster volumio[4017]: info: CorePlayQueue::getTrack 0 May 24 23:24:34 ghettoblaster volumio[4017]: info: touch_display: X display number found: 0 May 24 23:24:34 ghettoblaster volumio[4017]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 24 23:24:34 ghettoblaster volumio[4017]: info: touch_display: X display number found: 0 May 24 23:24:34 ghettoblaster volumio[4017]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 23:24:34 ghettoblaster volumio[4017]: TypeError: Cannot read property 'sort' of null May 24 23:24:34 ghettoblaster volumio[4017]: at /data/plugins/user_interface/touch_display/index.js:1599:48 May 24 23:24:34 ghettoblaster volumio[4017]: at ChildProcess.exithandler (child_process.js:299:7) May 24 23:24:34 ghettoblaster volumio[4017]: at ChildProcess.emit (events.js:315:20) May 24 23:24:34 ghettoblaster volumio[4017]: at maybeClose (internal/child_process.js:1048:16) May 24 23:24:34 ghettoblaster volumio[4017]: at Socket. (internal/child_process.js:439:11) May 24 23:24:34 ghettoblaster volumio[4017]: at Socket.emit (events.js:315:20) May 24 23:24:34 ghettoblaster volumio[4017]: at Pipe. (net.js:673:12) May 24 23:24:34 ghettoblaster volumio[4017]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 23:24:34 ghettoblaster sudo[4274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 23:23 May 24 23:24:34 ghettoblaster sudo[4274]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:34 ghettoblaster sudo[4274]: pam_unix(sudo:session): session closed for user root May 24 23:24:34 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Fetching time from Volumio... May 24 23:24:34 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:34] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 24 23:24:34 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:34] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 24 23:24:34 ghettoblaster systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 24 23:24:34 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Date not found in response May 24 23:24:34 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Retrying in 5 seconds... May 24 23:24:34 ghettoblaster systemd[1]: volumio.service: Failed with result 'exit-code'. May 24 23:24:35 ghettoblaster systemd[1]: Started dynamicswap service. May 24 23:24:35 ghettoblaster systemd[1]: dynamicswap.service: Succeeded. May 24 23:24:35 ghettoblaster systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 24 23:24:35 ghettoblaster systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. May 24 23:24:35 ghettoblaster systemd[1]: Stopped Volumio Backend Module. May 24 23:24:35 ghettoblaster systemd[1]: Started Volumio Backend Module. May 24 23:24:35 ghettoblaster systemd[1]: Started dynamicswap service. May 24 23:24:35 ghettoblaster systemd[1]: dynamicswap.service: Succeeded. May 24 23:24:36 ghettoblaster volumio[4292]: info: ------------------------------------------- May 24 23:24:36 ghettoblaster volumio[4292]: info: ----- Volumio3 ---- May 24 23:24:36 ghettoblaster volumio[4292]: info: ------------------------------------------- May 24 23:24:36 ghettoblaster volumio[4292]: info: ----- System startup ---- May 24 23:24:36 ghettoblaster volumio[4292]: info: ------------------------------------------- May 24 23:24:36 ghettoblaster volumio[4292]: info: MYVOLUMIO Environment detected May 24 23:24:36 ghettoblaster volumio[4292]: info: Plugin folders cleanup May 24 23:24:36 ghettoblaster volumio[4292]: info: Scanning into folder /volumio/app/plugins/ May 24 23:24:36 ghettoblaster volumio[4292]: info: Scanning category audio_interface May 24 23:24:36 ghettoblaster volumio[4292]: info: Scanning category miscellanea May 24 23:24:36 ghettoblaster volumio[4292]: info: Scanning category music_service May 24 23:24:36 ghettoblaster volumio[4292]: info: Scanning category plugins.json May 24 23:24:36 ghettoblaster volumio[4292]: info: Scanning category system_controller May 24 23:24:36 ghettoblaster volumio[4292]: info: Scanning category user_interface May 24 23:24:36 ghettoblaster volumio[4292]: info: Scanning into folder /data/plugins/ May 24 23:24:36 ghettoblaster volumio[4292]: info: Scanning category user_interface May 24 23:24:36 ghettoblaster volumio[4292]: info: Plugin folders cleanup completed May 24 23:24:36 ghettoblaster volumio[4292]: info: ------------------------------------------- May 24 23:24:36 ghettoblaster volumio[4292]: info: ----- Core plugins startup ---- May 24 23:24:36 ghettoblaster volumio[4292]: info: ------------------------------------------- May 24 23:24:36 ghettoblaster volumio[4292]: info: Loading plugins from folder /volumio/app/plugins/ May 24 23:24:36 ghettoblaster volumio[4292]: info: Adding plugin upnp to MyMusic Plugins May 24 23:24:36 ghettoblaster volumio[4292]: info: Adding plugin airplay_emulation to MyMusic Plugins May 24 23:24:36 ghettoblaster volumio[4292]: info: Adding plugin upnp_browser to MyMusic Plugins May 24 23:24:36 ghettoblaster volumio[4292]: info: Loading plugins from folder /data/plugins/ May 24 23:24:36 ghettoblaster volumio[4292]: info: Loading plugin "system"... May 24 23:24:36 ghettoblaster volumio[4292]: info: Loading plugin "appearance"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "network"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Refreshing Cached IP Addresses May 24 23:24:37 ghettoblaster sudo[4319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 23:24:37 ghettoblaster sudo[4319]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:37 ghettoblaster sudo[4319]: pam_unix(sudo:session): session closed for user root May 24 23:24:37 ghettoblaster sudo[4321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 23:24:37 ghettoblaster sudo[4321]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "services"... May 24 23:24:37 ghettoblaster sudo[4321]: pam_unix(sudo:session): session closed for user root May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "alsa_controller"... May 24 23:24:37 ghettoblaster volumio[4292]: warn: Unable to locate the audio output device HiFiBerry DAC. Please configure a valid output device. May 24 23:24:37 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "wizard"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "networkfs"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Starting Udev Watcher for removable devices May 24 23:24:37 ghettoblaster volumio[4292]: info: Ignoring mount for partition: boot May 24 23:24:37 ghettoblaster volumio[4292]: info: Ignoring mount for partition: volumio May 24 23:24:37 ghettoblaster volumio[4292]: info: Ignoring mount for partition: volumio_data May 24 23:24:37 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "volumio_command_line_client"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "upnp"... May 24 23:24:37 ghettoblaster volumio[4292]: info: [1748121877221] Starting Upmpd Daemon May 24 23:24:37 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "my_music"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "mpd"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "upnp_browser"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "alarm-clock"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "airplay_emulation"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Starting Shairport Sync May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "last_100"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "webradio"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "i2s_dacs"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "volumiodiscovery"... May 24 23:24:37 ghettoblaster volumio[4292]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 23:24:37 ghettoblaster volumio[4292]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:37 ghettoblaster volumio[4292]: *** WARNING *** For more information see May 24 23:24:37 ghettoblaster node[4292]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 23:24:37 ghettoblaster volumio[4292]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 23:24:37 ghettoblaster volumio[4292]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:37 ghettoblaster volumio[4292]: *** WARNING *** For more information see May 24 23:24:37 ghettoblaster node[4292]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:37 ghettoblaster node[4292]: *** WARNING *** For more information see May 24 23:24:37 ghettoblaster node[4292]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 23:24:37 ghettoblaster node[4292]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 23:24:37 ghettoblaster node[4292]: *** WARNING *** For more information see May 24 23:24:37 ghettoblaster volumio[4292]: info: Applying required configuration parameters for plugin volumiodiscovery May 24 23:24:37 ghettoblaster volumio[4292]: info: Discovery: Started advertising with name: GhettoBlaster May 24 23:24:37 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "outputs"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "albumart"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Plugin example_plugin is not enabled May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "inputs"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "updater_comm"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Plugin mpdemulation is not enabled May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "rest_api"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "websocket"... May 24 23:24:37 ghettoblaster volumio[4292]: info: Starting Socket.io Server version 2.3.0 May 24 23:24:37 ghettoblaster volumio[4292]: info: Loading plugin "touch_display"... May 24 23:24:38 ghettoblaster volumio[4292]: Forking 3 albumart workers May 24 23:24:38 ghettoblaster volumio[4292]: info: Applying required configuration parameters for plugin touch_display May 24 23:24:38 ghettoblaster volumio[4292]: info: Loading i18n strings for locale en May 24 23:24:38 ghettoblaster volumio[4292]: Updating browse sources language May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::initPlayerControls May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:38 ghettoblaster volumio[4292]: Express server listening on port 3000 May 24 23:24:38 ghettoblaster volumio[4292]: [Metrics] WebUI: 2s 723.01ms May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreStateMachine::resetVolumioState May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreStateMachine::getcurrentVolume May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioRetrievevolume May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreStateMachine::pushState May 24 23:24:38 ghettoblaster volumio[4292]: info: CorePlayQueue::getTrack 0 May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioPushState May 24 23:24:38 ghettoblaster volumio[4292]: info: Cannot read play queue from file May 24 23:24:38 ghettoblaster volumio[4292]: info: Volumio Network Manager: Network status updated: 0 May 24 23:24:38 ghettoblaster volumio[4292]: Starting albumart workers May 24 23:24:38 ghettoblaster volumio[4292]: Starting albumart workers May 24 23:24:38 ghettoblaster volumio[4292]: Starting albumart workers May 24 23:24:38 ghettoblaster volumio[4292]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:38 ghettoblaster dhcpcd[4066]: eth0: leased 192.168.1.122 for 7200 seconds May 24 23:24:38 ghettoblaster ifplugd(eth0)[787]: client: eth0: leased 192.168.1.122 for 7200 seconds May 24 23:24:38 ghettoblaster avahi-daemon[625]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.122. May 24 23:24:38 ghettoblaster avahi-daemon[625]: New relevant interface eth0.IPv4 for mDNS. May 24 23:24:38 ghettoblaster dhcpcd[4066]: eth0: adding route to 192.168.1.0/24 May 24 23:24:38 ghettoblaster avahi-daemon[625]: Registering new address record for 192.168.1.122 on eth0.IPv4. May 24 23:24:38 ghettoblaster dhcpcd[4066]: eth0: adding default route via 192.168.1.254 May 24 23:24:38 ghettoblaster ifplugd(eth0)[787]: client: eth0: adding route to 192.168.1.0/24 May 24 23:24:38 ghettoblaster ifplugd(eth0)[787]: client: eth0: adding default route via 192.168.1.254 May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreStateMachine::setRepeat null single undefined May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreStateMachine::pushState May 24 23:24:38 ghettoblaster volumio[4292]: info: CorePlayQueue::getTrack 0 May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioPushState May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreStateMachine::setRandom null May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreStateMachine::pushState May 24 23:24:38 ghettoblaster volumio[4292]: info: CorePlayQueue::getTrack 0 May 24 23:24:38 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioPushState May 24 23:24:38 ghettoblaster volumio[4292]: info: Setting Device type: Raspberry PI May 24 23:24:38 ghettoblaster volumio[4292]: info: Completed loading Core Plugins May 24 23:24:38 ghettoblaster volumio[4292]: info: Preparing to generate the ALSA configuration file May 24 23:24:38 ghettoblaster dhcpcd[4066]: forked to background, child pid 4402 May 24 23:24:38 ghettoblaster ifplugd(eth0)[787]: client: forked to background, child pid 4402 May 24 23:24:38 ghettoblaster volumio[4292]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 24 23:24:38 ghettoblaster volumio[4292]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 May 24 23:24:38 ghettoblaster volumio[4292]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 24 23:24:38 ghettoblaster volumio[4292]: info: Asound.conf file unchanged, so no further update is needed May 24 23:24:38 ghettoblaster volumio[4292]: info: Output device has changed, restarting MPD May 24 23:24:38 ghettoblaster sudo[4424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 23:24:38 ghettoblaster volumio[4292]: info: Output device has changed, restarting Shairport Sync May 24 23:24:39 ghettoblaster sudo[4424]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:39 ghettoblaster sudo[4424]: pam_unix(sudo:session): session closed for user root May 24 23:24:39 ghettoblaster sudo[4433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 23:24:39 ghettoblaster sudo[4433]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:39 ghettoblaster systemd[1]: Stopping Music Player Daemon... May 24 23:24:39 ghettoblaster volumio[4292]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:39 ghettoblaster systemd[1]: mpd.service: Succeeded. May 24 23:24:39 ghettoblaster systemd[1]: Stopped Music Player Daemon. May 24 23:24:39 ghettoblaster volumio[4292]: info: ___________ START PLUGINS ___________ May 24 23:24:39 ghettoblaster ifplugd(eth0)[787]: Program executed successfully. May 24 23:24:39 ghettoblaster volumio[4292]: info: ControllerMpd::onStart: Initializing MPD May 24 23:24:39 ghettoblaster volumio[4292]: info: Creating MPD Configuration file May 24 23:24:39 ghettoblaster systemd[1]: Starting Music Player Daemon... May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:39 ghettoblaster volumio[4292]: info: [1748121879150] CoreMusicLibrary::Adding element Media Servers May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:39 ghettoblaster sudo[4441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 23:24:39 ghettoblaster sudo[4441]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:39 ghettoblaster sudo[4441]: pam_unix(sudo:session): session closed for user root May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster sudo[4443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 23:24:39 ghettoblaster sudo[4443]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:39 ghettoblaster systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 24 23:24:39 ghettoblaster systemd[1]: mpd.service: Succeeded. May 24 23:24:39 ghettoblaster systemd[1]: Stopped Music Player Daemon. May 24 23:24:39 ghettoblaster systemd[1]: Starting Music Player Daemon... May 24 23:24:39 ghettoblaster volumio[4292]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:39 ghettoblaster sudo[4457]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 23:24:39 ghettoblaster sudo[4457]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:39 ghettoblaster sudo[4457]: pam_unix(sudo:session): session closed for user root May 24 23:24:39 ghettoblaster volumio[4292]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:39 ghettoblaster volumio[4292]: info: [1748121879331] CoreMusicLibrary::Adding element Last_100 May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 23:24:39 ghettoblaster volumio[4292]: info: [1748121879333] CoreMusicLibrary::Adding element Webradio May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:39 ghettoblaster volumio[4292]: info: Initializing BBC Radios May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: Loading i18n strings for locale en May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 23:24:39 ghettoblaster volumio[4292]: info: Volumio Calling Home May 24 23:24:39 ghettoblaster sudo[4493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service May 24 23:24:39 ghettoblaster sudo[4493]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:39 ghettoblaster sudo[4495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service May 24 23:24:39 ghettoblaster sudo[4495]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:39 ghettoblaster sudo[4499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 24 23:24:39 ghettoblaster sudo[4499]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:39 ghettoblaster systemd[1]: Reloading. May 24 23:24:39 ghettoblaster volumio[4292]: info: touch_display: No backlight interface detected. May 24 23:24:39 ghettoblaster volumio[4292]: info: MPD Permissions set May 24 23:24:39 ghettoblaster volumio[4292]: verbose: New Socket.io Connection to 192.168.1.122 from 192.168.1.190 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 24 23:24:39 ghettoblaster volumio[4292]: verbose: New Socket.io Connection to 192.168.1.122 from 192.168.1.190 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 24 23:24:39 ghettoblaster volumio[4292]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 24 23:24:39 ghettoblaster volumio[4292]: info: Volumio called home May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 23:24:39 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 23:24:39 ghettoblaster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 23:24:39 ghettoblaster volumio[4292]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 24 23:24:39 ghettoblaster sudo[4524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 24 23:24:39 ghettoblaster sudo[4524]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:39 ghettoblaster sudo[4524]: pam_unix(sudo:session): session closed for user root May 24 23:24:39 ghettoblaster sudo[4526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf May 24 23:24:39 ghettoblaster sudo[4526]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:39 ghettoblaster sudo[4526]: pam_unix(sudo:session): session closed for user root May 24 23:24:39 ghettoblaster volumio[4292]: info: Starting Shairport Sync May 24 23:24:39 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:39] [connect] Successful connection May 24 23:24:39 ghettoblaster volumio[4292]: info: Starting Shairport Sync May 24 23:24:39 ghettoblaster sudo[4530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:39 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Fetching time from Volumio... May 24 23:24:39 ghettoblaster volumio[4292]: info: Starting Shairport Sync May 24 23:24:40 ghettoblaster sudo[4530]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:40 ghettoblaster sudo[4533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:40 ghettoblaster sudo[4533]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:40 ghettoblaster volumio[4292]: info: Discovery: adding b024b946-910d-4e68-956c-dccd6edb4dd2 May 24 23:24:40 ghettoblaster volumio[4292]: info: Discovery: Found device GhettoBlaster May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioGetState May 24 23:24:40 ghettoblaster volumio[4292]: info: CorePlayQueue::getTrack 0 May 24 23:24:40 ghettoblaster sudo[4540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 23:24:40 ghettoblaster ntpd[791]: Listen normally on 3 eth0 192.168.1.122:123 May 24 23:24:40 ghettoblaster ntpd[791]: new interface(s) found: waking up resolver May 24 23:24:40 ghettoblaster sudo[4540]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:40 ghettoblaster volumio-time-update[631]: volumio-time-update-util: Setting system time to: 2025-05-24 23:25:03 May 24 23:24:40 ghettoblaster sudo[4543]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-24 23:25:03 May 24 23:24:40 ghettoblaster sudo[4543]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioGetState May 24 23:24:40 ghettoblaster volumio[4292]: info: CorePlayQueue::getTrack 0 May 24 23:24:40 ghettoblaster volumio[4292]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. May 24 23:24:40 ghettoblaster volumio[4292]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 24 23:24:40 ghettoblaster dbus-daemon[638]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.71' (uid=0 pid=4544 comm="timedatectl set-time 2025-05-24 23:25:03 ") May 24 23:24:40 ghettoblaster volumio-remote-updater[636]: [2025-05-24 23:24:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748121879 101 May 24 23:24:40 ghettoblaster volumio[4292]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 May 24 23:24:40 ghettoblaster sudo[4495]: pam_unix(sudo:session): session closed for user root May 24 23:24:40 ghettoblaster volumio[4292]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 24 23:24:40 ghettoblaster sudo[4493]: pam_unix(sudo:session): session closed for user root May 24 23:24:40 ghettoblaster volumio[4292]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 24 23:24:40 ghettoblaster mpd[4470]: May 24 23:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 24 23:24:40 ghettoblaster systemd[1]: Started Music Player Daemon. May 24 23:24:40 ghettoblaster sudo[4433]: pam_unix(sudo:session): session closed for user root May 24 23:24:40 ghettoblaster sudo[4443]: pam_unix(sudo:session): session closed for user root May 24 23:24:40 ghettoblaster sudo[4549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 23:24:40 ghettoblaster sudo[4549]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:40 ghettoblaster systemd[1]: Reloading. May 24 23:24:40 ghettoblaster sudo[4549]: pam_unix(sudo:session): session closed for user root May 24 23:24:40 ghettoblaster sudo[4551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 23:24:40 ghettoblaster sudo[4551]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:40 ghettoblaster sudo[4551]: pam_unix(sudo:session): session closed for user root May 24 23:24:40 ghettoblaster volumio[4292]: error: MPD error: The expression evaluated to a falsy value: May 24 23:24:40 ghettoblaster volumio[4292]: assert.ok(self.idling) May 24 23:24:40 ghettoblaster volumio[4292]: error: The expression evaluated to a falsy value: May 24 23:24:40 ghettoblaster volumio[4292]: assert.ok(self.idling) May 24 23:24:40 ghettoblaster volumio[4292]: info: MPD running with PID4470 May 24 23:24:40 ghettoblaster volumio[4292]: ,establishing connection May 24 23:24:40 ghettoblaster volumio[4292]: error: updateQueue error: null May 24 23:24:40 ghettoblaster volumio[4292]: verbose: New Socket.io Connection to 192.168.1.122 from 192.168.1.190 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 24 23:24:40 ghettoblaster volumio[4292]: error: updateQueue error: null May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioGetVisibleSources May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioGetState May 24 23:24:40 ghettoblaster volumio[4292]: info: CorePlayQueue::getTrack 0 May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 23:24:40 ghettoblaster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 23:24:40 ghettoblaster volumio[4292]: info: Received Get System Info May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 23:24:40 ghettoblaster volumio[4292]: info: Discovery: Getting this device information May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioGetState May 24 23:24:40 ghettoblaster volumio[4292]: info: CorePlayQueue::getTrack 0 May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioGetState May 24 23:24:40 ghettoblaster volumio[4292]: info: CorePlayQueue::getTrack 0 May 24 23:24:40 ghettoblaster volumio[4292]: info: Listing playlists May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 24 23:24:40 ghettoblaster sudo[4499]: pam_unix(sudo:session): session closed for user root May 24 23:24:40 ghettoblaster volumio[4292]: info: touch_display: systemctl daemon-reload succeeded. May 24 23:24:40 ghettoblaster systemd[1]: Starting Time & Date Service... May 24 23:24:40 ghettoblaster systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 24 23:24:40 ghettoblaster systemd[1]: shairport-sync.service: Succeeded. May 24 23:24:40 ghettoblaster systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 24 23:24:40 ghettoblaster systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 24 23:24:40 ghettoblaster sudo[4572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 24 23:24:40 ghettoblaster sudo[4572]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 23:24:40 ghettoblaster sudo[4533]: pam_unix(sudo:session): session closed for user root May 24 23:24:40 ghettoblaster sudo[4530]: pam_unix(sudo:session): session closed for user root May 24 23:24:40 ghettoblaster sudo[4540]: pam_unix(sudo:session): session closed for user root May 24 23:24:40 ghettoblaster volumio[4292]: info: Shairport-Sync Started May 24 23:24:40 ghettoblaster volumio[4292]: Error adding Membership: Error: addMembership EINVAL May 24 23:24:40 ghettoblaster sudo[4572]: pam_unix(sudo:session): session closed for user root May 24 23:24:40 ghettoblaster volumio[4292]: info: Shairport-Sync Started May 24 23:24:40 ghettoblaster volumio[4292]: info: Shairport-Sync Started May 24 23:24:40 ghettoblaster volumio[4292]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 24 23:24:40 ghettoblaster volumio[4292]: info: touch_display: Volumio Kiosk started. May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioGetState May 24 23:24:40 ghettoblaster volumio[4292]: info: CorePlayQueue::getTrack 0 May 24 23:24:40 ghettoblaster volumio[4292]: info: Completed starting Core Plugins May 24 23:24:40 ghettoblaster volumio[4292]: info: ------------------------------------------- May 24 23:24:40 ghettoblaster volumio[4292]: info: ----- MyVolumio plugins startup ---- May 24 23:24:40 ghettoblaster volumio[4292]: info: ------------------------------------------- May 24 23:24:40 ghettoblaster volumio[4292]: info: [MyVolumio PluginManager] Fetching plans data.... May 24 23:24:40 ghettoblaster volumio[4292]: info: CoreCommandRouter::volumioGetState May 24 23:24:40 ghettoblaster volumio[4292]: info: CorePlayQueue::getTrack 0 May 24 23:24:40 ghettoblaster volumio[4292]: info: touch_display: X display number found: 0 May 24 23:24:40 ghettoblaster dbus-daemon[638]: [system] Successfully activated service 'org.freedesktop.timedate1' May 24 23:24:40 ghettoblaster systemd[1]: Started Time & Date Service. May 24 23:25:03 ghettoblaster systemd-timedated[4571]: Changed local time to Sat May 24 23:25:03 2025 May 24 23:25:03 ghettoblaster sudo[4543]: pam_unix(sudo:session): session closed for user root May 24 23:25:03 ghettoblaster volumio-time-update[631]: volumio-time-update-util: System time updated successfully. May 24 23:25:03 ghettoblaster systemd[1]: Started Volumio Time Update Utility. May 24 23:25:03 ghettoblaster volumio[4292]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 24 23:25:03 ghettoblaster volumio[4292]: info: touch_display: X display number found: 0 May 24 23:25:03 ghettoblaster volumio[4292]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 23:25:03 ghettoblaster volumio[4292]: TypeError: Cannot read property 'sort' of null May 24 23:25:03 ghettoblaster volumio[4292]: at /data/plugins/user_interface/touch_display/index.js:1599:48 May 24 23:25:03 ghettoblaster volumio[4292]: at ChildProcess.exithandler (child_process.js:299:7) May 24 23:25:03 ghettoblaster volumio[4292]: at ChildProcess.emit (events.js:315:20) May 24 23:25:03 ghettoblaster volumio[4292]: at maybeClose (internal/child_process.js:1048:16) May 24 23:25:03 ghettoblaster volumio[4292]: at Socket. (internal/child_process.js:439:11) May 24 23:25:03 ghettoblaster volumio[4292]: at Socket.emit (events.js:315:20) May 24 23:25:03 ghettoblaster volumio[4292]: at Pipe. (net.js:673:12) May 24 23:25:03 ghettoblaster volumio[4292]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 23:25:03 ghettoblaster nmbd[762]: [2025/05/24 23:25:03.130895, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 24 23:25:03 ghettoblaster systemd[1]: Started Samba NMB Daemon. May 24 23:25:03 ghettoblaster nmbd[762]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 24 23:25:03 ghettoblaster systemd[1]: Starting Samba Winbind Daemon... May 24 23:25:03 ghettoblaster winbindd[4584]: [2025/05/24 23:25:03.354915, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 24 23:25:03 ghettoblaster winbindd[4584]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 24 23:25:03 ghettoblaster winbindd[4584]: [2025/05/24 23:25:03.370052, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 24 23:25:03 ghettoblaster systemd[1]: Started Samba Winbind Daemon. May 24 23:25:03 ghettoblaster winbindd[4584]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 24 23:25:03 ghettoblaster systemd[1]: Starting Samba SMB Daemon... May 24 23:25:03 ghettoblaster sudo[4597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 23:24 May 24 23:25:03 ghettoblaster sudo[4597]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"