-- Logs begin at Thu 2024-11-21 14:59:56 UTC, end at Thu 2024-11-21 15:39:59 UTC. -- Nov 21 15:38:00 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Nov 21 15:38:00 volumio systemd[1]: Unit volumio.service entered failed state. Nov 21 15:38:00 volumio systemd[1]: Starting dynamicswap service... Nov 21 15:38:00 volumio systemd[1]: Started dynamicswap service. Nov 21 15:38:00 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Nov 21 15:38:00 volumio systemd[1]: Starting dynamicswap service... Nov 21 15:38:00 volumio systemd[1]: Started dynamicswap service. Nov 21 15:38:00 volumio systemd[1]: Stopping Volumio Backend Module... Nov 21 15:38:00 volumio systemd[1]: Starting Volumio Backend Module... Nov 21 15:38:00 volumio systemd[1]: Started Volumio Backend Module. Nov 21 15:38:01 volumio volumio[9743]: info: ------------------------------------------- Nov 21 15:38:01 volumio volumio[9743]: info: ----- Volumio2 ---- Nov 21 15:38:01 volumio volumio[9743]: info: ------------------------------------------- Nov 21 15:38:01 volumio volumio[9743]: info: ----- System startup ---- Nov 21 15:38:01 volumio volumio[9743]: info: ------------------------------------------- Nov 21 15:38:02 volumio volumio[9743]: info: MYVOLUMIO Environment detected Nov 21 15:38:02 volumio volumio[9743]: info: Plugin folders cleanup Nov 21 15:38:02 volumio volumio[9743]: info: Scanning into folder /volumio/app/plugins/ Nov 21 15:38:02 volumio volumio[9743]: info: Scanning category audio_interface Nov 21 15:38:02 volumio volumio[9743]: info: Scanning category miscellanea Nov 21 15:38:02 volumio volumio[9743]: info: Scanning category music_service Nov 21 15:38:02 volumio volumio[9743]: info: Scanning category plugins.json Nov 21 15:38:02 volumio volumio[9743]: info: Scanning category system_controller Nov 21 15:38:02 volumio volumio[9743]: info: Scanning category user_interface Nov 21 15:38:02 volumio volumio[9743]: info: Scanning into folder /data/plugins/ Nov 21 15:38:02 volumio volumio[9743]: info: Scanning category miscellanea Nov 21 15:38:02 volumio volumio[9743]: info: Plugin folders cleanup completed Nov 21 15:38:02 volumio volumio[9743]: info: ------------------------------------------- Nov 21 15:38:02 volumio volumio[9743]: info: ----- Core plugins startup ---- Nov 21 15:38:02 volumio volumio[9743]: info: ------------------------------------------- Nov 21 15:38:02 volumio volumio[9743]: info: Loading plugins from folder /volumio/app/plugins/ Nov 21 15:38:02 volumio volumio[9743]: info: Adding plugin upnp to MyMusic Plugins Nov 21 15:38:02 volumio volumio[9743]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 21 15:38:02 volumio volumio[9743]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 21 15:38:02 volumio volumio[9743]: info: Loading plugins from folder /data/plugins/ Nov 21 15:38:02 volumio volumio[9743]: info: Loading plugin "system"... Nov 21 15:38:02 volumio volumio[9743]: info: Loading plugin "appearance"... Nov 21 15:38:03 volumio volumio[9743]: info: Loading plugin "network"... Nov 21 15:38:03 volumio volumio[9743]: info: Refreshing Cached IP Addresses Nov 21 15:38:03 volumio sudo[9769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 15:38:03 volumio volumio[9743]: info: Loading plugin "touch_display"... Nov 21 15:38:03 volumio sudo[9769]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:03 volumio sudo[9769]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:03 volumio sudo[9772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 15:38:03 volumio sudo[9772]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:03 volumio sudo[9772]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:03 volumio volumio[9743]: info: Applying required configuration parameters for plugin touch_display Nov 21 15:38:03 volumio volumio[9743]: info: Loading plugin "services"... Nov 21 15:38:03 volumio volumio[9743]: info: Loading plugin "alsa_controller"... Nov 21 15:38:03 volumio volumio[9743]: info: Loading plugin "wizard"... Nov 21 15:38:03 volumio volumio[9743]: info: Loading plugin "volumio_command_line_client"... Nov 21 15:38:03 volumio volumio[9743]: info: Loading plugin "upnp"... Nov 21 15:38:03 volumio volumio[9743]: info: [1732203483983] Starting Upmpd Daemon Nov 21 15:38:03 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 15:38:03 volumio volumio[9743]: info: Loading plugin "my_music"... Nov 21 15:38:03 volumio volumio[9743]: info: Loading plugin "mpd"... Nov 21 15:38:04 volumio volumio[9743]: info: Loading plugin "upnp_browser"... Nov 21 15:38:04 volumio volumio[9743]: info: Loading plugin "networkfs"... Nov 21 15:38:04 volumio volumio[9743]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Nov 21 15:38:04 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 15:38:04 volumio volumio[9743]: info: Loading plugin "alarm-clock"... Nov 21 15:38:04 volumio volumio[9743]: info: Loading plugin "airplay_emulation"... Nov 21 15:38:04 volumio volumio[9743]: info: Starting Shairport Sync Nov 21 15:38:04 volumio volumio[9743]: info: Loading plugin "last_100"... Nov 21 15:38:04 volumio volumio[9743]: info: Loading plugin "webradio"... Nov 21 15:38:04 volumio volumio[9743]: info: Loading plugin "i2s_dacs"... Nov 21 15:38:04 volumio volumio[9743]: info: I2S DAC not set, start Auto-detection Nov 21 15:38:04 volumio volumio[9743]: info: Loading plugin "volumiodiscovery"... Nov 21 15:38:04 volumio volumio[9743]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 15:38:04 volumio node[9743]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 15:38:04 volumio node[9743]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 15:38:04 volumio node[9743]: *** WARNING *** For more information see Nov 21 15:38:04 volumio node[9743]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 15:38:04 volumio node[9743]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 15:38:04 volumio node[9743]: *** WARNING *** For more information see Nov 21 15:38:04 volumio volumio[9743]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 15:38:04 volumio volumio[9743]: *** WARNING *** For more information see Nov 21 15:38:04 volumio volumio[9743]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 15:38:04 volumio volumio[9743]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 15:38:04 volumio volumio[9743]: *** WARNING *** For more information see Nov 21 15:38:04 volumio volumio[9743]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 21 15:38:04 volumio volumio[9743]: Discovery: StartAdv! undefined Nov 21 15:38:04 volumio volumio[9743]: Discovery: Started advertising... Volumio - undefined Nov 21 15:38:04 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 15:38:04 volumio volumio[9743]: info: Loading plugin "autostart"... Nov 21 15:38:04 volumio volumio[9743]: info: Applying required configuration parameters for plugin autostart Nov 21 15:38:04 volumio volumio[9743]: info: Loading plugin "outputs"... Nov 21 15:38:04 volumio volumio[9743]: info: Loading plugin "albumart"... Nov 21 15:38:04 volumio volumio[9743]: info: Plugin example_plugin is not enabled Nov 21 15:38:04 volumio volumio[9743]: info: Loading plugin "inputs"... Nov 21 15:38:04 volumio volumio[9743]: info: Loading plugin "updater_comm"... Nov 21 15:38:05 volumio volumio[9743]: info: Plugin mpdemulation is not enabled Nov 21 15:38:05 volumio volumio[9743]: info: Loading plugin "rest_api"... Nov 21 15:38:05 volumio volumio[9743]: info: Loading plugin "websocket"... Nov 21 15:38:05 volumio volumio[9743]: info: ___________ START PLUGINS ___________ Nov 21 15:38:05 volumio volumio[9743]: info: Loading i18n strings for locale zh Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 15:38:05 volumio volumio[9743]: info: [1732203485163] CoreMusicLibrary::Adding element Media Servers Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 15:38:05 volumio sudo[9803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 21 15:38:05 volumio sudo[9803]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:05 volumio systemd[1]: Reloading. Nov 21 15:38:05 volumio volumio[9743]: Forking 3 albumart workers Nov 21 15:38:05 volumio volumio[9743]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:05 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Nov 21 15:38:05 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Nov 21 15:38:05 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Nov 21 15:38:05 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Nov 21 15:38:05 volumio sudo[9803]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:05 volumio volumio[9743]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 15:38:05 volumio volumio[9743]: info: [1732203485373] CoreMusicLibrary::Adding element Last_100 Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 15:38:05 volumio volumio[9743]: info: [1732203485379] CoreMusicLibrary::Adding element Webradio Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 15:38:05 volumio volumio[9743]: info: ------------------------------------------- Nov 21 15:38:05 volumio volumio[9743]: info: ----- MyVolumio plugins startup ---- Nov 21 15:38:05 volumio volumio[9743]: info: ------------------------------------------- Nov 21 15:38:05 volumio volumio[9743]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 21 15:38:05 volumio volumio[9743]: info: Loading i18n strings for locale zh Nov 21 15:38:05 volumio volumio[9743]: Updating browse sources language Nov 21 15:38:05 volumio volumio[9743]: Cannot find translation for sourceMedia Servers Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::initPlayerControls Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 15:38:05 volumio volumio[9743]: info: BOOT COMPLETED Nov 21 15:38:05 volumio volumio[9743]: [Metrics] CommandRouter: 3s 639.85ms Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:38:05 volumio volumio[9743]: aplay: main:722: audio open error: Device or resource busy Nov 21 15:38:05 volumio volumio[9743]: Cannot play startup sound Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::Close All Modals sent Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::Close All Modals sent Nov 21 15:38:05 volumio volumio[9743]: Express server listening on port 3000 Nov 21 15:38:05 volumio volumio[9743]: [Metrics] WebUI: 3s 988.62ms Nov 21 15:38:05 volumio volumio[9743]: Volumio Calling Home Nov 21 15:38:05 volumio volumio[9743]: info: CoreStateMachine::resetVolumioState Nov 21 15:38:05 volumio volumio[9743]: info: CoreStateMachine::getcurrentVolume Nov 21 15:38:05 volumio volumio[9743]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 15:38:05 volumio volumio[9743]: Starting albumart workers Nov 21 15:38:05 volumio volumio[9743]: Starting albumart workers Nov 21 15:38:05 volumio volumio[9743]: Starting albumart workers Nov 21 15:38:05 volumio volumio[9743]: info: Setting Device type: Raspberry PI Nov 21 15:38:05 volumio volumio[9743]: info: touch_display: systemctl daemon-reload succeeded. Nov 21 15:38:06 volumio sudo[9862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 21 15:38:06 volumio volumio[9743]: info: MPD running with PID3411 Nov 21 15:38:06 volumio volumio[9743]: ,establishing connection Nov 21 15:38:06 volumio sudo[9862]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:06 volumio sudo[9864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Nov 21 15:38:06 volumio sudo[9864]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:06 volumio systemd[1]: Started Volumio Kiosk. Nov 21 15:38:06 volumio sudo[9862]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:06 volumio sudo[9864]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:06 volumio volumio[9743]: info: CoreStateMachine::pushState Nov 21 15:38:06 volumio volumio[9743]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::volumioPushState Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:38:06 volumio volumio[9743]: info: CoreStateMachine::updateTrackBlock Nov 21 15:38:06 volumio volumio[9743]: info: CorePlayQueue::getTrackBlock Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 15:38:06 volumio volumio[9743]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 21 15:38:06 volumio volumio[9743]: info: touch_display: Volumio Kiosk started Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::volumioGetState Nov 21 15:38:06 volumio volumio[9743]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:38:06 volumio volumio[9743]: info: Reloading queue from file Nov 21 15:38:06 volumio volumio[9743]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ado/%E7%8B%82%E8%A8%80/09e8b3fc-31a6-4c49-b28f-a3beb47ab2ef.jpg' Nov 21 15:38:06 volumio volumio[9743]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ado/%E6%AE%8B%E5%A4%A2/ea153d8f-17ae-466b-a2cb-8226276e23e7.jpg' Nov 21 15:38:06 volumio volumio[9743]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ado/Ado%E3%81%AE%E6%AD%8C%E3%81%A3%E3%81%A6%E3%81%BF%E3%81%9F%E3%82%A2%E3%83%AB%E3%83%90%E3%83%A0/ca1ba499-2516-41aa-9efa-31c0169d0360.jpg' Nov 21 15:38:06 volumio volumio[9743]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ado/Ado%20%22Ready%20For%20My%20Show%20Playlist%22/c1de4a66-d9d9-4317-946d-195cf9a30490.jpg' Nov 21 15:38:06 volumio volumio[9743]: error: Failed LSINFO: Error: [50@0] {} No such directory Nov 21 15:38:06 volumio volumio[9743]: Cannot compose Albumart path Nov 21 15:38:06 volumio volumio[9743]: info: CoreStateMachine::pushState Nov 21 15:38:06 volumio volumio[9743]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::volumioPushState Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 21 15:38:06 volumio volumio[9743]: info: CoreStateMachine::setRepeat null single undefined Nov 21 15:38:06 volumio volumio[9743]: info: CoreStateMachine::pushState Nov 21 15:38:06 volumio volumio[9743]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::volumioPushState Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:38:06 volumio volumio[9743]: info: CoreStateMachine::setRandom null Nov 21 15:38:06 volumio volumio[9743]: info: CoreStateMachine::pushState Nov 21 15:38:06 volumio volumio[9743]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::volumioPushState Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:38:06 volumio volumio[9743]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 21 15:38:06 volumio volumio[9743]: info: Starting Shairport Sync Nov 21 15:38:06 volumio volumio[9743]: info: Starting Shairport Sync Nov 21 15:38:06 volumio sudo[9915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 15:38:06 volumio sudo[9915]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:06 volumio sudo[9918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 15:38:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 21 15:38:06 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 21 15:38:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 21 15:38:06 volumio sudo[9915]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:06 volumio sudo[9918]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:06 volumio volumio[9743]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Nov 21 15:38:06 volumio volumio[9743]: Discovery: adding 453aa665-c5eb-4874-bc1a-be8764e596bb Nov 21 15:38:06 volumio volumio[9743]: info: mDNS: Found device Volumio Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::volumioGetState Nov 21 15:38:06 volumio volumio[9743]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::volumioGetState Nov 21 15:38:06 volumio volumio[9743]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 21 15:38:06 volumio volumio[9743]: info: Volumio called home Nov 21 15:38:06 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 21 15:38:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 21 15:38:06 volumio sudo[9918]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:06 volumio sudo[9944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Option "TransformationMatrix"/d /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 21 15:38:06 volumio sudo[9944]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:06 volumio sudo[9944]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:06 volumio volumio[9743]: info: Shairport-Sync Started Nov 21 15:38:06 volumio volumio[9743]: Error adding Membership: Error: addMembership EINVAL Nov 21 15:38:06 volumio volumio[9743]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 21 15:38:06 volumio volumio[9743]: info: Shairport-Sync Started Nov 21 15:38:06 volumio volumio[9743]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 21 15:38:06 volumio volumio[9743]: info: CoreCommandRouter::volumioGetState Nov 21 15:38:06 volumio volumio[9743]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:06 volumio volumio[9743]: info: touch_display: Touchscreen transformation matrix removed. Nov 21 15:38:07 volumio sudo[9972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Identifier "Touch rotation"/a\ Option "TransformationMatrix" "-1 0 1 0 -1 1 0 0 1" /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 21 15:38:07 volumio sudo[9972]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:07 volumio sudo[9972]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:07 volumio volumio[9743]: info: touch_display: Touchscreen transformation matrix written. Nov 21 15:38:07 volumio volumio[9743]: info: CoreCommandRouter::volumioGetState Nov 21 15:38:07 volumio volumio[9743]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:07 volumio volumio[9743]: info: CALLMETHOD: user_interface websocket broadcastMessage [object Object] Nov 21 15:38:07 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Nov 21 15:38:07 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 21 15:38:07 volumio volumio[9743]: info: CoreCommandRouter::volumioGetVisibleSources Nov 21 15:38:07 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 15:38:07 volumio volumio[9743]: info: Listing playlists Nov 21 15:38:07 volumio volumio[9743]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:38:07 volumio volumio[9743]: info: CoreStateMachine::getQueue Nov 21 15:38:07 volumio volumio[9743]: info: CorePlayQueue::getQueue Nov 21 15:38:08 volumio volumio[9743]: info: CoreCommandRouter::volumioGetState Nov 21 15:38:08 volumio volumio[9743]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:09 volumio volumio[9743]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Nov 21 15:38:09 volumio volumio[9743]: info: CoreCommandRouter::volumioGetState Nov 21 15:38:09 volumio volumio[9743]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:09 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 21 15:38:09 volumio volumio[9743]: info: Listing playlists Nov 21 15:38:09 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:38:09 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:09 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:14 volumio sudo[9998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 15:38:14 volumio sudo[9998]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:14 volumio sudo[9998]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:14 volumio sudo[10001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 15:38:14 volumio sudo[10001]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:14 volumio sudo[10001]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:14 volumio sudo[10009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 21 15:38:14 volumio sudo[10009]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:14 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 21 15:38:14 volumio sudo[10009]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:14 volumio volumio[9743]: info: Upmpdcli Daemon Started Nov 21 15:38:14 volumio volumio[9743]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 21 15:38:14 volumio volumio[9743]: info: Adding plugin bluetooth to MyMusic Plugins Nov 21 15:38:14 volumio volumio[9743]: info: Adding plugin cd_controller to MyMusic Plugins Nov 21 15:38:14 volumio volumio[9743]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 21 15:38:14 volumio volumio[9743]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 21 15:38:14 volumio volumio[9743]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 21 15:38:15 volumio volumio[9743]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 21 15:38:15 volumio volumio[9743]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 21 15:38:15 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:38:15 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:38:15 volumio volumio[9743]: info: Starting MyVolumio Remote Streaming Endpoints Nov 21 15:38:15 volumio volumio[9743]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 21 15:38:15 volumio volumio[9743]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 21 15:38:15 volumio volumio[9743]: info: Streaming services startup Nov 21 15:38:15 volumio volumio[9743]: info: Starting Streaming Daemon Nov 21 15:38:15 volumio sudo[10025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 21 15:38:15 volumio sudo[10025]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:15 volumio systemd[1]: Stopping Volumio Streaming Daemon... Nov 21 15:38:15 volumio volumio[9743]: info: MyVolumio not started Nov 21 15:38:15 volumio volumio[9743]: info: Initializing device activation check Nov 21 15:38:15 volumio systemd[1]: Starting Volumio Streaming Daemon... Nov 21 15:38:15 volumio systemd[1]: Started Volumio Streaming Daemon. Nov 21 15:38:15 volumio volumio[9743]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 21 15:38:15 volumio sudo[10025]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:15 volumio volumio-streaming-daemon[10035]: ############################ Nov 21 15:38:15 volumio volumio-streaming-daemon[10035]: # Volumio Streaming Daemon # Nov 21 15:38:15 volumio volumio-streaming-daemon[10035]: # Running on port 7777 # Nov 21 15:38:15 volumio volumio-streaming-daemon[10035]: ############################ Nov 21 15:38:15 volumio volumio-streaming-daemon[10035]: Environment: production Nov 21 15:38:17 volumio volumio-streaming-daemon[10035]: Environment: production Nov 21 15:38:17 volumio volumio[9743]: info: Fetching Streaming Services browse cache Nov 21 15:38:19 volumio volumio[9743]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Nov 21 15:38:24 volumio volumio[9743]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 21 15:38:24 volumio volumio[9743]: info: AutoStart - getting queue Nov 21 15:38:24 volumio volumio[9743]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:38:24 volumio volumio[9743]: info: CoreStateMachine::getQueue Nov 21 15:38:24 volumio volumio[9743]: info: CorePlayQueue::getQueue Nov 21 15:38:24 volumio volumio[9743]: info: AutoStart - start playing -> queue is not empty Nov 21 15:38:24 volumio volumio[9743]: info: CoreCommandRouter::volumioPlay Nov 21 15:38:24 volumio volumio[9743]: verbose: UNSET VOLATILE Nov 21 15:38:24 volumio volumio[9743]: info: CoreStateMachine::play index 11 Nov 21 15:38:24 volumio volumio[9743]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:38:24 volumio volumio[9743]: info: CoreStateMachine::stop Nov 21 15:38:24 volumio volumio[9743]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:38:24 volumio volumio[9743]: verbose: UNSET VOLATILE Nov 21 15:38:24 volumio volumio[9743]: info: CoreStateMachine::play index undefined Nov 21 15:38:24 volumio volumio[9743]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:38:24 volumio volumio[9743]: info: CorePlayQueue::getTrack 11 Nov 21 15:38:24 volumio volumio[9743]: info: CoreStateMachine::startPlaybackTimer Nov 21 15:38:24 volumio volumio[9743]: info: CorePlayQueue::getTrack 11 Nov 21 15:38:24 volumio volumio[9743]: verbose: ControllerMpd::clearAddPlayTracks NAS/鉴听女声天碟CD1/12 Some Other Time.m4a Nov 21 15:38:24 volumio volumio[9743]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 15:38:25 volumio volumio[9743]: info: Nov 21 15:38:25 volumio volumio[9743]: ---------------------------- MPD announces state update: player Nov 21 15:38:25 volumio volumio[9743]: info: sendMpdCommand stop took 228 milliseconds Nov 21 15:38:25 volumio volumio[9743]: info: ControllerMpd::getState Nov 21 15:38:25 volumio volumio[9743]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:38:25 volumio volumio[9743]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 15:38:25 volumio volumio[9743]: info: sendMpdCommand status took 2 milliseconds Nov 21 15:38:25 volumio volumio[9743]: verbose: ControllerMpd::parseState Nov 21 15:38:25 volumio volumio[9743]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:38:25 volumio volumio[9743]: info: Nov 21 15:38:25 volumio volumio[9743]: ---------------------------- MPD announces system playlist update Nov 21 15:38:25 volumio volumio[9743]: info: Ignoring MPD Status Update Nov 21 15:38:25 volumio volumio[9743]: info: sendMpdCommand clear took 5 milliseconds Nov 21 15:38:25 volumio volumio[9743]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 21 15:38:25 volumio volumio[9743]: verbose: ControllerMpd::sendMpdCommand add "NAS/鉴听女声天碟CD1/12 Some Other Time.m4a" Nov 21 15:38:25 volumio volumio[9743]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:38:25 volumio volumio[9743]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 21 15:38:25 volumio volumio[9743]: info: ------------------------------ 11ms Nov 21 15:38:25 volumio volumio[9743]: error: updateQueue error: null Nov 21 15:38:25 volumio volumio[9743]: info: Nov 21 15:38:25 volumio volumio[9743]: ---------------------------- MPD announces system playlist update Nov 21 15:38:25 volumio volumio[9743]: info: Ignoring MPD Status Update Nov 21 15:38:25 volumio volumio[9743]: info: ------------------------------ 6ms Nov 21 15:38:25 volumio volumio[9743]: info: sendMpdCommand add "NAS/鉴听女声天碟CD1/12 Some Other Time.m4a" took 5 milliseconds Nov 21 15:38:25 volumio volumio[9743]: verbose: ControllerMpd::sendMpdCommand play Nov 21 15:38:25 volumio volumio[9743]: info: ------------------------------ 2ms Nov 21 15:38:25 volumio volumio[9743]: info: sendMpdCommand play took 1 milliseconds Nov 21 15:38:25 volumio volumio[9743]: info: Nov 21 15:38:25 volumio volumio[9743]: ---------------------------- MPD announces state update: player Nov 21 15:38:25 volumio volumio[9743]: info: ControllerMpd::getState Nov 21 15:38:25 volumio volumio[9743]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:38:26 volumio volumio[9743]: info: sendMpdCommand status took 928 milliseconds Nov 21 15:38:26 volumio volumio[9743]: verbose: ControllerMpd::parseState Nov 21 15:38:26 volumio volumio[9743]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:38:26 volumio volumio[9743]: info: Nov 21 15:38:26 volumio volumio[9743]: ---------------------------- MPD announces state update: player Nov 21 15:38:26 volumio volumio[9743]: info: ControllerMpd::getState Nov 21 15:38:26 volumio volumio[9743]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:38:26 volumio volumio[9743]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 21 15:38:26 volumio volumio[9743]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:38:26 volumio volumio[9743]: info: ControllerMpd::pushState Nov 21 15:38:26 volumio volumio[9743]: info: CoreCommandRouter::servicePushState Nov 21 15:38:26 volumio volumio[9743]: info: CorePlayQueue::getTrack 11 Nov 21 15:38:26 volumio volumio[9743]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"887 Kbps","isStreaming":false,"title":"Some Other Time","artist":"Lovisa","album":"鉴听女声天碟CD1","uri":"NAS/鉴听女声天碟CD1/12 Some Other Time.m4a","trackType":"m4a"} Nov 21 15:38:26 volumio volumio[9743]: verbose: CURRENT POSITION 11 Nov 21 15:38:26 volumio volumio[9743]: info: CoreStateMachine::syncState stateService play Nov 21 15:38:26 volumio volumio[9743]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:38:26 volumio volumio[9743]: info: CoreStateMachine::pushState Nov 21 15:38:26 volumio volumio[9743]: info: CorePlayQueue::getTrack 11 Nov 21 15:38:26 volumio volumio[9743]: info: CoreCommandRouter::volumioPushState Nov 21 15:38:26 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:38:26 volumio volumio[9743]: info: ------------------------------ 949ms Nov 21 15:38:26 volumio volumio[9743]: info: sendMpdCommand status took 17 milliseconds Nov 21 15:38:26 volumio volumio[9743]: verbose: ControllerMpd::parseState Nov 21 15:38:26 volumio volumio[9743]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:38:26 volumio volumio[9743]: info: sendMpdCommand playlistinfo took 25 milliseconds Nov 21 15:38:26 volumio volumio[9743]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:38:26 volumio volumio[9743]: info: ControllerMpd::pushState Nov 21 15:38:26 volumio volumio[9743]: info: CoreCommandRouter::servicePushState Nov 21 15:38:26 volumio volumio[9743]: info: CorePlayQueue::getTrack 11 Nov 21 15:38:26 volumio volumio[9743]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"887 Kbps","isStreaming":false,"title":"Some Other Time","artist":"Lovisa","album":"鉴听女声天碟CD1","uri":"NAS/鉴听女声天碟CD1/12 Some Other Time.m4a","trackType":"m4a"} Nov 21 15:38:26 volumio volumio[9743]: verbose: CURRENT POSITION 11 Nov 21 15:38:26 volumio volumio[9743]: info: CoreStateMachine::syncState stateService play Nov 21 15:38:26 volumio volumio[9743]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:38:26 volumio volumio[9743]: info: Received an update from plugin. extracting info from payload Nov 21 15:38:26 volumio volumio[9743]: info: CoreStateMachine::pushState Nov 21 15:38:26 volumio volumio[9743]: info: CorePlayQueue::getTrack 11 Nov 21 15:38:26 volumio volumio[9743]: info: CoreCommandRouter::volumioPushState Nov 21 15:38:26 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:38:26 volumio volumio[9743]: info: CoreStateMachine::pushState Nov 21 15:38:26 volumio volumio[9743]: info: CorePlayQueue::getTrack 11 Nov 21 15:38:26 volumio volumio[9743]: info: CoreCommandRouter::volumioPushState Nov 21 15:38:26 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:38:26 volumio volumio[9743]: info: ------------------------------ 59ms Nov 21 15:38:26 volumio volumio[9743]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 21 15:38:35 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 15:38:35 volumio volumio[9743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:38:39 volumio volumio[9743]: info: CoreCommandRouter::Close All Modals sent Nov 21 15:38:39 volumio volumio[9743]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 15:38:39 volumio volumio[9743]: { Error: connect ETIMEDOUT 103.73.161.52:443 Nov 21 15:38:39 volumio volumio[9743]: at Object._errnoException (util.js:1022:11) Nov 21 15:38:39 volumio volumio[9743]: at _exceptionWithHostPort (util.js:1044:20) Nov 21 15:38:39 volumio volumio[9743]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Nov 21 15:38:39 volumio volumio[9743]: code: 'ETIMEDOUT', Nov 21 15:38:39 volumio volumio[9743]: errno: 'ETIMEDOUT', Nov 21 15:38:39 volumio volumio[9743]: syscall: 'connect', Nov 21 15:38:39 volumio volumio[9743]: address: '103.73.161.52', Nov 21 15:38:39 volumio volumio[9743]: port: 443 } Nov 21 15:38:39 volumio volumio[9743]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 15:38:39 volumio sudo[10127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 15:37 Nov 21 15:38:39 volumio sudo[10127]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:39 volumio sudo[10127]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:39 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Nov 21 15:38:39 volumio systemd[1]: Unit volumio.service entered failed state. Nov 21 15:38:39 volumio systemd[1]: Starting dynamicswap service... Nov 21 15:38:39 volumio systemd[1]: Started dynamicswap service. Nov 21 15:38:40 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Nov 21 15:38:40 volumio systemd[1]: Starting dynamicswap service... Nov 21 15:38:40 volumio systemd[1]: Started dynamicswap service. Nov 21 15:38:40 volumio systemd[1]: Stopping Volumio Backend Module... Nov 21 15:38:40 volumio systemd[1]: Starting Volumio Backend Module... Nov 21 15:38:40 volumio systemd[1]: Started Volumio Backend Module. Nov 21 15:38:41 volumio volumio[10148]: info: ------------------------------------------- Nov 21 15:38:41 volumio volumio[10148]: info: ----- Volumio2 ---- Nov 21 15:38:41 volumio volumio[10148]: info: ------------------------------------------- Nov 21 15:38:41 volumio volumio[10148]: info: ----- System startup ---- Nov 21 15:38:41 volumio volumio[10148]: info: ------------------------------------------- Nov 21 15:38:41 volumio volumio[10148]: info: MYVOLUMIO Environment detected Nov 21 15:38:41 volumio volumio[10148]: info: Plugin folders cleanup Nov 21 15:38:41 volumio volumio[10148]: info: Scanning into folder /volumio/app/plugins/ Nov 21 15:38:41 volumio volumio[10148]: info: Scanning category audio_interface Nov 21 15:38:41 volumio volumio[10148]: info: Scanning category miscellanea Nov 21 15:38:41 volumio volumio[10148]: info: Scanning category music_service Nov 21 15:38:41 volumio volumio[10148]: info: Scanning category plugins.json Nov 21 15:38:41 volumio volumio[10148]: info: Scanning category system_controller Nov 21 15:38:41 volumio volumio[10148]: info: Scanning category user_interface Nov 21 15:38:41 volumio volumio[10148]: info: Scanning into folder /data/plugins/ Nov 21 15:38:41 volumio volumio[10148]: info: Scanning category miscellanea Nov 21 15:38:41 volumio volumio[10148]: info: Plugin folders cleanup completed Nov 21 15:38:41 volumio volumio[10148]: info: ------------------------------------------- Nov 21 15:38:41 volumio volumio[10148]: info: ----- Core plugins startup ---- Nov 21 15:38:41 volumio volumio[10148]: info: ------------------------------------------- Nov 21 15:38:41 volumio volumio[10148]: info: Loading plugins from folder /volumio/app/plugins/ Nov 21 15:38:41 volumio volumio[10148]: info: Adding plugin upnp to MyMusic Plugins Nov 21 15:38:41 volumio volumio[10148]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 21 15:38:41 volumio volumio[10148]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 21 15:38:41 volumio volumio[10148]: info: Loading plugins from folder /data/plugins/ Nov 21 15:38:41 volumio volumio[10148]: info: Loading plugin "system"... Nov 21 15:38:41 volumio volumio[10148]: info: Loading plugin "appearance"... Nov 21 15:38:42 volumio volumio[10148]: info: Loading plugin "network"... Nov 21 15:38:42 volumio volumio[10148]: info: Refreshing Cached IP Addresses Nov 21 15:38:42 volumio sudo[10174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 15:38:42 volumio volumio[10148]: info: Loading plugin "touch_display"... Nov 21 15:38:42 volumio sudo[10174]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:42 volumio sudo[10174]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:42 volumio sudo[10177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 15:38:42 volumio sudo[10177]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:42 volumio sudo[10177]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:43 volumio volumio[10148]: info: Applying required configuration parameters for plugin touch_display Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "services"... Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "alsa_controller"... Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "wizard"... Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "volumio_command_line_client"... Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "upnp"... Nov 21 15:38:43 volumio volumio[10148]: info: [1732203523205] Starting Upmpd Daemon Nov 21 15:38:43 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "my_music"... Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "mpd"... Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "upnp_browser"... Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "networkfs"... Nov 21 15:38:43 volumio volumio[10148]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Nov 21 15:38:43 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "alarm-clock"... Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "airplay_emulation"... Nov 21 15:38:43 volumio volumio[10148]: info: Starting Shairport Sync Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "last_100"... Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "webradio"... Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "i2s_dacs"... Nov 21 15:38:43 volumio volumio[10148]: info: I2S DAC not set, start Auto-detection Nov 21 15:38:43 volumio volumio[10148]: info: Loading plugin "volumiodiscovery"... Nov 21 15:38:44 volumio volumio[10148]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 15:38:44 volumio node[10148]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 15:38:44 volumio node[10148]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 15:38:44 volumio node[10148]: *** WARNING *** For more information see Nov 21 15:38:44 volumio node[10148]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 15:38:44 volumio node[10148]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 15:38:44 volumio node[10148]: *** WARNING *** For more information see Nov 21 15:38:44 volumio volumio[10148]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 15:38:44 volumio volumio[10148]: *** WARNING *** For more information see Nov 21 15:38:44 volumio volumio[10148]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 15:38:44 volumio volumio[10148]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 15:38:44 volumio volumio[10148]: *** WARNING *** For more information see Nov 21 15:38:44 volumio volumio[10148]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 21 15:38:44 volumio volumio[10148]: Discovery: StartAdv! undefined Nov 21 15:38:44 volumio volumio[10148]: Discovery: Started advertising... Volumio - undefined Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 15:38:44 volumio volumio[10148]: info: Loading plugin "autostart"... Nov 21 15:38:44 volumio volumio[10148]: info: Applying required configuration parameters for plugin autostart Nov 21 15:38:44 volumio volumio[10148]: info: Loading plugin "outputs"... Nov 21 15:38:44 volumio volumio[10148]: info: Loading plugin "albumart"... Nov 21 15:38:44 volumio volumio[10148]: info: Plugin example_plugin is not enabled Nov 21 15:38:44 volumio volumio[10148]: info: Loading plugin "inputs"... Nov 21 15:38:44 volumio volumio[10148]: info: Loading plugin "updater_comm"... Nov 21 15:38:44 volumio volumio[10148]: info: Plugin mpdemulation is not enabled Nov 21 15:38:44 volumio volumio[10148]: info: Loading plugin "rest_api"... Nov 21 15:38:44 volumio volumio[10148]: info: Loading plugin "websocket"... Nov 21 15:38:44 volumio volumio[10148]: info: ___________ START PLUGINS ___________ Nov 21 15:38:44 volumio volumio[10148]: info: Loading i18n strings for locale zh Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 15:38:44 volumio volumio[10148]: info: [1732203524327] CoreMusicLibrary::Adding element Media Servers Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 15:38:44 volumio sudo[10208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 21 15:38:44 volumio sudo[10208]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:44 volumio systemd[1]: Reloading. Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:44 volumio volumio[10148]: Forking 3 albumart workers Nov 21 15:38:44 volumio volumio[10148]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:44 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Nov 21 15:38:44 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Nov 21 15:38:44 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Nov 21 15:38:44 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Nov 21 15:38:44 volumio volumio[10148]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 15:38:44 volumio volumio[10148]: info: [1732203524491] CoreMusicLibrary::Adding element Last_100 Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 15:38:44 volumio volumio[10148]: info: [1732203524495] CoreMusicLibrary::Adding element Webradio Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 15:38:44 volumio volumio[10148]: info: ------------------------------------------- Nov 21 15:38:44 volumio volumio[10148]: info: ----- MyVolumio plugins startup ---- Nov 21 15:38:44 volumio volumio[10148]: info: ------------------------------------------- Nov 21 15:38:44 volumio volumio[10148]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 21 15:38:44 volumio volumio[10148]: info: Loading i18n strings for locale zh Nov 21 15:38:44 volumio volumio[10148]: Updating browse sources language Nov 21 15:38:44 volumio volumio[10148]: Cannot find translation for sourceMedia Servers Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 15:38:44 volumio sudo[10208]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::initPlayerControls Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 15:38:44 volumio volumio[10148]: info: BOOT COMPLETED Nov 21 15:38:44 volumio volumio[10148]: [Metrics] CommandRouter: 3s 472.48ms Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:38:44 volumio volumio[10148]: aplay: main:722: audio open error: Device or resource busy Nov 21 15:38:44 volumio volumio[10148]: Cannot play startup sound Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::Close All Modals sent Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::Close All Modals sent Nov 21 15:38:44 volumio volumio[10148]: Express server listening on port 3000 Nov 21 15:38:44 volumio volumio[10148]: [Metrics] WebUI: 3s 829.32ms Nov 21 15:38:44 volumio volumio[10148]: Volumio Calling Home Nov 21 15:38:44 volumio volumio[10148]: info: CoreStateMachine::resetVolumioState Nov 21 15:38:44 volumio volumio[10148]: info: CoreStateMachine::getcurrentVolume Nov 21 15:38:44 volumio volumio[10148]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 15:38:45 volumio volumio[10148]: Starting albumart workers Nov 21 15:38:45 volumio volumio[10148]: Starting albumart workers Nov 21 15:38:45 volumio volumio[10148]: Starting albumart workers Nov 21 15:38:45 volumio volumio[10148]: info: Setting Device type: Raspberry PI Nov 21 15:38:45 volumio volumio[10148]: info: touch_display: systemctl daemon-reload succeeded. Nov 21 15:38:45 volumio volumio[10148]: info: MPD running with PID3411 Nov 21 15:38:45 volumio volumio[10148]: ,establishing connection Nov 21 15:38:45 volumio sudo[10267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 21 15:38:45 volumio sudo[10267]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:45 volumio sudo[10268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Nov 21 15:38:45 volumio sudo[10268]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:45 volumio systemd[1]: Started Volumio Kiosk. Nov 21 15:38:45 volumio sudo[10267]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:45 volumio sudo[10268]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:45 volumio volumio[10148]: info: CoreStateMachine::pushState Nov 21 15:38:45 volumio volumio[10148]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::volumioPushState Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:38:45 volumio volumio[10148]: info: CoreStateMachine::updateTrackBlock Nov 21 15:38:45 volumio volumio[10148]: info: CorePlayQueue::getTrackBlock Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 15:38:45 volumio volumio[10148]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 21 15:38:45 volumio volumio[10148]: info: touch_display: Volumio Kiosk started Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::volumioGetState Nov 21 15:38:45 volumio volumio[10148]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:45 volumio volumio[10148]: info: Volumio called home Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:38:45 volumio volumio[10148]: info: Reloading queue from file Nov 21 15:38:45 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ivoris/I%20Wish%20My%20Mind%20Would%20Shut%20Up/3cfe1dae-c785-415f-9504-73635152854f.jpg' Nov 21 15:38:45 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/I%20Don%27t%20Like%20Mondays./Zero%20Gravity/8050fab7-821d-49f3-bad3-42defa18c9f4.jpg' Nov 21 15:38:45 volumio volumio[10148]: error: Failed LSINFO: Error: [50@0] {} No such directory Nov 21 15:38:45 volumio volumio[10148]: Cannot compose Albumart path Nov 21 15:38:45 volumio volumio[10148]: info: CoreStateMachine::pushState Nov 21 15:38:45 volumio volumio[10148]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::volumioPushState Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 21 15:38:45 volumio volumio[10148]: info: CoreStateMachine::setRepeat null single undefined Nov 21 15:38:45 volumio volumio[10148]: info: CoreStateMachine::pushState Nov 21 15:38:45 volumio volumio[10148]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::volumioPushState Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:38:45 volumio volumio[10148]: info: CoreStateMachine::setRandom null Nov 21 15:38:45 volumio volumio[10148]: info: CoreStateMachine::pushState Nov 21 15:38:45 volumio volumio[10148]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::volumioPushState Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:38:45 volumio volumio[10148]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 21 15:38:45 volumio volumio[10148]: info: Starting Shairport Sync Nov 21 15:38:45 volumio volumio[10148]: info: Starting Shairport Sync Nov 21 15:38:45 volumio sudo[10321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 15:38:45 volumio sudo[10321]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:45 volumio volumio[10148]: info: CoreCommandRouter::volumioGetState Nov 21 15:38:45 volumio sudo[10324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 15:38:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 21 15:38:45 volumio volumio[10148]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:45 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 21 15:38:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 21 15:38:45 volumio sudo[10324]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:45 volumio volumio[10148]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Nov 21 15:38:45 volumio sudo[10321]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:46 volumio volumio[10148]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 21 15:38:46 volumio sudo[10346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Option "TransformationMatrix"/d /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 21 15:38:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 21 15:38:46 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 21 15:38:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 21 15:38:46 volumio sudo[10324]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:46 volumio volumio[10148]: Discovery: adding 453aa665-c5eb-4874-bc1a-be8764e596bb Nov 21 15:38:46 volumio volumio[10148]: info: mDNS: Found device Volumio Nov 21 15:38:46 volumio sudo[10346]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:46 volumio volumio[10148]: info: CoreCommandRouter::volumioGetState Nov 21 15:38:46 volumio volumio[10148]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:46 volumio sudo[10346]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:46 volumio volumio[10148]: info: Shairport-Sync Started Nov 21 15:38:46 volumio volumio[10148]: Error adding Membership: Error: addMembership EINVAL Nov 21 15:38:46 volumio volumio[10148]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 21 15:38:46 volumio volumio[10148]: info: Shairport-Sync Started Nov 21 15:38:46 volumio volumio[10148]: info: CoreCommandRouter::volumioGetState Nov 21 15:38:46 volumio volumio[10148]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:46 volumio volumio[10148]: info: touch_display: Touchscreen transformation matrix removed. Nov 21 15:38:46 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/SIRUP/FEEL%20GOOD/5a629393-762e-445d-acd0-8b364dae1b64.jpg' Nov 21 15:38:46 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/nonoc/Memento/a4de108c-b246-4fa0-ac15-a014884eaa6c.png' Nov 21 15:38:46 volumio sudo[10376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Identifier "Touch rotation"/a\ Option "TransformationMatrix" "-1 0 1 0 -1 1 0 0 1" /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 21 15:38:46 volumio sudo[10376]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:46 volumio sudo[10376]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:46 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/SIRUP/FEEL%20GOOD/5a629393-762e-445d-acd0-8b364dae1b64.jpg' Nov 21 15:38:46 volumio volumio[10148]: info: touch_display: Touchscreen transformation matrix written. Nov 21 15:38:46 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/SIRUP/FEEL%20GOOD/5a629393-762e-445d-acd0-8b364dae1b64.jpg' Nov 21 15:38:46 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/MADKID/CIRCUS/0d7caa83-1e02-4700-a604-58d631f0c652.jpg' Nov 21 15:38:46 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/nonoc/Believe%20in%20you/10506bf6-346b-4662-903d-d4de75d34d5f.png' Nov 21 15:38:46 volumio volumio[10148]: info: CALLMETHOD: user_interface websocket broadcastMessage [object Object] Nov 21 15:38:46 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Nov 21 15:38:47 volumio volumio[10148]: info: CoreCommandRouter::volumioGetState Nov 21 15:38:47 volumio volumio[10148]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:48 volumio volumio[10148]: info: CoreCommandRouter::volumioGetState Nov 21 15:38:48 volumio volumio[10148]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:48 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 21 15:38:48 volumio volumio[10148]: info: CoreCommandRouter::volumioGetVisibleSources Nov 21 15:38:48 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 15:38:48 volumio volumio[10148]: info: Listing playlists Nov 21 15:38:48 volumio volumio[10148]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:38:48 volumio volumio[10148]: info: CoreStateMachine::getQueue Nov 21 15:38:48 volumio volumio[10148]: info: CorePlayQueue::getQueue Nov 21 15:38:48 volumio volumio[10148]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Nov 21 15:38:48 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:38:48 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:48 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:38:49 volumio volumio[10148]: info: CoreCommandRouter::volumioGetState Nov 21 15:38:49 volumio volumio[10148]: info: CorePlayQueue::getTrack 0 Nov 21 15:38:49 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 21 15:38:49 volumio volumio[10148]: info: Listing playlists Nov 21 15:38:52 volumio volumio[10148]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 15:38:52 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 21 15:38:52 volumio volumio[10148]: info: Received Get System Version Nov 21 15:38:52 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 15:38:53 volumio sudo[10406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 15:38:53 volumio sudo[10406]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:53 volumio sudo[10406]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:53 volumio sudo[10409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 15:38:53 volumio sudo[10409]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:53 volumio sudo[10409]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:53 volumio sudo[10420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 21 15:38:53 volumio sudo[10420]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:53 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 21 15:38:53 volumio sudo[10420]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:53 volumio volumio[10148]: info: Upmpdcli Daemon Started Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 21 15:38:53 volumio volumio[10148]: info: Adding plugin bluetooth to MyMusic Plugins Nov 21 15:38:53 volumio volumio[10148]: info: Adding plugin cd_controller to MyMusic Plugins Nov 21 15:38:53 volumio volumio[10148]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 21 15:38:53 volumio volumio[10148]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 21 15:38:53 volumio volumio[10148]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 21 15:38:54 volumio volumio[10148]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 21 15:38:54 volumio volumio[10148]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 21 15:38:54 volumio volumio[10148]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 21 15:38:54 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:38:54 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:38:54 volumio volumio[10148]: info: Starting MyVolumio Remote Streaming Endpoints Nov 21 15:38:54 volumio volumio[10148]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 21 15:38:54 volumio volumio[10148]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 21 15:38:54 volumio volumio[10148]: info: Streaming services startup Nov 21 15:38:54 volumio volumio[10148]: info: Starting Streaming Daemon Nov 21 15:38:54 volumio sudo[10433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 21 15:38:54 volumio sudo[10433]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:38:54 volumio systemd[1]: Stopping Volumio Streaming Daemon... Nov 21 15:38:54 volumio systemd[1]: Starting Volumio Streaming Daemon... Nov 21 15:38:54 volumio systemd[1]: Started Volumio Streaming Daemon. Nov 21 15:38:54 volumio volumio[10148]: info: MyVolumio not started Nov 21 15:38:54 volumio volumio[10148]: info: Initializing device activation check Nov 21 15:38:54 volumio sudo[10433]: pam_unix(sudo:session): session closed for user root Nov 21 15:38:54 volumio volumio[10148]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 21 15:38:54 volumio volumio-streaming-daemon[10442]: ############################ Nov 21 15:38:54 volumio volumio-streaming-daemon[10442]: # Volumio Streaming Daemon # Nov 21 15:38:54 volumio volumio-streaming-daemon[10442]: # Running on port 7777 # Nov 21 15:38:54 volumio volumio-streaming-daemon[10442]: ############################ Nov 21 15:38:54 volumio volumio-streaming-daemon[10442]: Environment: production Nov 21 15:38:54 volumio volumio[10148]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Nov 21 15:38:56 volumio volumio-streaming-daemon[10442]: Environment: production Nov 21 15:38:56 volumio volumio[10148]: info: Fetching Streaming Services browse cache Nov 21 15:38:59 volumio volumio[10148]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Nov 21 15:39:01 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 21 15:39:01 volumio volumio[10148]: info: CURURI: artists:// Nov 21 15:39:02 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/96%E7%8C%AB/f49d5329-bbe8-4682-bd7e-2d04ecbafff0.jpg' Nov 21 15:39:02 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ARTY/5347cc88-95ee-4152-952b-4949689caa01.jpg' Nov 21 15:39:02 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ATB/026f2693-c9bf-4dd8-b57b-ed49457c5b3e.jpg' Nov 21 15:39:02 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/2002/a2ac81f5-ab3f-48e8-a5e5-12ec01ae754e.jpg' Nov 21 15:39:02 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bill%20Elm%20and%20Woody%20Jackson/b6f87353-78b4-4246-96d3-2bb3e07eb4bc.jpg' Nov 21 15:39:02 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Billie%20Eilish/bdc0c171-8ddd-4eb6-af02-1b899f193119.jpg' Nov 21 15:39:02 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 21 15:39:02 volumio volumio[10148]: info: CURURI: music-library Nov 21 15:39:02 volumio volumio[10148]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Billy%20Crockett/2874bea8-f30e-4e10-b42d-73cc92decaeb.jpg' Nov 21 15:39:03 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 21 15:39:03 volumio volumio[10148]: info: CURURI: artists:// Nov 21 15:39:04 volumio volumio[10148]: info: AutoStart - getting queue Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::getQueue Nov 21 15:39:04 volumio volumio[10148]: info: CorePlayQueue::getQueue Nov 21 15:39:04 volumio volumio[10148]: info: AutoStart - start playing -> queue is not empty Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::volumioPlay Nov 21 15:39:04 volumio volumio[10148]: verbose: UNSET VOLATILE Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::play index 11 Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::stop Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:39:04 volumio volumio[10148]: verbose: UNSET VOLATILE Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::play index undefined Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:39:04 volumio volumio[10148]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::startPlaybackTimer Nov 21 15:39:04 volumio volumio[10148]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::clearAddPlayTracks NAS/鉴听女声天碟CD1/12 Some Other Time.m4a Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 15:39:04 volumio volumio[10148]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 21 15:39:04 volumio volumio[10148]: info: sendMpdCommand stop took 160 milliseconds Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 15:39:04 volumio volumio[10148]: info: Nov 21 15:39:04 volumio volumio[10148]: ---------------------------- MPD announces state update: player Nov 21 15:39:04 volumio volumio[10148]: info: ControllerMpd::getState Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:39:04 volumio volumio[10148]: info: Nov 21 15:39:04 volumio volumio[10148]: ---------------------------- MPD announces system playlist update Nov 21 15:39:04 volumio volumio[10148]: info: Ignoring MPD Status Update Nov 21 15:39:04 volumio volumio[10148]: info: sendMpdCommand clear took 4 milliseconds Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::sendMpdCommand add "NAS/鉴听女声天碟CD1/12 Some Other Time.m4a" Nov 21 15:39:04 volumio volumio[10148]: error: updateQueue error: null Nov 21 15:39:04 volumio volumio[10148]: info: Nov 21 15:39:04 volumio volumio[10148]: ---------------------------- MPD announces system playlist update Nov 21 15:39:04 volumio volumio[10148]: info: Ignoring MPD Status Update Nov 21 15:39:04 volumio volumio[10148]: info: sendMpdCommand status took 5 milliseconds Nov 21 15:39:04 volumio volumio[10148]: info: ------------------------------ 4ms Nov 21 15:39:04 volumio volumio[10148]: info: sendMpdCommand add "NAS/鉴听女声天碟CD1/12 Some Other Time.m4a" took 4 milliseconds Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::parseState Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::sendMpdCommand play Nov 21 15:39:04 volumio volumio[10148]: info: ControllerMpd::pushState Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::servicePushState Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::pushState Nov 21 15:39:04 volumio volumio[10148]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::volumioPushState Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:39:04 volumio volumio[10148]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:04 volumio volumio[10148]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 21 15:39:04 volumio volumio[10148]: verbose: CURRENT POSITION 11 Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::syncState stateService stop Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::pushState Nov 21 15:39:04 volumio volumio[10148]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::volumioPushState Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:39:04 volumio volumio[10148]: info: No code Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::pushState Nov 21 15:39:04 volumio volumio[10148]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::volumioPushState Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:39:04 volumio volumio[10148]: info: ------------------------------ 29ms Nov 21 15:39:04 volumio volumio[10148]: info: ------------------------------ 25ms Nov 21 15:39:04 volumio volumio[10148]: info: sendMpdCommand play took 22 milliseconds Nov 21 15:39:04 volumio volumio[10148]: info: Nov 21 15:39:04 volumio volumio[10148]: ---------------------------- MPD announces state update: player Nov 21 15:39:04 volumio volumio[10148]: info: ControllerMpd::getState Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:39:04 volumio volumio[10148]: info: sendMpdCommand status took 300 milliseconds Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::parseState Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:39:04 volumio volumio[10148]: info: Nov 21 15:39:04 volumio volumio[10148]: ---------------------------- MPD announces state update: player Nov 21 15:39:04 volumio volumio[10148]: info: ControllerMpd::getState Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:39:04 volumio volumio[10148]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:39:04 volumio volumio[10148]: info: ControllerMpd::pushState Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::servicePushState Nov 21 15:39:04 volumio volumio[10148]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:04 volumio volumio[10148]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"887 Kbps","isStreaming":false,"title":"Some Other Time","artist":"Lovisa","album":"鉴听女声天碟CD1","uri":"NAS/鉴听女声天碟CD1/12 Some Other Time.m4a","trackType":"m4a"} Nov 21 15:39:04 volumio volumio[10148]: verbose: CURRENT POSITION 11 Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::syncState stateService play Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::pushState Nov 21 15:39:04 volumio volumio[10148]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::volumioPushState Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:39:04 volumio volumio[10148]: info: ------------------------------ 313ms Nov 21 15:39:04 volumio volumio[10148]: info: sendMpdCommand status took 13 milliseconds Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::parseState Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:39:04 volumio volumio[10148]: info: sendMpdCommand playlistinfo took 25 milliseconds Nov 21 15:39:04 volumio volumio[10148]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:39:04 volumio volumio[10148]: info: ControllerMpd::pushState Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::servicePushState Nov 21 15:39:04 volumio volumio[10148]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:04 volumio volumio[10148]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"887 Kbps","isStreaming":false,"title":"Some Other Time","artist":"Lovisa","album":"鉴听女声天碟CD1","uri":"NAS/鉴听女声天碟CD1/12 Some Other Time.m4a","trackType":"m4a"} Nov 21 15:39:04 volumio volumio[10148]: verbose: CURRENT POSITION 11 Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::syncState stateService play Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:39:04 volumio volumio[10148]: info: Received an update from plugin. extracting info from payload Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::pushState Nov 21 15:39:04 volumio volumio[10148]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::volumioPushState Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:39:04 volumio volumio[10148]: info: CoreStateMachine::pushState Nov 21 15:39:04 volumio volumio[10148]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::volumioPushState Nov 21 15:39:04 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:39:04 volumio volumio[10148]: info: ------------------------------ 73ms Nov 21 15:39:04 volumio volumio[10148]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 21 15:39:12 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 21 15:39:12 volumio volumio[10148]: info: CURURI: music-library Nov 21 15:39:14 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 15:39:14 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:39:14 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:39:15 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:39:17 volumio volumio[10148]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 21 15:39:18 volumio volumio[10148]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 15:39:18 volumio volumio[10148]: { Error: connect ETIMEDOUT 103.73.161.52:443 Nov 21 15:39:18 volumio volumio[10148]: at Object._errnoException (util.js:1022:11) Nov 21 15:39:18 volumio volumio[10148]: at _exceptionWithHostPort (util.js:1044:20) Nov 21 15:39:18 volumio volumio[10148]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Nov 21 15:39:18 volumio volumio[10148]: code: 'ETIMEDOUT', Nov 21 15:39:18 volumio volumio[10148]: errno: 'ETIMEDOUT', Nov 21 15:39:18 volumio volumio[10148]: syscall: 'connect', Nov 21 15:39:18 volumio volumio[10148]: address: '103.73.161.52', Nov 21 15:39:18 volumio volumio[10148]: port: 443 } Nov 21 15:39:18 volumio volumio[10148]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 15:39:18 volumio sudo[10517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 15:38 Nov 21 15:39:18 volumio sudo[10517]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:39:18 volumio sudo[10517]: pam_unix(sudo:session): session closed for user root Nov 21 15:39:18 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Nov 21 15:39:18 volumio systemd[1]: Unit volumio.service entered failed state. Nov 21 15:39:18 volumio systemd[1]: Starting dynamicswap service... Nov 21 15:39:18 volumio systemd[1]: Started dynamicswap service. Nov 21 15:39:19 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Nov 21 15:39:19 volumio systemd[1]: Starting dynamicswap service... Nov 21 15:39:19 volumio systemd[1]: Started dynamicswap service. Nov 21 15:39:19 volumio systemd[1]: Stopping Volumio Backend Module... Nov 21 15:39:19 volumio systemd[1]: Starting Volumio Backend Module... Nov 21 15:39:19 volumio systemd[1]: Started Volumio Backend Module. Nov 21 15:39:20 volumio volumio[10539]: info: ------------------------------------------- Nov 21 15:39:20 volumio volumio[10539]: info: ----- Volumio2 ---- Nov 21 15:39:20 volumio volumio[10539]: info: ------------------------------------------- Nov 21 15:39:20 volumio volumio[10539]: info: ----- System startup ---- Nov 21 15:39:20 volumio volumio[10539]: info: ------------------------------------------- Nov 21 15:39:20 volumio volumio[10539]: info: MYVOLUMIO Environment detected Nov 21 15:39:20 volumio volumio[10539]: info: Plugin folders cleanup Nov 21 15:39:20 volumio volumio[10539]: info: Scanning into folder /volumio/app/plugins/ Nov 21 15:39:20 volumio volumio[10539]: info: Scanning category audio_interface Nov 21 15:39:20 volumio volumio[10539]: info: Scanning category miscellanea Nov 21 15:39:20 volumio volumio[10539]: info: Scanning category music_service Nov 21 15:39:20 volumio volumio[10539]: info: Scanning category plugins.json Nov 21 15:39:20 volumio volumio[10539]: info: Scanning category system_controller Nov 21 15:39:20 volumio volumio[10539]: info: Scanning category user_interface Nov 21 15:39:20 volumio volumio[10539]: info: Scanning into folder /data/plugins/ Nov 21 15:39:20 volumio volumio[10539]: info: Scanning category miscellanea Nov 21 15:39:20 volumio volumio[10539]: info: Plugin folders cleanup completed Nov 21 15:39:20 volumio volumio[10539]: info: ------------------------------------------- Nov 21 15:39:20 volumio volumio[10539]: info: ----- Core plugins startup ---- Nov 21 15:39:20 volumio volumio[10539]: info: ------------------------------------------- Nov 21 15:39:20 volumio volumio[10539]: info: Loading plugins from folder /volumio/app/plugins/ Nov 21 15:39:20 volumio volumio[10539]: info: Adding plugin upnp to MyMusic Plugins Nov 21 15:39:20 volumio volumio[10539]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 21 15:39:20 volumio volumio[10539]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 21 15:39:20 volumio volumio[10539]: info: Loading plugins from folder /data/plugins/ Nov 21 15:39:20 volumio volumio[10539]: info: Loading plugin "system"... Nov 21 15:39:20 volumio volumio[10539]: info: Loading plugin "appearance"... Nov 21 15:39:22 volumio volumio[10539]: info: Loading plugin "network"... Nov 21 15:39:22 volumio volumio[10539]: info: Refreshing Cached IP Addresses Nov 21 15:39:22 volumio sudo[10563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 15:39:22 volumio volumio[10539]: info: Loading plugin "touch_display"... Nov 21 15:39:22 volumio sudo[10563]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:39:22 volumio sudo[10566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 15:39:22 volumio sudo[10563]: pam_unix(sudo:session): session closed for user root Nov 21 15:39:22 volumio sudo[10566]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:39:22 volumio sudo[10566]: pam_unix(sudo:session): session closed for user root Nov 21 15:39:22 volumio volumio[10539]: info: Applying required configuration parameters for plugin touch_display Nov 21 15:39:22 volumio volumio[10539]: info: Loading plugin "services"... Nov 21 15:39:22 volumio volumio[10539]: info: Loading plugin "alsa_controller"... Nov 21 15:39:22 volumio volumio[10539]: info: Loading plugin "wizard"... Nov 21 15:39:22 volumio volumio[10539]: info: Loading plugin "volumio_command_line_client"... Nov 21 15:39:22 volumio volumio[10539]: info: Loading plugin "upnp"... Nov 21 15:39:22 volumio volumio[10539]: info: [1732203562548] Starting Upmpd Daemon Nov 21 15:39:22 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 15:39:22 volumio volumio[10539]: info: Loading plugin "my_music"... Nov 21 15:39:22 volumio volumio[10539]: info: Loading plugin "mpd"... Nov 21 15:39:22 volumio volumio[10539]: info: Loading plugin "upnp_browser"... Nov 21 15:39:23 volumio volumio[10539]: info: Loading plugin "networkfs"... Nov 21 15:39:23 volumio volumio[10539]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 15:39:23 volumio volumio[10539]: info: Loading plugin "alarm-clock"... Nov 21 15:39:23 volumio volumio[10539]: info: Loading plugin "airplay_emulation"... Nov 21 15:39:23 volumio volumio[10539]: info: Starting Shairport Sync Nov 21 15:39:23 volumio volumio[10539]: info: Loading plugin "last_100"... Nov 21 15:39:23 volumio volumio[10539]: info: Loading plugin "webradio"... Nov 21 15:39:23 volumio volumio[10539]: info: Loading plugin "i2s_dacs"... Nov 21 15:39:23 volumio volumio[10539]: info: I2S DAC not set, start Auto-detection Nov 21 15:39:23 volumio volumio[10539]: info: Loading plugin "volumiodiscovery"... Nov 21 15:39:23 volumio volumio[10539]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 15:39:23 volumio node[10539]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 15:39:23 volumio node[10539]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 15:39:23 volumio node[10539]: *** WARNING *** For more information see Nov 21 15:39:23 volumio node[10539]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 15:39:23 volumio node[10539]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 15:39:23 volumio node[10539]: *** WARNING *** For more information see Nov 21 15:39:23 volumio volumio[10539]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 15:39:23 volumio volumio[10539]: *** WARNING *** For more information see Nov 21 15:39:23 volumio volumio[10539]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 15:39:23 volumio volumio[10539]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 15:39:23 volumio volumio[10539]: *** WARNING *** For more information see Nov 21 15:39:23 volumio volumio[10539]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 21 15:39:23 volumio volumio[10539]: Discovery: StartAdv! undefined Nov 21 15:39:23 volumio volumio[10539]: Discovery: Started advertising... Volumio - undefined Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 15:39:23 volumio volumio[10539]: info: Loading plugin "autostart"... Nov 21 15:39:23 volumio volumio[10539]: info: Applying required configuration parameters for plugin autostart Nov 21 15:39:23 volumio volumio[10539]: info: Loading plugin "outputs"... Nov 21 15:39:23 volumio volumio[10539]: info: Loading plugin "albumart"... Nov 21 15:39:23 volumio volumio[10539]: info: Plugin example_plugin is not enabled Nov 21 15:39:23 volumio volumio[10539]: info: Loading plugin "inputs"... Nov 21 15:39:23 volumio volumio[10539]: info: Loading plugin "updater_comm"... Nov 21 15:39:23 volumio volumio[10539]: info: Plugin mpdemulation is not enabled Nov 21 15:39:23 volumio volumio[10539]: info: Loading plugin "rest_api"... Nov 21 15:39:23 volumio volumio[10539]: info: Loading plugin "websocket"... Nov 21 15:39:23 volumio volumio[10539]: info: ___________ START PLUGINS ___________ Nov 21 15:39:23 volumio volumio[10539]: info: Loading i18n strings for locale zh Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 15:39:23 volumio volumio[10539]: info: [1732203563632] CoreMusicLibrary::Adding element Media Servers Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 15:39:23 volumio sudo[10597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 21 15:39:23 volumio sudo[10597]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:39:23 volumio systemd[1]: Reloading. Nov 21 15:39:23 volumio volumio[10539]: Forking 3 albumart workers Nov 21 15:39:23 volumio volumio[10539]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:39:23 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Nov 21 15:39:23 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Nov 21 15:39:23 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Nov 21 15:39:23 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Nov 21 15:39:23 volumio volumio[10539]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 15:39:23 volumio volumio[10539]: info: [1732203563825] CoreMusicLibrary::Adding element Last_100 Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 15:39:23 volumio volumio[10539]: info: [1732203563830] CoreMusicLibrary::Adding element Webradio Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 15:39:23 volumio sudo[10597]: pam_unix(sudo:session): session closed for user root Nov 21 15:39:23 volumio volumio[10539]: info: ------------------------------------------- Nov 21 15:39:23 volumio volumio[10539]: info: ----- MyVolumio plugins startup ---- Nov 21 15:39:23 volumio volumio[10539]: info: ------------------------------------------- Nov 21 15:39:23 volumio volumio[10539]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 21 15:39:23 volumio volumio[10539]: info: Loading i18n strings for locale zh Nov 21 15:39:23 volumio volumio[10539]: Updating browse sources language Nov 21 15:39:23 volumio volumio[10539]: Cannot find translation for sourceMedia Servers Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::initPlayerControls Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 15:39:23 volumio volumio[10539]: info: BOOT COMPLETED Nov 21 15:39:23 volumio volumio[10539]: [Metrics] CommandRouter: 3s 511.97ms Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:39:23 volumio volumio[10539]: aplay: main:722: audio open error: Device or resource busy Nov 21 15:39:23 volumio volumio[10539]: Cannot play startup sound Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::Close All Modals sent Nov 21 15:39:23 volumio volumio[10539]: info: CoreCommandRouter::Close All Modals sent Nov 21 15:39:23 volumio volumio[10539]: Express server listening on port 3000 Nov 21 15:39:23 volumio volumio[10539]: [Metrics] WebUI: 3s 879.57ms Nov 21 15:39:23 volumio volumio[10539]: Volumio Calling Home Nov 21 15:39:24 volumio volumio[10539]: info: CoreStateMachine::resetVolumioState Nov 21 15:39:24 volumio volumio[10539]: info: CoreStateMachine::getcurrentVolume Nov 21 15:39:24 volumio volumio[10539]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 15:39:24 volumio volumio[10539]: Starting albumart workers Nov 21 15:39:24 volumio volumio[10539]: Starting albumart workers Nov 21 15:39:24 volumio volumio[10539]: Starting albumart workers Nov 21 15:39:24 volumio volumio[10539]: info: Setting Device type: Raspberry PI Nov 21 15:39:24 volumio volumio[10539]: info: touch_display: systemctl daemon-reload succeeded. Nov 21 15:39:24 volumio volumio[10539]: info: MPD running with PID3411 Nov 21 15:39:24 volumio volumio[10539]: ,establishing connection Nov 21 15:39:24 volumio sudo[10656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 21 15:39:24 volumio sudo[10657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Nov 21 15:39:24 volumio sudo[10657]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:39:24 volumio sudo[10656]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:39:24 volumio systemd[1]: Started Volumio Kiosk. Nov 21 15:39:24 volumio sudo[10656]: pam_unix(sudo:session): session closed for user root Nov 21 15:39:24 volumio sudo[10657]: pam_unix(sudo:session): session closed for user root Nov 21 15:39:24 volumio volumio[10539]: info: CoreStateMachine::pushState Nov 21 15:39:24 volumio volumio[10539]: info: CorePlayQueue::getTrack 0 Nov 21 15:39:24 volumio volumio[10539]: info: CoreCommandRouter::volumioPushState Nov 21 15:39:24 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:39:24 volumio volumio[10539]: info: CoreStateMachine::updateTrackBlock Nov 21 15:39:24 volumio volumio[10539]: info: CorePlayQueue::getTrackBlock Nov 21 15:39:24 volumio volumio[10539]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 15:39:24 volumio volumio[10539]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 21 15:39:24 volumio volumio[10539]: info: touch_display: Volumio Kiosk started Nov 21 15:39:24 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:39:24 volumio volumio[10539]: info: CoreCommandRouter::volumioGetState Nov 21 15:39:24 volumio volumio[10539]: info: CorePlayQueue::getTrack 0 Nov 21 15:39:24 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:39:24 volumio volumio[10539]: info: Volumio called home Nov 21 15:39:24 volumio volumio[10539]: info: Reloading queue from file Nov 21 15:39:24 volumio volumio[10539]: error: Failed LSINFO: Error: [50@0] {} No such directory Nov 21 15:39:24 volumio volumio[10539]: Cannot compose Albumart path Nov 21 15:39:25 volumio volumio[10539]: info: CoreStateMachine::pushState Nov 21 15:39:25 volumio volumio[10539]: info: CorePlayQueue::getTrack 0 Nov 21 15:39:25 volumio volumio[10539]: info: CoreCommandRouter::volumioPushState Nov 21 15:39:25 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:39:25 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 21 15:39:25 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 21 15:39:25 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 21 15:39:25 volumio volumio[10539]: info: CoreStateMachine::setRepeat null single undefined Nov 21 15:39:25 volumio volumio[10539]: info: CoreStateMachine::pushState Nov 21 15:39:25 volumio volumio[10539]: info: CorePlayQueue::getTrack 0 Nov 21 15:39:25 volumio volumio[10539]: info: CoreCommandRouter::volumioPushState Nov 21 15:39:25 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:39:25 volumio volumio[10539]: info: CoreStateMachine::setRandom null Nov 21 15:39:25 volumio volumio[10539]: info: CoreStateMachine::pushState Nov 21 15:39:25 volumio volumio[10539]: info: CorePlayQueue::getTrack 0 Nov 21 15:39:25 volumio volumio[10539]: info: CoreCommandRouter::volumioPushState Nov 21 15:39:25 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:39:25 volumio volumio[10539]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 21 15:39:25 volumio volumio[10539]: info: Starting Shairport Sync Nov 21 15:39:25 volumio volumio[10539]: info: Starting Shairport Sync Nov 21 15:39:25 volumio sudo[10708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 15:39:25 volumio sudo[10708]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:39:25 volumio sudo[10711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 15:39:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 21 15:39:25 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 21 15:39:25 volumio sudo[10708]: pam_unix(sudo:session): session closed for user root Nov 21 15:39:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 21 15:39:25 volumio sudo[10711]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:39:25 volumio volumio[10539]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Nov 21 15:39:25 volumio volumio[10539]: info: CoreCommandRouter::volumioGetState Nov 21 15:39:25 volumio volumio[10539]: info: CorePlayQueue::getTrack 0 Nov 21 15:39:25 volumio volumio[10539]: Discovery: adding 453aa665-c5eb-4874-bc1a-be8764e596bb Nov 21 15:39:25 volumio volumio[10539]: info: mDNS: Found device Volumio Nov 21 15:39:25 volumio volumio[10539]: info: CoreCommandRouter::volumioGetState Nov 21 15:39:25 volumio volumio[10539]: info: CorePlayQueue::getTrack 0 Nov 21 15:39:25 volumio sudo[10736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Option "TransformationMatrix"/d /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 21 15:39:25 volumio volumio[10539]: info: Shairport-Sync Started Nov 21 15:39:25 volumio sudo[10736]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:39:25 volumio volumio[10539]: Error adding Membership: Error: addMembership EINVAL Nov 21 15:39:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 21 15:39:25 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 21 15:39:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 21 15:39:25 volumio sudo[10711]: pam_unix(sudo:session): session closed for user root Nov 21 15:39:25 volumio sudo[10736]: pam_unix(sudo:session): session closed for user root Nov 21 15:39:25 volumio volumio[10539]: info: CoreCommandRouter::volumioGetState Nov 21 15:39:25 volumio volumio[10539]: info: CorePlayQueue::getTrack 0 Nov 21 15:39:25 volumio volumio[10539]: info: touch_display: Touchscreen transformation matrix removed. Nov 21 15:39:25 volumio volumio[10539]: info: Shairport-Sync Started Nov 21 15:39:25 volumio volumio[10539]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 21 15:39:25 volumio volumio[10539]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 21 15:39:25 volumio sudo[10766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Identifier "Touch rotation"/a\ Option "TransformationMatrix" "-1 0 1 0 -1 1 0 0 1" /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 21 15:39:25 volumio sudo[10766]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:39:25 volumio sudo[10766]: pam_unix(sudo:session): session closed for user root Nov 21 15:39:25 volumio volumio[10539]: info: touch_display: Touchscreen transformation matrix written. Nov 21 15:39:26 volumio volumio[10539]: info: CALLMETHOD: user_interface websocket broadcastMessage [object Object] Nov 21 15:39:26 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Nov 21 15:39:26 volumio volumio[10539]: info: CoreCommandRouter::volumioGetState Nov 21 15:39:26 volumio volumio[10539]: info: CorePlayQueue::getTrack 0 Nov 21 15:39:26 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 21 15:39:26 volumio volumio[10539]: info: Listing playlists Nov 21 15:39:26 volumio volumio[10539]: info: CoreCommandRouter::volumioGetState Nov 21 15:39:26 volumio volumio[10539]: info: CorePlayQueue::getTrack 0 Nov 21 15:39:28 volumio volumio[10539]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Nov 21 15:39:28 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:39:28 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:39:28 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 15:39:29 volumio volumio[10539]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/CMA/b26d939b-011d-4703-adf3-01d441f6316d.jpg' Nov 21 15:39:32 volumio sudo[10792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 15:39:32 volumio sudo[10792]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:39:32 volumio sudo[10792]: pam_unix(sudo:session): session closed for user root Nov 21 15:39:32 volumio sudo[10795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 15:39:32 volumio sudo[10795]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:39:32 volumio sudo[10795]: pam_unix(sudo:session): session closed for user root Nov 21 15:39:32 volumio sudo[10804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 21 15:39:32 volumio sudo[10804]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:39:32 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 21 15:39:32 volumio sudo[10804]: pam_unix(sudo:session): session closed for user root Nov 21 15:39:32 volumio volumio[10539]: info: Upmpdcli Daemon Started Nov 21 15:39:33 volumio volumio[10539]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 21 15:39:33 volumio volumio[10539]: info: Adding plugin bluetooth to MyMusic Plugins Nov 21 15:39:33 volumio volumio[10539]: info: Adding plugin cd_controller to MyMusic Plugins Nov 21 15:39:33 volumio volumio[10539]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 21 15:39:33 volumio volumio[10539]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 21 15:39:33 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:39:33 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:39:33 volumio volumio[10539]: info: Starting MyVolumio Remote Streaming Endpoints Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 21 15:39:33 volumio volumio[10539]: info: Streaming services startup Nov 21 15:39:33 volumio volumio[10539]: info: Starting Streaming Daemon Nov 21 15:39:33 volumio sudo[10819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 21 15:39:33 volumio sudo[10819]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 15:39:33 volumio systemd[1]: Stopping Volumio Streaming Daemon... Nov 21 15:39:33 volumio volumio[10539]: info: MyVolumio not started Nov 21 15:39:33 volumio volumio[10539]: info: Initializing device activation check Nov 21 15:39:33 volumio volumio[10539]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 21 15:39:33 volumio systemd[1]: Starting Volumio Streaming Daemon... Nov 21 15:39:33 volumio systemd[1]: Started Volumio Streaming Daemon. Nov 21 15:39:33 volumio sudo[10819]: pam_unix(sudo:session): session closed for user root Nov 21 15:39:33 volumio volumio-streaming-daemon[10829]: ############################ Nov 21 15:39:33 volumio volumio-streaming-daemon[10829]: # Volumio Streaming Daemon # Nov 21 15:39:33 volumio volumio-streaming-daemon[10829]: # Running on port 7777 # Nov 21 15:39:33 volumio volumio-streaming-daemon[10829]: ############################ Nov 21 15:39:33 volumio volumio-streaming-daemon[10829]: Environment: production Nov 21 15:39:36 volumio volumio-streaming-daemon[10829]: Environment: production Nov 21 15:39:36 volumio volumio[10539]: info: Fetching Streaming Services browse cache Nov 21 15:39:38 volumio volumio[10539]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Nov 21 15:39:43 volumio volumio[10539]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 21 15:39:43 volumio volumio[10539]: info: AutoStart - getting queue Nov 21 15:39:43 volumio volumio[10539]: info: CoreCommandRouter::volumioGetQueue Nov 21 15:39:43 volumio volumio[10539]: info: CoreStateMachine::getQueue Nov 21 15:39:43 volumio volumio[10539]: info: CorePlayQueue::getQueue Nov 21 15:39:43 volumio volumio[10539]: info: AutoStart - start playing -> queue is not empty Nov 21 15:39:43 volumio volumio[10539]: info: CoreCommandRouter::volumioPlay Nov 21 15:39:43 volumio volumio[10539]: verbose: UNSET VOLATILE Nov 21 15:39:43 volumio volumio[10539]: info: CoreStateMachine::play index 11 Nov 21 15:39:43 volumio volumio[10539]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:39:43 volumio volumio[10539]: info: CoreStateMachine::stop Nov 21 15:39:43 volumio volumio[10539]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:39:43 volumio volumio[10539]: verbose: UNSET VOLATILE Nov 21 15:39:43 volumio volumio[10539]: info: CoreStateMachine::play index undefined Nov 21 15:39:43 volumio volumio[10539]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 15:39:43 volumio volumio[10539]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:43 volumio volumio[10539]: info: CoreStateMachine::startPlaybackTimer Nov 21 15:39:43 volumio volumio[10539]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::clearAddPlayTracks NAS/鉴听女声天碟CD1/12 Some Other Time.m4a Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 15:39:43 volumio volumio[10539]: info: Nov 21 15:39:43 volumio volumio[10539]: ---------------------------- MPD announces state update: player Nov 21 15:39:43 volumio volumio[10539]: info: sendMpdCommand stop took 199 milliseconds Nov 21 15:39:43 volumio volumio[10539]: info: ControllerMpd::getState Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 15:39:43 volumio volumio[10539]: info: sendMpdCommand status took 2 milliseconds Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::parseState Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:39:43 volumio volumio[10539]: info: Nov 21 15:39:43 volumio volumio[10539]: ---------------------------- MPD announces system playlist update Nov 21 15:39:43 volumio volumio[10539]: info: Ignoring MPD Status Update Nov 21 15:39:43 volumio volumio[10539]: info: sendMpdCommand clear took 5 milliseconds Nov 21 15:39:43 volumio volumio[10539]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::sendMpdCommand add "NAS/鉴听女声天碟CD1/12 Some Other Time.m4a" Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:39:43 volumio volumio[10539]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 21 15:39:43 volumio volumio[10539]: info: ------------------------------ 12ms Nov 21 15:39:43 volumio volumio[10539]: error: updateQueue error: null Nov 21 15:39:43 volumio volumio[10539]: info: Nov 21 15:39:43 volumio volumio[10539]: ---------------------------- MPD announces system playlist update Nov 21 15:39:43 volumio volumio[10539]: info: Ignoring MPD Status Update Nov 21 15:39:43 volumio volumio[10539]: info: ------------------------------ 5ms Nov 21 15:39:43 volumio volumio[10539]: info: sendMpdCommand add "NAS/鉴听女声天碟CD1/12 Some Other Time.m4a" took 5 milliseconds Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::sendMpdCommand play Nov 21 15:39:43 volumio volumio[10539]: info: ------------------------------ 2ms Nov 21 15:39:43 volumio volumio[10539]: info: sendMpdCommand play took 1 milliseconds Nov 21 15:39:43 volumio volumio[10539]: info: Nov 21 15:39:43 volumio volumio[10539]: ---------------------------- MPD announces state update: player Nov 21 15:39:43 volumio volumio[10539]: info: ControllerMpd::getState Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:39:43 volumio volumio[10539]: info: Nov 21 15:39:43 volumio volumio[10539]: ---------------------------- MPD announces state update: player Nov 21 15:39:43 volumio volumio[10539]: info: sendMpdCommand status took 319 milliseconds Nov 21 15:39:43 volumio volumio[10539]: info: ControllerMpd::getState Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::sendMpdCommand status Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::parseState Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:39:43 volumio volumio[10539]: info: sendMpdCommand status took 4 milliseconds Nov 21 15:39:43 volumio volumio[10539]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::parseState Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 15:39:43 volumio volumio[10539]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:39:43 volumio volumio[10539]: info: ControllerMpd::pushState Nov 21 15:39:43 volumio volumio[10539]: info: CoreCommandRouter::servicePushState Nov 21 15:39:44 volumio volumio[10539]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:44 volumio volumio[10539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"887 Kbps","isStreaming":false,"title":"Some Other Time","artist":"Lovisa","album":"鉴听女声天碟CD1","uri":"NAS/鉴听女声天碟CD1/12 Some Other Time.m4a","trackType":"m4a"} Nov 21 15:39:44 volumio volumio[10539]: verbose: CURRENT POSITION 11 Nov 21 15:39:44 volumio volumio[10539]: info: CoreStateMachine::syncState stateService play Nov 21 15:39:44 volumio volumio[10539]: info: CoreStateMachine::syncState currentStatus stop Nov 21 15:39:44 volumio volumio[10539]: info: CoreStateMachine::pushState Nov 21 15:39:44 volumio volumio[10539]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:44 volumio volumio[10539]: info: CoreCommandRouter::volumioPushState Nov 21 15:39:44 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:39:44 volumio volumio[10539]: info: ------------------------------ 339ms Nov 21 15:39:44 volumio volumio[10539]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 21 15:39:44 volumio volumio[10539]: verbose: ControllerMpd::parseTrackInfo Nov 21 15:39:44 volumio volumio[10539]: info: ControllerMpd::pushState Nov 21 15:39:44 volumio volumio[10539]: info: CoreCommandRouter::servicePushState Nov 21 15:39:44 volumio volumio[10539]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:44 volumio volumio[10539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"887 Kbps","isStreaming":false,"title":"Some Other Time","artist":"Lovisa","album":"鉴听女声天碟CD1","uri":"NAS/鉴听女声天碟CD1/12 Some Other Time.m4a","trackType":"m4a"} Nov 21 15:39:44 volumio volumio[10539]: verbose: CURRENT POSITION 11 Nov 21 15:39:44 volumio volumio[10539]: info: CoreStateMachine::syncState stateService play Nov 21 15:39:44 volumio volumio[10539]: info: CoreStateMachine::syncState currentStatus play Nov 21 15:39:44 volumio volumio[10539]: info: Received an update from plugin. extracting info from payload Nov 21 15:39:44 volumio volumio[10539]: info: CoreStateMachine::pushState Nov 21 15:39:44 volumio volumio[10539]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:44 volumio volumio[10539]: info: CoreCommandRouter::volumioPushState Nov 21 15:39:44 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:39:44 volumio volumio[10539]: info: CoreStateMachine::pushState Nov 21 15:39:44 volumio volumio[10539]: info: CorePlayQueue::getTrack 11 Nov 21 15:39:44 volumio volumio[10539]: info: CoreCommandRouter::volumioPushState Nov 21 15:39:44 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 15:39:44 volumio volumio[10539]: info: ------------------------------ 35ms Nov 21 15:39:44 volumio volumio[10539]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 21 15:39:53 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 15:39:53 volumio volumio[10539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 15:39:59 volumio volumio[10539]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 15:39:59 volumio volumio[10539]: { Error: connect ETIMEDOUT 103.73.161.52:80 Nov 21 15:39:59 volumio volumio[10539]: at Object._errnoException (util.js:1022:11) Nov 21 15:39:59 volumio volumio[10539]: at _exceptionWithHostPort (util.js:1044:20) Nov 21 15:39:59 volumio volumio[10539]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Nov 21 15:39:59 volumio volumio[10539]: code: 'ETIMEDOUT', Nov 21 15:39:59 volumio volumio[10539]: errno: 'ETIMEDOUT', Nov 21 15:39:59 volumio volumio[10539]: syscall: 'connect', Nov 21 15:39:59 volumio volumio[10539]: address: '103.73.161.52', Nov 21 15:39:59 volumio volumio[10539]: port: 80 } Nov 21 15:39:59 volumio volumio[10539]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 15:39:59 volumio sudo[10929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 15:38 Nov 21 15:39:59 volumio sudo[10929]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"