Nov 22 22:27:24 volumio-pi4.lan volumio[11410]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 22 22:27:24 volumio-pi4.lan volumio[11410]: info: Received Get System Info Nov 22 22:27:24 volumio-pi4.lan volumio[11410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 22:27:24 volumio-pi4.lan volumio[11410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 22:27:24 volumio-pi4.lan volumio[11410]: info: Discovery: Getting this device information Nov 22 22:27:24 volumio-pi4.lan volumio[11410]: info: CoreCommandRouter::volumioGetState Nov 22 22:27:24 volumio-pi4.lan volumio[11410]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 22:27:37 volumio-pi4.lan volumio[11410]: info: [yt-cast-receiver] (YouTube) Sender connected: Desktop (user: undefined) Nov 22 22:27:37 volumio-pi4.lan volumio[11410]: info: [ytcr] ***** Sender connected ***** Nov 22 22:27:37 volumio-pi4.lan volumio[11410]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 22 22:27:43 volumio-pi4.lan volumio[11410]: info: [yt-cast-receiver] (YouTube) Sender connected: Desktop (user: SY) Nov 22 22:27:43 volumio-pi4.lan volumio[11410]: info: [ytcr] ***** Sender connected ***** Nov 22 22:27:43 volumio-pi4.lan volumio[11410]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 22 22:28:20 volumio-pi4.lan volumio[11410]: info: [yt-cast-receiver] Player.stop() Nov 22 22:28:20 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:20 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:20 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:20 volumio-pi4.lan volumio[11410]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:20 volumio-pi4.lan volumio[11410]: info: CoreCommandRouter::servicePushState Nov 22 22:28:20 volumio-pi4.lan volumio[11410]: info: CoreStateMachine::pushState Nov 22 22:28:20 volumio-pi4.lan volumio[11410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 22:28:20 volumio-pi4.lan volumio[11410]: info: CoreCommandRouter::volumioPushState Nov 22 22:28:20 volumio-pi4.lan volumio[11410]: info: [yt-cast-receiver] Player.play(): kd0naZRTnbs @ 0s Nov 22 22:28:20 volumio-pi4.lan volumio[11410]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:20 volumio-pi4.lan volumio[11410]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:20 volumio-pi4.lan volumio[11410]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:21 volumio-pi4.lan volumio[11410]: info: Ignoring MPD Status Update Nov 22 22:28:24 volumio-pi4.lan volumio[11410]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 22:28:24 volumio-pi4.lan volumio[11410]: MPDError: Failed to decode https://rr2---sn-aigl6nl7.c.youtube.com/videoplayback?expire=1732307301&ei=BZVAZ7HPJLX_vcAPpviGwAI&ip=42.200.182.243&id=o-AGfxbZTlbZD6vCScCeP_wLPI79l6pqT016WTuyJUpW1J&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1732285701%2C&mh=qV&mm=32%2C26&mn=sn-aigl6nl7%2Csn-2gb7sn7r&ms=su%2Conr&mv=m&mvi=2&pl=19&rms=su%2Csu&sc=yes&initcwndbps=397500&siu=1&bui=AQn3pFSZjxP9s59KvI87-77LEzklnsD-4kfh9e97jIoP6p6BYEpzxkZAxVyOY83XEoTa9MelAA&spc=qtApATRmOps2lEDgaVsp1TbOMJbFXi9FMjFTD5Rj037YYj-83eunMiid-hhs6f1YM5O-fxCHYA&vprv=1&svpuc=1&mime=audio%2Fmp4&ns=fQwa0OUPTwSC0Mm_4RcIHLEQ&rqh=1&gir=yes&clen=687652&dur=42.422&lmt=1632742758590697&mt=1732285251&fvip=3&keepalive=yes&fexp=51326932%2C51335594%2C51347746&c=WEB&sefc=1&txp=6311224&n=xjSnFrbJTq2bKg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhALifBXgqa2dmjy91PcBR97VODlxpfo3EsMWruV-R9TYVAiEA8DgXQaghbg9aPy3nxuLjGdBBx-j_jF1qFo2ftcT1yFc%3D&l; CURL failed: The requested URL returned error: 403 Nov 22 22:28:24 volumio-pi4.lan volumio[11410]: at MPDClient._receive (/data/plugins/music_service/ytcr/node_modules/mpd2/lib/index.js:147:24) Nov 22 22:28:24 volumio-pi4.lan volumio[11410]: at Socket.emit (node:events:514:28) Nov 22 22:28:24 volumio-pi4.lan volumio[11410]: at addChunk (node:internal/streams/readable:343:12) Nov 22 22:28:24 volumio-pi4.lan volumio[11410]: at readableAddChunk (node:internal/streams/readable:312:11) Nov 22 22:28:24 volumio-pi4.lan volumio[11410]: at Readable.push (node:internal/streams/readable:253:10) Nov 22 22:28:24 volumio-pi4.lan volumio[11410]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) { Nov 22 22:28:24 volumio-pi4.lan volumio[11410]: code: 'UNKNOWN', Nov 22 22:28:24 volumio-pi4.lan volumio[11410]: errno: 5, Nov 22 22:28:24 volumio-pi4.lan volumio[11410]: cmd_list_num: 0, Nov 22 22:28:24 volumio-pi4.lan volumio[11410]: current_command: 'playid' Nov 22 22:28:24 volumio-pi4.lan volumio[11410]: } Nov 22 22:28:24 volumio-pi4.lan volumio[11410]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 22:28:24 volumio-pi4.lan sudo[12341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-11-22 22:27' Nov 22 22:28:24 volumio-pi4.lan sudo[12341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:24 volumio-pi4.lan sudo[12341]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:25 volumio-pi4.lan volumio-remote-updater[592]: [2024-11-22 22:28:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 22 22:28:25 volumio-pi4.lan volumio-remote-updater[592]: [2024-11-22 22:28:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 22 22:28:25 volumio-pi4.lan systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 22 22:28:25 volumio-pi4.lan systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 22 22:28:25 volumio-pi4.lan systemd[1]: volumio.service: Consumed 6min 32.227s CPU time. Nov 22 22:28:25 volumio-pi4.lan systemd[1]: Started dynamicswap.service - dynamicswap service. Nov 22 22:28:25 volumio-pi4.lan systemd[1]: dynamicswap.service: Deactivated successfully. Nov 22 22:28:26 volumio-pi4.lan systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Nov 22 22:28:26 volumio-pi4.lan systemd[1]: Started dynamicswap.service - dynamicswap service. Nov 22 22:28:26 volumio-pi4.lan systemd[1]: Stopped volumio.service - Volumio Backend Module. Nov 22 22:28:26 volumio-pi4.lan systemd[1]: volumio.service: Consumed 6min 32.227s CPU time. Nov 22 22:28:26 volumio-pi4.lan systemd[1]: Started volumio.service - Volumio Backend Module. Nov 22 22:28:26 volumio-pi4.lan systemd[1]: dynamicswap.service: Deactivated successfully. Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: ------------------------------------------- Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: ----- Volumio3 ---- Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: ------------------------------------------- Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: ----- System startup ---- Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: ------------------------------------------- Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: MYVOLUMIO Environment detected Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Plugin folders cleanup Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Scanning into folder /volumio/app/plugins/ Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Scanning category audio_interface Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Scanning category miscellanea Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Scanning category music_service Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Scanning category plugins.json Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Scanning category system_controller Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Scanning category user_interface Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Scanning into folder /data/plugins/ Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Scanning category music_service Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Scanning category user_interface Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Plugin folders cleanup completed Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: ------------------------------------------- Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: ----- Core plugins startup ---- Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: ------------------------------------------- Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Loading plugins from folder /volumio/app/plugins/ Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Adding plugin upnp to MyMusic Plugins Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Loading plugins from folder /data/plugins/ Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Loading plugin "system"... Nov 22 22:28:27 volumio-pi4.lan volumio[12385]: info: Loading plugin "appearance"... Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Loading plugin "network"... Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Refreshing Cached IP Addresses Nov 22 22:28:28 volumio-pi4.lan sudo[12417]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 22 22:28:28 volumio-pi4.lan sudo[12417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:28 volumio-pi4.lan sudo[12415]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 22 22:28:28 volumio-pi4.lan sudo[12415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Loading plugin "services"... Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Loading plugin "alsa_controller"... Nov 22 22:28:28 volumio-pi4.lan sudo[12424]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 22 22:28:28 volumio-pi4.lan sudo[12424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:28 volumio-pi4.lan sudo[12417]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:28 volumio-pi4.lan sudo[12415]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Loading plugin "wizard"... Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Loading plugin "networkfs"... Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Starting Udev Watcher for removable devices Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Ignoring mount for partition: boot Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Ignoring mount for partition: volumio Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Ignoring mount for partition: volumio_data Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Loading plugin "volumio_command_line_client"... Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Loading plugin "upnp"... Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: [1732285708644] Starting Upmpd Daemon Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Loading plugin "my_music"... Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Loading plugin "mpd"... Nov 22 22:28:28 volumio-pi4.lan volumio[12385]: info: Loading plugin "upnp_browser"... Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: info: Loading plugin "alarm-clock"... Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: info: Loading plugin "airplay_emulation"... Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: info: Starting Shairport Sync Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: info: Loading plugin "last_100"... Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: info: Loading plugin "webradio"... Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: info: Loading plugin "i2s_dacs"... Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: info: I2S DAC not set, start Auto-detection Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: info: Loading plugin "volumiodiscovery"... Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: *** WARNING *** For more information see Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 22 22:28:29 volumio-pi4.lan node[12385]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: *** WARNING *** For more information see Nov 22 22:28:29 volumio-pi4.lan node[12385]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 22 22:28:29 volumio-pi4.lan node[12385]: *** WARNING *** For more information see Nov 22 22:28:29 volumio-pi4.lan node[12385]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 22 22:28:29 volumio-pi4.lan node[12385]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 22 22:28:29 volumio-pi4.lan node[12385]: *** WARNING *** For more information see Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: info: Discovery: Started advertising with name: volumio-pi4.lan Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 22 22:28:29 volumio-pi4.lan volumio[12385]: info: Loading plugin "bandcamp"... Nov 22 22:28:30 volumio-pi4.lan volumio[12385]: info: Loading plugin "jellyfin"... Nov 22 22:28:30 volumio-pi4.lan volumio-remote-updater[592]: [2024-11-22 22:28:30] [connect] Successful connection Nov 22 22:28:30 volumio-pi4.lan volumio[12385]: info: Loading plugin "soundcloud"... Nov 22 22:28:31 volumio-pi4.lan volumio[12385]: info: Loading plugin "squeezelite_mc"... Nov 22 22:28:31 volumio-pi4.lan volumio[12385]: info: Plugin youtube2 is not enabled Nov 22 22:28:31 volumio-pi4.lan volumio[12385]: info: Loading plugin "ytcr"... Nov 22 22:28:32 volumio-pi4.lan sudo[12424]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:33 volumio-pi4.lan volumio[12385]: info: Loading plugin "ytmusic"... Nov 22 22:28:35 volumio-pi4.lan volumio[12385]: info: Loading plugin "now_playing"... Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: Loading plugin "outputs"... Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: Loading plugin "albumart"... Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: Plugin example_plugin is not enabled Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: Loading plugin "inputs"... Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: Loading plugin "updater_comm"... Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: Plugin mpdemulation is not enabled Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: Loading plugin "rest_api"... Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: Loading plugin "websocket"... Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: Starting Socket.io Server version 1.7.4 Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: Loading plugin "touch_display"... Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: Applying required configuration parameters for plugin touch_display Nov 22 22:28:36 volumio-pi4.lan volumio[12450]: Forking 3 albumart workers Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: Loading i18n strings for locale en Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: Updating browse sources language Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::initPlayerControls Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: Express server listening on port 3000 Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: [Metrics] WebUI: 9s 595.24ms Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreStateMachine::resetVolumioState Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreStateMachine::getcurrentVolume Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioRetrievevolume Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: Volumio Network Manager: Network status updated: 1 Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 1 Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: VolumeController:: Volume=100 Mute =false Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreStateMachine::pushState Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioPushState Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreStateMachine::updateTrackBlock Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrackBlock Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioRetrievevolume Nov 22 22:28:36 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 2 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Reloading queue from file Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Setting Device type: Raspberry PI Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreStateMachine::setRepeat null single undefined Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreStateMachine::pushState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioPushState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreStateMachine::setRandom null Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreStateMachine::pushState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioPushState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 3 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 4 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: VolumeController:: Volume=100 Mute =false Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreStateMachine::pushState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioPushState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 6 Nov 22 22:28:37 volumio-pi4.lan volumio[12461]: Starting albumart workers Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: adding fd2b5306-afba-4e6d-8744-6279d60e667c Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: Found device volumio-pve Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: Connecting to remote: 192.168.11.130 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: adding 1358be95-2884-47b0-81c3-d56a8172e712 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: Found device volumio-pi Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: Connecting to remote: 192.168.11.5 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15:3000 from 192.168.11.6 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 8 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Completed loading Core Plugins Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Preparing to generate the ALSA configuration file Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: adding bf033306-fb20-41b9-8a87-e3c9dc6f5773 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: Found device volumio-pi4.lan Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: this is already registered, bf033306-fb20-41b9-8a87-e3c9dc6f5773 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: Found device volumio-pi4.lan Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:37 volumio-pi4.lan volumio[12460]: Starting albumart workers Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: this is already registered, bf033306-fb20-41b9-8a87-e3c9dc6f5773 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: Found device volumio-pi4.lan Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Received Get System Info Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: Getting this device information Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:37 volumio-pi4.lan volumio[12462]: Starting albumart workers Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetVisibleSources Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Received Get System Info Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: Getting this device information Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Listing playlists Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetQueue Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreStateMachine::getQueue Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getQueue Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 10 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: Connected to remote: 192.168.11.130 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Discovery: Connected to remote: 192.168.11.5 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15:3000 from 192.168.11.130 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 12 Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Asound.conf file unchanged, so no further update is needed Nov 22 22:28:37 volumio-pi4.lan volumio[12385]: info: Output device has changed, restarting MPD Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: Output device has changed, restarting Shairport Sync Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 22:28:38 volumio-pi4.lan sudo[12516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 22 22:28:38 volumio-pi4.lan sudo[12516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:38 volumio-pi4.lan sudo[12516]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:38 volumio-pi4.lan sudo[12518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 22 22:28:38 volumio-pi4.lan sudo[12518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: ___________ START PLUGINS ___________ Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: ControllerMpd::onStart: Initializing MPD Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: Creating MPD Configuration file Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 22:28:38 volumio-pi4.lan systemd[1]: Stopping mpd.service - Music Player Daemon... Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: [1732285718115] CoreMusicLibrary::Adding element Media Servers Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 22:28:38 volumio-pi4.lan sudo[12534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 22 22:28:38 volumio-pi4.lan sudo[12534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:38 volumio-pi4.lan sudo[12532]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 22 22:28:38 volumio-pi4.lan sudo[12532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 22:28:38 volumio-pi4.lan sudo[12532]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 22:28:38 volumio-pi4.lan systemd[1]: mpd.service: Deactivated successfully. Nov 22 22:28:38 volumio-pi4.lan systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 22 22:28:38 volumio-pi4.lan systemd[1]: mpd.service: Consumed 3min 1.903s CPU time. Nov 22 22:28:38 volumio-pi4.lan systemd[1]: mpd.socket: Deactivated successfully. Nov 22 22:28:38 volumio-pi4.lan systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 22 22:28:38 volumio-pi4.lan systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: [1732285718219] CoreMusicLibrary::Adding element Last_100 Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: [1732285718225] CoreMusicLibrary::Adding element Webradio Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: Initializing BBC Radios Nov 22 22:28:38 volumio-pi4.lan systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 22 22:28:38 volumio-pi4.lan systemd[1]: Starting mpd.service - Music Player Daemon... Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: [1732285718291] CoreMusicLibrary::Adding element Bandcamp Discover Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: Cannot find translation for source Bandcamp Discover Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: Discovery: Getting this device information Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: [1732285718309] CoreMusicLibrary::Adding element Jellyfin Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: Cannot find translation for source Bandcamp Discover Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: Cannot find translation for source Jellyfin Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: [jellyfin] Initialized plugin with device info: {"host":"http://192.168.11.15","id":"bf033306-fb20-41b9-8a87-e3c9dc6f5773","name":"volumio-pi4.lan","serviceName":"Volumio","state":{"albumart":"https://f4.bcbits.com/img/a3270851702_16.jpg","artist":"1000 Eyes","mute":false,"status":"stop","track":"MNHR (Drop to an Ocean)","volume":100},"type":"device"} Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: [1732285718317] CoreMusicLibrary::Adding element SoundCloud Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: Cannot find translation for source Bandcamp Discover Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: Cannot find translation for source Jellyfin Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: Cannot find translation for source SoundCloud Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: [squeezelite_mc] Starting proxy server... Nov 22 22:28:38 volumio-pi4.lan sudo[12556]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 22 22:28:38 volumio-pi4.lan sudo[12556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 22 22:28:38 volumio-pi4.lan sudo[12556]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: [1732285718399] CoreMusicLibrary::Adding element YouTube Music Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: Cannot find translation for source Bandcamp Discover Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: Cannot find translation for source Jellyfin Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: Cannot find translation for source SoundCloud Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: Cannot find translation for source YouTube Music Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: [now-playing] ConfigUpdater: config is up to date. Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: Loading i18n strings for locale en Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: Volumio Calling Home Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: [squeezelite_mc] Proxy server started on port 36307 Nov 22 22:28:38 volumio-pi4.lan sudo[12572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Nov 22 22:28:38 volumio-pi4.lan sudo[12572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:38 volumio-pi4.lan sudo[12574]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Nov 22 22:28:38 volumio-pi4.lan sudo[12574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:38 volumio-pi4.lan sudo[12576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 22 22:28:38 volumio-pi4.lan sudo[12576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:38 volumio-pi4.lan sudo[12572]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:38 volumio-pi4.lan systemd[1]: Reloading. Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: [now-playing] Fetching API key... Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioRetrievevolume Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: [now-playing] App is listening on port 4004. Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Nov 22 22:28:38 volumio-pi4.lan volumio[12385]: info: touch_display: No backlight interface detected. Nov 22 22:28:39 volumio-pi4.lan sudo[12596]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 22 22:28:39 volumio-pi4.lan sudo[12596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:39 volumio-pi4.lan sudo[12598]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 22 22:28:39 volumio-pi4.lan sudo[12598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:39 volumio-pi4.lan sudo[12596]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:39 volumio-pi4.lan sudo[12598]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: Discovery: Getting this device information Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: error: Upnp client error: Error: This socket has been ended by the other party Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15:3000 from 192.168.11.6 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: touch_display: systemctl stop getty@tty1.service succeeded. Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: MPD Permissions set Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: MPD Permissions set Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: Listing playlists Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetQueue Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreStateMachine::getQueue Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getQueue Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetVisibleSources Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: Listing playlists Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetQueue Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreStateMachine::getQueue Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getQueue Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetVisibleSources Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15:3000 from 192.168.11.130 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15:3000 from 192.168.11.6 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: VolumeController:: Volume=100 Mute =false Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreStateMachine::pushState Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioPushState Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: [squeezelite_mc] Server discovery started Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: [squeezelite_mc] Player finder started Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 16 Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: [jellyfin-poller] Polled https://jellyfin.amazingvision.com.hk: online Nov 22 22:28:39 volumio-pi4.lan mpd[12567]: Nov 22 22:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 17 Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: [squeezelite_mc] Card 0 supports the following ALSA formats: ["U8","S16_LE"] Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio-pi4.lan","dsdFormat":null,"card":"0","mixerType":"Hardware","mixer":"PCM"} Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Nov 22 22:28:39 volumio-pi4.lan sudo[12620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Nov 22 22:28:39 volumio-pi4.lan sudo[12620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:39 volumio-pi4.lan sudo[12620]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15:3000 from 192.168.11.130 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: Starting Shairport Sync Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: Starting Shairport Sync Nov 22 22:28:39 volumio-pi4.lan sudo[12618]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Nov 22 22:28:39 volumio-pi4.lan sudo[12618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: Starting Shairport Sync Nov 22 22:28:39 volumio-pi4.lan sudo[12618]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:39 volumio-pi4.lan sudo[12623]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 22 22:28:39 volumio-pi4.lan sudo[12623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:39 volumio-pi4.lan sudo[12626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 22 22:28:39 volumio-pi4.lan sudo[12626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:39 volumio-pi4.lan sudo[12628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 22 22:28:39 volumio-pi4.lan sudo[12628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:39 volumio-pi4.lan sudo[12631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 22 22:28:39 volumio-pi4.lan sudo[12631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to 192.168.11.15:3000 from 192.168.11.6 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: Volumio called home Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Nov 22 22:28:39 volumio-pi4.lan sudo[12637]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Nov 22 22:28:39 volumio-pi4.lan sudo[12637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 Nov 22 22:28:39 volumio-pi4.lan sudo[12637]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Nov 22 22:28:39 volumio-pi4.lan sudo[12641]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Nov 22 22:28:39 volumio-pi4.lan sudo[12641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:39 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:39 volumio-pi4.lan sudo[12641]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Nov 22 22:28:40 volumio-pi4.lan sudo[12644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 22 22:28:40 volumio-pi4.lan sudo[12644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"MNHR","album":"SIGNALIS: MEMORIES","artist":"1000 Eyes","uri":"bandcamp/album@albumUrl=https%3A%2F%2Fthousandeyes.bandcamp.com%2Falbum%2Fsignalis-memories-side-a@trackId=2575240215@artistUrl=https%3A%2F%2Fthousandeyes.bandcamp.com%2F","service":""} Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: error: [now-playing] Caught error in callback of MetdataAPI.#getFetchPromise(): Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Could not fetch info: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:180:15) Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:116:28) Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::volumioGetState Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: info: CorePlayQueue::getTrack 0 Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: TypeError: Cannot read properties of undefined (reading 'getVolume') Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: at MPDPlayer.doGetVolume (/data/plugins/music_service/ytcr/dist/lib/MPDPlayer.js:195:75) Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: at MPDPlayer.getVolume (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/Player.js:241:30) Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: at MPDPlayer.setZeroVolumeLevelOnMute (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/Player.js:266:42) Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: at YouTubeApp._YouTubeApp_enforceMutePolicy (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:289:65) Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: at YouTubeApp._YouTubeApp_handleIncomingMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:336:115) Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: at async YouTubeApp._YouTubeApp_handleIncomingMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:293:13) Nov 22 22:28:40 volumio-pi4.lan volumio[12385]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 22:28:40 volumio-pi4.lan systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 22 22:28:40 volumio-pi4.lan systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 22 22:28:41 volumio-pi4.lan systemd[1]: Started mpd.service - Music Player Daemon. Nov 22 22:28:41 volumio-pi4.lan sudo[12576]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:41 volumio-pi4.lan sudo[12534]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:41 volumio-pi4.lan sudo[12518]: pam_unix(sudo:session): session closed for user root Nov 22 22:28:41 volumio-pi4.lan systemd[1]: Reloading. Nov 22 22:28:41 volumio-pi4.lan sudo[12677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-11-22 22:27' Nov 22 22:28:41 volumio-pi4.lan sudo[12677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="6a71b1b8f832c66e3bde56982bbc4fb0dcc139a1" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="572bccfe6facd2cbc0b66d419c4ed66a3b0126c1" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Oct 19 20:30:59 UTC 2024" VOLUMIO_VERSION="0.012" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="68e75b944cee4d40104b64931fbc3e36"