-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Sat 2024-10-12 13:13:31 UTC. -- Oct 12 13:12:00 volumio volumio[758]: info: Loading i18n strings for locale ko Oct 12 13:12:00 volumio volumio[758]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 12 13:12:00 volumio volumio[758]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 12 13:12:00 volumio volumio[758]: Updating browse sources language Oct 12 13:12:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 13:12:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 13:12:00 volumio volumio[758]: info: CoreCommandRouter::initPlayerControls Oct 12 13:12:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 13:12:00 volumio volumio[758]: Express server listening on port 3000 Oct 12 13:12:00 volumio volumio[758]: [Metrics] WebUI: 22s 616.06ms Oct 12 13:12:00 volumio volumio[758]: info: CoreStateMachine::resetVolumioState Oct 12 13:12:00 volumio volumio[758]: info: CoreStateMachine::getcurrentVolume Oct 12 13:12:00 volumio volumio[758]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 13:12:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 13:12:00 volumio sudo[883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 13:12:00 volumio sudo[883]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:00 volumio sudo[883]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:00 volumio sudo[885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 13:12:00 volumio sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:00 volumio sudo[885]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:00 volumio volumio[758]: info: MPD Permissions set Oct 12 13:12:01 volumio volumio[758]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 12 13:12:01 volumio volumio[758]: wlan0 Interface doesn't support scanning. Oct 12 13:12:01 volumio volumio[758]: info: Cannot use regular scanning, forcing with ap-force Oct 12 13:12:01 volumio sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 12 13:12:01 volumio sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:01 volumio sudo[894]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:01 volumio volumio[758]: command failed: No such device (-19) Oct 12 13:12:01 volumio volumio[758]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 12 13:12:01 volumio volumio[758]: command failed: No such device (-19) Oct 12 13:12:01 volumio volumio[758]: info: MPD running with PID807 Oct 12 13:12:01 volumio volumio[758]: ,establishing connection Oct 12 13:12:01 volumio volumio[758]: info: VolumeController:: Volume=100 Mute =false Oct 12 13:12:01 volumio volumio[758]: info: CoreStateMachine::pushState Oct 12 13:12:01 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::volumioPushState Oct 12 13:12:01 volumio volumio[758]: info: CoreStateMachine::updateTrackBlock Oct 12 13:12:01 volumio volumio[758]: info: CorePlayQueue::getTrackBlock Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 13:12:01 volumio volumio[758]: info: Discovery: Getting this device information Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::volumioGetState Oct 12 13:12:01 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 13:12:01 volumio volumio[758]: error: MPD error: The expression evaluated to a falsy value: Oct 12 13:12:01 volumio volumio[758]: assert.ok(self.idling) Oct 12 13:12:01 volumio volumio[758]: error: MPD error: The expression evaluated to a falsy value: Oct 12 13:12:01 volumio volumio[758]: assert.ok(self.idling) Oct 12 13:12:01 volumio volumio[758]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:01 volumio volumio[758]: info: Reloading queue from file Oct 12 13:12:01 volumio volumio[758]: info: CoreStateMachine::setRepeat true single undefined Oct 12 13:12:01 volumio volumio[758]: info: CoreStateMachine::pushState Oct 12 13:12:01 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::volumioPushState Oct 12 13:12:01 volumio volumio[758]: info: CoreStateMachine::setRandom null Oct 12 13:12:01 volumio volumio[758]: info: CoreStateMachine::pushState Oct 12 13:12:01 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:12:01 volumio volumio[758]: info: CoreCommandRouter::volumioPushState Oct 12 13:12:01 volumio volumio[758]: info: Setting Device type: Raspberry PI Oct 12 13:12:01 volumio volumio[758]: error: updateQueue error: null Oct 12 13:12:02 volumio volumio[758]: info: VolumeController:: Volume=100 Mute =false Oct 12 13:12:02 volumio volumio[758]: info: CoreStateMachine::pushState Oct 12 13:12:02 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::volumioPushState Oct 12 13:12:02 volumio sudo[910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 12 13:12:02 volumio sudo[910]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:02 volumio volumio[758]: info: Completed loading Core Plugins Oct 12 13:12:02 volumio volumio[758]: info: Preparing to generate the ALSA configuration file Oct 12 13:12:02 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 12 13:12:02 volumio sudo[910]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:02 volumio volumio[758]: info: Discovery: adding 0df5d207-e447-4b9a-855e-f7c2d1e3a571 Oct 12 13:12:02 volumio volumio[758]: info: mDNS: Found device Volumio Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::volumioGetState Oct 12 13:12:02 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:12:02 volumio volumio[758]: info: Upmpdcli Daemon Started Oct 12 13:12:02 volumio volumio[758]: info: Asound.conf file unchanged, so no further update is needed Oct 12 13:12:02 volumio volumio[758]: info: Output device has changed, restarting MPD Oct 12 13:12:02 volumio volumio[758]: info: Output device has changed, restarting Shairport Sync Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 13:12:02 volumio sudo[915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 13:12:02 volumio sudo[915]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:02 volumio sudo[915]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:02 volumio sudo[917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 13:12:02 volumio sudo[917]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:02 volumio systemd[1]: Stopping Music Player Daemon... Oct 12 13:12:02 volumio volumio[758]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 13:12:02 volumio volumio[758]: info: ___________ START PLUGINS ___________ Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 13:12:02 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT Oct 12 13:12:02 volumio systemd[1]: mpd.service: Failed with result 'signal'. Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 13:12:02 volumio volumio[758]: info: [1728738722526] CoreMusicLibrary::Adding element Media Servers Oct 12 13:12:02 volumio systemd[1]: Stopped Music Player Daemon. Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 13:12:02 volumio volumio[758]: Cannot find translation for sourceMedia Servers Oct 12 13:12:02 volumio systemd[1]: Starting Music Player Daemon... Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 13:12:02 volumio volumio[758]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 13:12:02 volumio volumio[758]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 13:12:02 volumio volumio[758]: info: [1728738722767] CoreMusicLibrary::Adding element Last_100 Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 13:12:02 volumio volumio[758]: Cannot find translation for sourceMedia Servers Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 13:12:02 volumio volumio[758]: info: [1728738722784] CoreMusicLibrary::Adding element Webradio Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 13:12:02 volumio volumio[758]: Cannot find translation for sourceMedia Servers Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 13:12:02 volumio volumio[758]: info: [1728738722821] CoreMusicLibrary::Adding element YouTube2 Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 13:12:02 volumio volumio[758]: Cannot find translation for sourceMedia Servers Oct 12 13:12:02 volumio volumio[758]: Cannot find translation for sourceYouTube2 Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 13:12:02 volumio volumio[758]: info: [1728738722841] CoreMusicLibrary::Adding element YouTube Music Oct 12 13:12:02 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 13:12:02 volumio volumio[758]: Cannot find translation for sourceMedia Servers Oct 12 13:12:02 volumio volumio[758]: Cannot find translation for sourceYouTube2 Oct 12 13:12:02 volumio volumio[758]: Cannot find translation for sourceYouTube Music Oct 12 13:12:02 volumio volumio[758]: info: Loading i18n strings for locale ko Oct 12 13:12:02 volumio volumio[758]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 12 13:12:02 volumio volumio[758]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 12 13:12:03 volumio volumio[758]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 13:12:03 volumio volumio[758]: info: [1728738723116] CoreMusicLibrary::Adding element 개인 라디오 Oct 12 13:12:03 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 13:12:03 volumio volumio[758]: Cannot find translation for sourceMedia Servers Oct 12 13:12:03 volumio volumio[758]: Cannot find translation for sourceYouTube2 Oct 12 13:12:03 volumio volumio[758]: Cannot find translation for sourceYouTube Music Oct 12 13:12:03 volumio volumio[758]: Cannot find translation for source개인 라디오 Oct 12 13:12:03 volumio volumio[758]: info: Loading i18n strings for locale ko Oct 12 13:12:03 volumio volumio[758]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 12 13:12:03 volumio volumio[758]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 12 13:12:03 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 13:12:03 volumio volumio[758]: info: Volumio Calling Home Oct 12 13:12:03 volumio sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Oct 12 13:12:03 volumio sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:03 volumio sudo[943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 12 13:12:03 volumio sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:03 volumio sudo[940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Oct 12 13:12:03 volumio sudo[940]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:03 volumio sudo[938]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:03 volumio systemd[1]: Reloading. Oct 12 13:12:03 volumio volumio[758]: info: touch_display: No backlight interface detected. Oct 12 13:12:04 volumio volumio[758]: info: touch_display: systemctl stop getty@tty1.service succeeded. Oct 12 13:12:04 volumio volumio[758]: info: minidlna: Found miniDLNA version 1.2.1 Oct 12 13:12:04 volumio volumio[758]: info: minidlna: Starting minidlna.service Oct 12 13:12:04 volumio volumio[758]: info: MPD Permissions set Oct 12 13:12:04 volumio sudo[960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 12 13:12:04 volumio sudo[960]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:04 volumio volumio[758]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Oct 12 13:12:04 volumio volumio[758]: info: Volumio called home Oct 12 13:12:04 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:04 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:04 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:04 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:04 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:04 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:04 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:04 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:04 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 13:12:04 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:12:04 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 12 13:12:04 volumio volumio[758]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Oct 12 13:12:04 volumio mpd[933]: Oct 12 13:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 12 13:12:04 volumio sudo[967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Oct 12 13:12:04 volumio sudo[967]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:04 volumio sudo[967]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:04 volumio volumio[758]: info: Starting Shairport Sync Oct 12 13:12:04 volumio volumio[758]: info: Starting Shairport Sync Oct 12 13:12:04 volumio volumio[758]: info: Starting Shairport Sync Oct 12 13:12:04 volumio sudo[970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 13:12:04 volumio sudo[970]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:04 volumio sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 13:12:04 volumio sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:05 volumio sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 13:12:05 volumio sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:05 volumio volumio[758]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Oct 12 13:12:05 volumio volumio[758]: (node:758) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Oct 12 13:12:05 volumio volumio[758]: (Use `node --trace-deprecation ...` to show where the warning was created) Oct 12 13:12:05 volumio volumio[758]: (node:758) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. Oct 12 13:12:05 volumio volumio[758]: info: CoreCommandRouter::volumioGetState Oct 12 13:12:05 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:12:05 volumio systemd[1]: serial-getty@ttyAMA0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 12 13:12:05 volumio sudo[943]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:05 volumio volumio[758]: info: touch_display: systemctl daemon-reload succeeded. Oct 12 13:12:05 volumio sudo[979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 12 13:12:05 volumio sudo[979]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:05 volumio systemd[1]: Reloading. Oct 12 13:12:06 volumio volumio[758]: Upnp client error: Error: read ECONNRESET Oct 12 13:12:06 volumio volumio[912]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 12 13:12:06 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 12 13:12:06 volumio volumio[758]: error: MPD error: The expression evaluated to a falsy value: Oct 12 13:12:06 volumio volumio[758]: assert.ok(self.idling) Oct 12 13:12:06 volumio volumio[758]: error: updateQueue error: null Oct 12 13:12:07 volumio systemd[1]: Started Music Player Daemon. Oct 12 13:12:07 volumio sudo[940]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:07 volumio volumio[758]: info: touch_display: systemctl disable getty@tty1.service succeeded. Oct 12 13:12:07 volumio sudo[917]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:07 volumio volumio[758]: error: updateQueue error: null Oct 12 13:12:07 volumio systemd[1]: Started Volumio Kiosk. Oct 12 13:12:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 12 13:12:07 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 12 13:12:07 volumio sudo[960]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:07 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 12 13:12:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 12 13:12:07 volumio volumio[758]: info: minidlna: systemctl start minidlna.service succeeded. Oct 12 13:12:07 volumio sudo[979]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 12 13:12:07 volumio sudo[975]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:07 volumio sudo[970]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:07 volumio volumio[758]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Oct 12 13:12:07 volumio volumio[758]: info: touch_display: Volumio Kiosk started. Oct 12 13:12:07 volumio sudo[972]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:07 volumio volumio[758]: info: CoreCommandRouter::volumioGetState Oct 12 13:12:07 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:12:07 volumio volumio[758]: info: Completed starting Core Plugins Oct 12 13:12:07 volumio volumio[758]: info: ------------------------------------------- Oct 12 13:12:07 volumio volumio[758]: info: ----- MyVolumio plugins startup ---- Oct 12 13:12:07 volumio volumio[758]: info: ------------------------------------------- Oct 12 13:12:07 volumio volumio[758]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 12 13:12:07 volumio volumio[758]: info: CoreCommandRouter::volumioGetState Oct 12 13:12:07 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:12:07 volumio volumio[758]: info: Shairport-Sync Started Oct 12 13:12:07 volumio volumio[758]: Error adding Membership: Error: addMembership EINVAL Oct 12 13:12:07 volumio volumio[758]: info: Shairport-Sync Started Oct 12 13:12:07 volumio volumio[758]: info: Shairport-Sync Started Oct 12 13:12:07 volumio volumio[758]: info: touch_display: X display number found: 0 Oct 12 13:12:08 volumio startx[1010]: X.Org X Server 1.20.4 Oct 12 13:12:08 volumio startx[1010]: X Protocol Version 11, Revision 0 Oct 12 13:12:08 volumio startx[1010]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Oct 12 13:12:08 volumio startx[1010]: Current Operating System: Linux volumio 5.10.90-v7+ #1512 SMP Thu Jan 13 16:32:54 GMT 2022 armv7l Oct 12 13:12:08 volumio startx[1010]: Kernel command line: coherent_pool=1M snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Oct 12 13:12:08 volumio startx[1010]: Build Date: 04 April 2023 07:50:56AM Oct 12 13:12:08 volumio startx[1010]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Oct 12 13:12:08 volumio startx[1010]: Current version of pixman: 0.36.0 Oct 12 13:12:08 volumio startx[1010]: Before reporting problems, check http://wiki.x.org Oct 12 13:12:08 volumio startx[1010]: to make sure that you have the latest version. Oct 12 13:12:08 volumio startx[1010]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 12 13:12:08 volumio startx[1010]: (++) from command line, (!!) notice, (II) informational, Oct 12 13:12:08 volumio startx[1010]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 12 13:12:08 volumio startx[1010]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Oct 12 13:12:08 2024 Oct 12 13:12:08 volumio startx[1010]: (==) Using config directory: "/etc/X11/xorg.conf.d" Oct 12 13:12:08 volumio startx[1010]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 12 13:12:08 volumio volumio[758]: info: touch_display: X display number found: 0 Oct 12 13:12:08 volumio volumio[758]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 12 13:12:08 volumio volumio[758]: info: touch_display: X display number found: 0 Oct 12 13:12:08 volumio startx[1010]: modprobe: FATAL: Module g2d_23 not found in directory /lib/modules/5.10.90-v7+ Oct 12 13:12:09 volumio volumio-remote-updater[394]: [2024-10-12 13:12:09] [connect] Successful connection Oct 12 13:12:09 volumio volumio-remote-updater[394]: [2024-10-12 13:12:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728738729 101 Oct 12 13:12:09 volumio volumio[758]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Oct 12 13:12:10 volumio volumio[758]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 12 13:12:11 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 13:12:11 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 13:12:11 volumio volumio[758]: info: Discovery: Getting this device information Oct 12 13:12:11 volumio volumio[758]: info: CoreCommandRouter::volumioGetState Oct 12 13:12:11 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:12:11 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 13:12:11 volumio volumio[758]: verbose: New Socket.io Connection to 192.168.1.164:3000 from 192.168.1.150 UA: Dart/3.4 (dart:io) Total Clients: 4 Oct 12 13:12:11 volumio volumio[758]: info: CoreCommandRouter::volumioGetState Oct 12 13:12:11 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:12:11 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 13:12:11 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 13:12:11 volumio volumio[758]: error: updateQueue error: null Oct 12 13:12:13 volumio ntpd[572]: Soliciting pool server 121.174.142.81 Oct 12 13:12:14 volumio ntpd[572]: Soliciting pool server 106.247.248.106 Oct 12 13:12:15 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 13:12:15 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 13:12:15 volumio volumio[758]: info: Discovery: Getting this device information Oct 12 13:12:15 volumio volumio[758]: info: CoreCommandRouter::volumioGetState Oct 12 13:12:15 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:12:15 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 13:12:16 volumio sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 13:12:16 volumio sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:16 volumio sudo[1132]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:16 volumio sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 13:12:16 volumio sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:16 volumio sudo[1134]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:16 volumio sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 13:12:16 volumio sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:16 volumio sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 13:12:16 volumio sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:16 volumio sudo[1141]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:16 volumio volumio[758]: verbose: New Socket.io Connection to 192.168.1.164 from 192.168.1.150 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 5 Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 12 13:12:16 volumio sudo[1142]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 12 13:12:16 volumio volumio[758]: info: Adding plugin bluetooth to MyMusic Plugins Oct 12 13:12:16 volumio volumio[758]: info: Adding plugin multiroom to MyMusic Plugins Oct 12 13:12:16 volumio volumio[758]: info: Adding plugin cd_controller to MyMusic Plugins Oct 12 13:12:16 volumio volumio[758]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 12 13:12:16 volumio volumio[758]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 12 13:12:16 volumio volumio[758]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 12 13:12:16 volumio ntpd[572]: Soliciting pool server 39.118.108.191 Oct 12 13:12:17 volumio ntpd[572]: Soliciting pool server 175.193.3.234 Oct 12 13:12:17 volumio ntpd[572]: Soliciting pool server 193.123.243.2 Oct 12 13:12:17 volumio ntpd[572]: Soliciting pool server 121.174.142.82 Oct 12 13:12:17 volumio ntpd[572]: Soliciting pool server 13.209.84.50 Oct 12 13:12:17 volumio ntpd[572]: Soliciting pool server 194.0.5.123 Oct 12 13:12:18 volumio ntpd[572]: Soliciting pool server 2401:c080:1c00:24a1:5400:5ff:fe04:720 Oct 12 13:12:19 volumio volumio[912]: .................................................++++ Oct 12 13:12:19 volumio volumio[758]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 12 13:12:19 volumio volumio[758]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 12 13:12:19 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 13:12:19 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 13:12:19 volumio volumio[758]: info: Starting MyVolumio Remote Streaming Endpoints Oct 12 13:12:19 volumio volumio[758]: info: MyVolumio login type: Token Oct 12 13:12:19 volumio volumio[758]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 12 13:12:19 volumio volumio[758]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 12 13:12:20 volumio volumio[758]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 12 13:12:20 volumio volumio[758]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 12 13:12:20 volumio volumio[758]: info: Streaming services startup Oct 12 13:12:20 volumio volumio[758]: info: Starting Streaming Daemon Oct 12 13:12:20 volumio sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 12 13:12:20 volumio sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:20 volumio systemd[1]: Stopping Volumio Streaming Daemon... Oct 12 13:12:20 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Oct 12 13:12:20 volumio systemd[1]: volumio-streaming-daemon.service: Succeeded. Oct 12 13:12:20 volumio systemd[1]: Stopped Volumio Streaming Daemon. Oct 12 13:12:20 volumio systemd[1]: Started Volumio Streaming Daemon. Oct 12 13:12:20 volumio sudo[1168]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:20 volumio volumio-streaming-daemon[1174]: ############################ Oct 12 13:12:20 volumio volumio-streaming-daemon[1174]: # Volumio Streaming Daemon # Oct 12 13:12:20 volumio volumio-streaming-daemon[1174]: # Running on port 7777 # Oct 12 13:12:20 volumio volumio-streaming-daemon[1174]: ############################ Oct 12 13:12:20 volumio volumio-streaming-daemon[1174]: Environment: production Oct 12 13:12:20 volumio volumio[758]: verbose: New Socket.io Connection to 192.168.1.164 from 192.168.1.150 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 5 Oct 12 13:12:21 volumio sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 13:12:21 volumio sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 13:12:21 volumio sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:21 volumio sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:21 volumio sudo[1183]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:21 volumio sudo[1184]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:21 volumio volumio[758]: verbose: New Socket.io Connection to 192.168.1.164 from 192.168.1.150 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 6 Oct 12 13:12:21 volumio volumio[758]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 12 13:12:21 volumio volumio[758]: info: MYVOLUMIO: Adding device Oct 12 13:12:21 volumio volumio[758]: info: MYVOLUMIO: Evaluating Server Oct 12 13:12:22 volumio volumio[758]: info: CoreCommandRouter::volumioGetState Oct 12 13:12:22 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:12:22 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 13:12:22 volumio volumio[758]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 13:12:22 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 13:12:22 volumio volumio[758]: info: Listing playlists Oct 12 13:12:22 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 13:12:22 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 13:12:22 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 13:12:22 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 13:12:22 volumio dbus-daemon[392]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.14' (uid=1000 pid=1098 comm="/usr/lib/chromium-browser/chromium-browser-v7 --en") Oct 12 13:12:22 volumio systemd[1]: Starting Bluetooth service... Oct 12 13:12:43 volumio ntpd[572]: receive: Unexpected origin timestamp 0xeab4f436.9f64faf6 does not match aorg 0000000000.00000000 from server@13.209.84.50 xmt 0xeab4f44b.7fef1176 Oct 12 13:12:43 volumio kernel: Bluetooth: Core ver 2.22 Oct 12 13:12:43 volumio kernel: NET: Registered protocol family 31 Oct 12 13:12:43 volumio kernel: Bluetooth: HCI device and connection manager initialized Oct 12 13:12:43 volumio kernel: Bluetooth: HCI socket layer initialized Oct 12 13:12:43 volumio kernel: Bluetooth: L2CAP socket layer initialized Oct 12 13:12:43 volumio kernel: Bluetooth: SCO socket layer initialized Oct 12 13:12:43 volumio bluetoothd[1205]: Bluetooth daemon 5.50 Oct 12 13:12:43 volumio bluetoothd[1205]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Oct 12 13:12:43 volumio dbus-daemon[392]: [system] Successfully activated service 'org.bluez' Oct 12 13:12:43 volumio systemd[1]: Started Bluetooth service. Oct 12 13:12:43 volumio volumio[758]: info: MyVolumio status changed Oct 12 13:12:43 volumio volumio[758]: info: Streaming services startup Oct 12 13:12:43 volumio volumio[758]: info: Re-Starting Streaming Daemon Oct 12 13:12:43 volumio bluetoothd[1205]: Starting SDP server Oct 12 13:12:43 volumio bluetoothd[1205]: Excluding (cli) sap Oct 12 13:12:43 volumio volumio[758]: info: Removing browser output: myVolumio user plan is not superstar Oct 12 13:12:43 volumio volumio[758]: info: Removing audio output: Oct 12 13:12:43 volumio volumio[758]: info: Stoppping Tunnel 1 Oct 12 13:12:44 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 12 13:12:44 volumio kernel: Bluetooth: BNEP filters: protocol multicast Oct 12 13:12:44 volumio kernel: Bluetooth: BNEP socket layer initialized Oct 12 13:12:44 volumio bluetoothd[1205]: Bluetooth management interface 1.18 initialized Oct 12 13:12:44 volumio dbus-daemon[392]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.15' (uid=0 pid=1205 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Oct 12 13:12:44 volumio sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 12 13:12:44 volumio sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:44 volumio systemd[1]: Starting Hostname Service... Oct 12 13:12:44 volumio sudo[1214]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:44 volumio dbus-daemon[392]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 12 13:12:44 volumio systemd[1]: Started Hostname Service. Oct 12 13:12:45 volumio volumio-streaming-daemon[1174]: Restarting daemon Oct 12 13:12:45 volumio volumio-streaming-daemon[1174]: Environment: production Oct 12 13:12:45 volumio volumio[758]: info: Remote SSH Stopped Oct 12 13:12:45 volumio sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 13:12:45 volumio sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:45 volumio sudo[1255]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:45 volumio volumio-streaming-daemon[1174]: Environment: production Oct 12 13:12:45 volumio volumio[758]: info: Setting Geolocation for MyVolumio to as1 Oct 12 13:12:45 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 13:12:45 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 13:12:45 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 13:12:45 volumio sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 13:12:45 volumio sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 13:12:45 volumio sudo[1258]: pam_unix(sudo:session): session closed for user root Oct 12 13:12:45 volumio volumio[758]: info: Fetching Streaming Services browse cache Oct 12 13:12:45 volumio volumio[758]: verbose: New Socket.io Connection to 192.168.1.164 from 192.168.1.150 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 7 Oct 12 13:12:46 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 13:12:46 volumio volumio-streaming-daemon[1174]: Environment: production Oct 12 13:12:46 volumio volumio[758]: info: Updating MyVolumio device info Oct 12 13:12:46 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 13:12:46 volumio volumio[758]: info: CoreCommandRouter::volumioGetState Oct 12 13:12:46 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:12:46 volumio volumio[758]: info: Fetching Streaming Services browse cache Oct 12 13:12:46 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 13:12:46 volumio volumio[758]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 13:12:46 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 13:12:46 volumio volumio[758]: info: Listing playlists Oct 12 13:12:46 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 13:12:46 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 13:12:46 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 13:12:46 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 13:12:48 volumio volumio[758]: info: CoreCommandRouter::volumioGetQueue Oct 12 13:12:48 volumio volumio[758]: info: CoreStateMachine::getQueue Oct 12 13:12:48 volumio volumio[758]: info: CorePlayQueue::getQueue Oct 12 13:12:49 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 13:12:53 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 13:12:53 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 13:12:55 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 13:12:55 volumio volumio[758]: info: CURURI: music-library Oct 12 13:12:56 volumio volumio[758]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 8 Oct 12 13:12:57 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 13:12:57 volumio volumio[758]: info: CURURI: music-library/USB Oct 12 13:12:58 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 13:12:58 volumio volumio[758]: info: CURURI: music-library/USB/vogel-Music Oct 12 13:13:00 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 13:13:00 volumio volumio[758]: info: CURURI: music-library/USB/vogel-Music/Music Oct 12 13:13:01 volumio volumio[758]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 12 13:13:01 volumio volumio[758]: info: Completed starting MyVolumio Plugin Oct 12 13:13:01 volumio volumio[758]: info: BOOT COMPLETED Oct 12 13:13:01 volumio volumio[758]: [Metrics] CommandRouter: 61s 217.88ms Oct 12 13:13:01 volumio volumio[758]: info: CoreCommandRouter::volumiosetStartupVolume Oct 12 13:13:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 13:13:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 13:13:01 volumio volumio[758]: info: CoreCommandRouter::Close All Modals sent Oct 12 13:13:01 volumio volumio[758]: info: CoreCommandRouter::Close All Modals sent Oct 12 13:13:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 13:13:01 volumio volumio[758]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 12 13:13:01 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 12 13:13:01 volumio volumio[758]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Oct 12 13:13:02 volumio volumio[758]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 8 Oct 12 13:13:02 volumio volumio[758]: info: Received Get System Version Oct 12 13:13:02 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 13:13:03 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 12 13:13:03 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 13:13:03 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 12 13:13:03 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 13:13:03 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 13:13:03 volumio volumio[758]: info: CURURI: music-library/USB/vogel-Music/Music/Classic Oct 12 13:13:03 volumio volumio[758]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 12 13:13:03 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 12 13:13:03 volumio volumio[758]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Oct 12 13:13:03 volumio volumio[758]: info: Received Get System Version Oct 12 13:13:03 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 13:13:06 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 13:13:06 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 13:13:06 volumio volumio[758]: info: Discovery: Getting this device information Oct 12 13:13:06 volumio volumio[758]: info: CoreCommandRouter::volumioGetState Oct 12 13:13:06 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:13:06 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 13:13:07 volumio volumio[758]: info: CoreCommandRouter::volumioGetState Oct 12 13:13:07 volumio volumio[758]: info: CorePlayQueue::getTrack 0 Oct 12 13:13:07 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 13:13:07 volumio volumio[758]: info: Listing playlists Oct 12 13:13:07 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 13:13:07 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 13:13:07 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 13:13:07 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 13:13:08 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 13:13:08 volumio volumio[758]: info: CURURI: music-library/USB/vogel-Music/Music/Classic/Vivaldi Oct 12 13:13:14 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 12 13:13:19 volumio volumio[912]: .................................................................................................................................................++++ Oct 12 13:13:19 volumio volumio[912]: e is 65537 (0x010001) Oct 12 13:13:19 volumio volumio[912]: writing RSA key Oct 12 13:13:21 volumio volumio[758]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 13:13:21 volumio volumio[758]: info: CoreStateMachine::ClearQueue Oct 12 13:13:21 volumio volumio[758]: info: CoreStateMachine::stop Oct 12 13:13:21 volumio volumio[758]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 13:13:21 volumio volumio[758]: verbose: UNSET VOLATILE Oct 12 13:13:21 volumio volumio[758]: info: CorePlayQueue::clearPlayQueue Oct 12 13:13:21 volumio volumio[758]: info: CorePlayQueue::saveQueue Oct 12 13:13:21 volumio volumio[758]: info: CoreCommandRouter::volumioPushState Oct 12 13:13:21 volumio volumio[758]: info: CoreCommandRouter::volumioPushQueue Oct 12 13:13:21 volumio volumio[758]: info: CoreStateMachine::addQueueItems Oct 12 13:13:21 volumio volumio[758]: info: CorePlayQueue::addQueueItems Oct 12 13:13:21 volumio volumio[758]: info: Adding Item to queue: music-library/USB/vogel-Music/Music/Classic/Vivaldi/I Musici - Antonio Vivaldi 8 LP (18 LP Boxset) Oct 12 13:13:21 volumio volumio[758]: info: Exploding uri music-library/USB/vogel-Music/Music/Classic/Vivaldi/I Musici - Antonio Vivaldi 8 LP (18 LP Boxset) in service mpd Oct 12 13:13:29 volumio volumio[758]: info: ALBUMART /albumart?cacheid=422&web=I%20Musici/Antonio%20Vivaldi%20-%20L'Estro%20Armonico.%20op.3%20%20(LP%201)/extralarge&path=%2Fmnt%2FUSB%2Fvogel-Music%2FMusic%2FClassic%2FVivaldi%2FI%20Musici%20-%20Antonio%20Vivaldi%20%208%20LP%20(18%20LP%20Boxset)%2F01.%20I%20Musici%20-%20Antonio%20Vivaldi%20-%20L'Estro%20Armonico.%20op.3%20(LP%201)%2FI%20Musici%20-%20Antonio%20Vivaldi%20-%20L'Estro%20Armonico.%20op.3%20(LP%201).cue&metadata=false Oct 12 13:13:29 volumio volumio[758]: info: URI /mnt/USB/vogel-Music/Music/Classic/Vivaldi/I Musici - Antonio Vivaldi 8 LP (18 LP Boxset)/01. I Musici - Antonio Vivaldi - L'Estro Armonico. op.3 (LP 1)/I Musici - Antonio Vivaldi - L'Estro Armonico. op.3 (LP 1).cue Oct 12 13:13:29 volumio volumio[758]: info: ALBUMART /albumart?cacheid=422&web=I%20Musici/Antonio%20Vivaldi%20-%20L'Estro%20Armonico.%20op.3%20%20(LP%201)/extralarge&path=%2Fmnt%2FUSB%2Fvogel-Music%2FMusic%2FClassic%2FVivaldi%2FI%20Musici%20-%20Antonio%20Vivaldi%20%208%20LP%20(18%20LP%20Boxset)%2F01.%20I%20Musici%20-%20Antonio%20Vivaldi%20-%20L'Estro%20Armonico.%20op.3%20(LP%201)%2FI%20Musici%20-%20Antonio%20Vivaldi%20-%20L'Estro%20Armonico.%20op.3%20(LP%201).cue&metadata=false Oct 12 13:13:29 volumio volumio[758]: info: URI /mnt/USB/vogel-Music/Music/Classic/Vivaldi/I Musici - Antonio Vivaldi 8 LP (18 LP Boxset)/01. I Musici - Antonio Vivaldi - L'Estro Armonico. op.3 (LP 1)/I Musici - Antonio Vivaldi - L'Estro Armonico. op.3 (LP 1).cue Oct 12 13:13:29 volumio volumio[758]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 13:13:29 volumio volumio[758]: Error: Unable to resolve or reject the same promise twice Oct 12 13:13:29 volumio volumio[758]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 12 13:13:29 volumio volumio[758]: at /volumio/app/plugins/music_service/mpd/index.js:2541:21 Oct 12 13:13:29 volumio volumio[758]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 12 13:13:29 volumio volumio[758]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 12 13:13:29 volumio volumio[758]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 12 13:13:29 volumio volumio[758]: at Socket.emit (events.js:315:20) Oct 12 13:13:29 volumio volumio[758]: at addChunk (internal/streams/readable.js:309:12) Oct 12 13:13:29 volumio volumio[758]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 12 13:13:29 volumio volumio[758]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 12 13:13:29 volumio volumio[758]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Oct 12 13:13:29 volumio volumio[758]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 13:13:31 volumio sudo[1544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-12 13:12 Oct 12 13:13:31 volumio sudo[1544]: 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="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17"