Nov 23 15:28:54 volumio-pi4.lan volumio[31659]: info: [yt-cast-receiver] Connecting sender through DIAL... Nov 23 15:28:57 volumio-pi4.lan volumio[31659]: info: [yt-cast-receiver] (YouTube) Sender connected: Desktop (user: SY) Nov 23 15:28:57 volumio-pi4.lan volumio[31659]: info: [ytcr] ***** Sender connected ***** Nov 23 15:28:57 volumio-pi4.lan volumio[31659]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: [yt-cast-receiver] Player.play(): XYMaIn2JEh0 @ 192s Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreCommandRouter::volumioStop Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreStateMachine::stop Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: [ytcr] InnertubeLoader: creating Innertube instance... Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreCommandRouter::servicePushState Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreStateMachine::pushState Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreCommandRouter::volumioPushState Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreCommandRouter::servicePushState Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreStateMachine::pushState Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreCommandRouter::volumioPushState Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreCommandRouter::servicePushState Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreStateMachine::pushState Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CoreCommandRouter::volumioPushState Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:01 volumio-pi4.lan volumio[31659]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received ytcr Nov 23 15:29:04 volumio-pi4.lan volumio[31659]: info: [ytcr] InnertubeLoader: obtaining po_token by visitorData... Nov 23 15:29:05 volumio-pi4.lan volumio[31659]: info: [ytcr] InnertubeLoader: obtained po_token (expires in 43200 seconds) Nov 23 15:29:05 volumio-pi4.lan volumio[31659]: info: [ytcr] InnertubeLoader: re-create Innertube instance with po_token Nov 23 15:29:05 volumio-pi4.lan volumio[31659]: info: [ytcr] InnertubeLoader: creating Innertube instance with po_token... Nov 23 15:29:07 volumio-pi4.lan volumio[31659]: info: [ytcr] InnertubeLoader: going to refresh po_token in 43100 seconds Nov 23 15:29:07 volumio-pi4.lan volumio[31659]: info: [ytcr] InnertubeLoader: creating Innertube instance... Nov 23 15:29:09 volumio-pi4.lan volumio[31659]: info: [ytcr] InnertubeLoader: obtaining po_token by visitorData... Nov 23 15:29:09 volumio-pi4.lan volumio[31659]: info: [ytcr] InnertubeLoader: obtained po_token (expires in 43200 seconds) Nov 23 15:29:09 volumio-pi4.lan volumio[31659]: info: [ytcr] InnertubeLoader: re-create Innertube instance with po_token Nov 23 15:29:09 volumio-pi4.lan volumio[31659]: info: [ytcr] InnertubeLoader: creating Innertube instance with po_token... Nov 23 15:29:11 volumio-pi4.lan volumio[31659]: info: [ytcr] InnertubeLoader: going to refresh po_token in 43100 seconds Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: info: Ignoring MPD Status Update Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: MPDError: Failed to decode https://rr5---sn-i3b7knlk.googlevideo.com/videoplayback?expire=1732368552&ei=SIRBZ7GRL83K2roPgrT1sQg&ip=42.200.182.243&id=o-AMygkUcCuQ6HWZe6uV0N1o7aeRkq4pLqJe2mhxzuheFj&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1732346952%2C&mh=cA&mm=31%2C29&mn=sn-i3b7knlk%2Csn-i3belnlz&ms=au%2Crdu&mv=m&mvi=5&pl=19&rms=au%2Cau&gcr=hk&initcwndbps=1833750&siu=1&bui=AQn3pFTFpLZBhFQAzX24X-8X-l_bvFA0R2dT4RRFHGhwUEpUq8ahJZhY-01ncuAmpFKFcZ5BBg&spc=qtApAXSnhsSjbhhzT-dNPWHB84gcqmb0DFPyYURhnDOPpoIkTzW1hioUf5XBt07Dz1N7I2UG6A9F&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=GfzJxyOJGIzvKHbYxllBrI0Q&rqh=1&gir=yes&clen=27313042&dur=1751.261&lmt=1727737683391510&mt=1732346236&fvip=1&keepalive=yes&fexp=51326932%2C51335594&c=WEB&sefc=1&txp=4432434&n=3hSntLm1QXht6Ov&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAKIkdbdtIhR4q_IGHu6s3P0sst5a82m3Q8j_t3xKS7dJAiEA2VPaAzR-YLGtVZ_5zoAQHMUnMBxDu4H6jAC9XxzfWe; CURL failed: The requested URL returned error: 403 Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: at MPDClient._receive (/data/plugins/music_service/ytcr/node_modules/mpd2/lib/index.js:147:24) Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: at Socket.emit (node:events:514:28) Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: at addChunk (node:internal/streams/readable:343:12) Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: at readableAddChunk (node:internal/streams/readable:312:11) Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: at Readable.push (node:internal/streams/readable:253:10) Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) { Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: code: 'UNKNOWN', Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: errno: 5, Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: cmd_list_num: 0, Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: current_command: 'playid' Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: } Nov 23 15:29:13 volumio-pi4.lan volumio[31659]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 15:29:14 volumio-pi4.lan sudo[32611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-11-23 15:28' Nov 23 15:29:14 volumio-pi4.lan sudo[32611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:14 volumio-pi4.lan sudo[32611]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:14 volumio-pi4.lan volumio-remote-updater[594]: [2024-11-23 15:29:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 23 15:29:14 volumio-pi4.lan volumio-remote-updater[594]: [2024-11-23 15:29:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 23 15:29:14 volumio-pi4.lan systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 23 15:29:14 volumio-pi4.lan systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 23 15:29:14 volumio-pi4.lan systemd[1]: volumio.service: Consumed 56.074s CPU time. Nov 23 15:29:14 volumio-pi4.lan systemd[1]: Started dynamicswap.service - dynamicswap service. Nov 23 15:29:14 volumio-pi4.lan systemd[1]: dynamicswap.service: Deactivated successfully. Nov 23 15:29:14 volumio-pi4.lan systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Nov 23 15:29:15 volumio-pi4.lan systemd[1]: Started dynamicswap.service - dynamicswap service. Nov 23 15:29:15 volumio-pi4.lan systemd[1]: Stopped volumio.service - Volumio Backend Module. Nov 23 15:29:15 volumio-pi4.lan systemd[1]: volumio.service: Consumed 56.074s CPU time. Nov 23 15:29:15 volumio-pi4.lan systemd[1]: Started volumio.service - Volumio Backend Module. Nov 23 15:29:15 volumio-pi4.lan systemd[1]: dynamicswap.service: Deactivated successfully. Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: ------------------------------------------- Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: ----- Volumio3 ---- Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: ------------------------------------------- Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: ----- System startup ---- Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: ------------------------------------------- Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: MYVOLUMIO Environment detected Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Plugin folders cleanup Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Scanning into folder /volumio/app/plugins/ Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Scanning category audio_interface Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Scanning category miscellanea Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Scanning category music_service Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Scanning category plugins.json Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Scanning category system_controller Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Scanning category user_interface Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Scanning into folder /data/plugins/ Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Scanning category music_service Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Scanning category user_interface Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Plugin folders cleanup completed Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: ------------------------------------------- Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: ----- Core plugins startup ---- Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: ------------------------------------------- Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Loading plugins from folder /volumio/app/plugins/ Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Adding plugin upnp to MyMusic Plugins Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Loading plugins from folder /data/plugins/ Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Loading plugin "system"... Nov 23 15:29:16 volumio-pi4.lan volumio[32652]: info: Loading plugin "appearance"... Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Loading plugin "network"... Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Refreshing Cached IP Addresses Nov 23 15:29:17 volumio-pi4.lan sudo[32680]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 15:29:17 volumio-pi4.lan sudo[32680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:17 volumio-pi4.lan sudo[32682]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 15:29:17 volumio-pi4.lan sudo[32680]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:17 volumio-pi4.lan sudo[32682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Loading plugin "services"... Nov 23 15:29:17 volumio-pi4.lan sudo[32682]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Loading plugin "alsa_controller"... Nov 23 15:29:17 volumio-pi4.lan sudo[32690]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 23 15:29:17 volumio-pi4.lan sudo[32690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Loading plugin "wizard"... Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Loading plugin "networkfs"... Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Starting Udev Watcher for removable devices Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Ignoring mount for partition: boot Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Ignoring mount for partition: volumio Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Ignoring mount for partition: volumio_data Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Loading plugin "volumio_command_line_client"... Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Loading plugin "upnp"... Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: [1732346957492] Starting Upmpd Daemon Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Loading plugin "my_music"... Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Loading plugin "mpd"... Nov 23 15:29:17 volumio-pi4.lan volumio[32652]: info: Loading plugin "upnp_browser"... Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: info: Loading plugin "alarm-clock"... Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: info: Loading plugin "airplay_emulation"... Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: info: Starting Shairport Sync Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: info: Loading plugin "last_100"... Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: info: Loading plugin "webradio"... Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: info: Loading plugin "i2s_dacs"... Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: info: I2S DAC not set, start Auto-detection Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: info: Loading plugin "volumiodiscovery"... Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: *** WARNING *** For more information see Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: *** WARNING *** For more information see Nov 23 15:29:18 volumio-pi4.lan node[32652]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 23 15:29:18 volumio-pi4.lan node[32652]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 23 15:29:18 volumio-pi4.lan node[32652]: *** WARNING *** For more information see Nov 23 15:29:18 volumio-pi4.lan node[32652]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 23 15:29:18 volumio-pi4.lan node[32652]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 23 15:29:18 volumio-pi4.lan node[32652]: *** WARNING *** For more information see Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: info: Discovery: Started advertising with name: volumio-pi4.lan Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 23 15:29:18 volumio-pi4.lan volumio[32652]: info: Loading plugin "bandcamp"... Nov 23 15:29:19 volumio-pi4.lan volumio[32652]: info: Loading plugin "jellyfin"... Nov 23 15:29:19 volumio-pi4.lan volumio-remote-updater[594]: [2024-11-23 15:29:19] [connect] Successful connection Nov 23 15:29:19 volumio-pi4.lan volumio[32652]: info: Loading plugin "soundcloud"... Nov 23 15:29:19 volumio-pi4.lan volumio[32652]: info: Loading plugin "squeezelite_mc"... Nov 23 15:29:20 volumio-pi4.lan volumio[32652]: info: Loading plugin "youtube2"... Nov 23 15:29:21 volumio-pi4.lan sudo[32690]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:21 volumio-pi4.lan volumio[32652]: info: Loading plugin "ytcr"... Nov 23 15:29:23 volumio-pi4.lan volumio[32652]: info: Loading plugin "ytmusic"... Nov 23 15:29:25 volumio-pi4.lan volumio[32652]: info: Loading plugin "now_playing"... Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Loading plugin "outputs"... Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Loading plugin "albumart"... Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Plugin example_plugin is not enabled Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Loading plugin "inputs"... Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Loading plugin "updater_comm"... Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Plugin mpdemulation is not enabled Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Loading plugin "rest_api"... Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Loading plugin "websocket"... Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Starting Socket.io Server version 1.7.4 Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Loading plugin "touch_display"... Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Applying required configuration parameters for plugin touch_display Nov 23 15:29:26 volumio-pi4.lan volumio[32716]: Forking 3 albumart workers Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Loading i18n strings for locale en Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: Updating browse sources language Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::initPlayerControls Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: Express server listening on port 3000 Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: [Metrics] WebUI: 10s 648.59ms Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreStateMachine::resetVolumioState Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreStateMachine::getcurrentVolume Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioRetrievevolume Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Volumio Network Manager: Network status updated: 1 Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: 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: 1 Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.142 UA: Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 2 Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: VolumeController:: Volume=86 Mute =false Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreStateMachine::pushState Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioPushState Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreStateMachine::updateTrackBlock Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrackBlock Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioRetrievevolume Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Reloading queue from file Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.142 UA: Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 3 Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreStateMachine::setRepeat null single undefined Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreStateMachine::pushState Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioPushState Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreStateMachine::setRandom null Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreStateMachine::pushState Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioPushState Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: Setting Device type: Raspberry PI Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 23 15:29:26 volumio-pi4.lan volumio[32652]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.142 UA: Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 4 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.142 UA: Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 5 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.142 UA: Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 6 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: VolumeController:: Volume=86 Mute =false Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreStateMachine::pushState Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioPushState Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.142 UA: Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 7 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: adding 1358be95-2884-47b0-81c3-d56a8172e712 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: Found device volumio-pi Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: Connecting to remote: 192.168.11.5 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: adding fd2b5306-afba-4e6d-8744-6279d60e667c Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: Found device volumio-pve Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: Connecting to remote: 192.168.11.130 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Completed loading Core Plugins Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Preparing to generate the ALSA configuration file Nov 23 15:29:27 volumio-pi4.lan volumio[32727]: Starting albumart workers Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: 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: 8 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: Connected to remote: 192.168.11.130 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: Connected to remote: 192.168.11.5 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: adding bf033306-fb20-41b9-8a87-e3c9dc6f5773 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: Found device volumio-pi4.lan Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: this is already registered, bf033306-fb20-41b9-8a87-e3c9dc6f5773 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: Found device volumio-pi4.lan Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:27 volumio-pi4.lan volumio[32726]: Starting albumart workers Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: this is already registered, bf033306-fb20-41b9-8a87-e3c9dc6f5773 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: Found device volumio-pi4.lan Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.142 UA: Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 9 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.142 UA: Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 10 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Asound.conf file unchanged, so no further update is needed Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Output device has changed, restarting MPD Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Output device has changed, restarting Shairport Sync Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:29:27 volumio-pi4.lan sudo[321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 15:29:27 volumio-pi4.lan sudo[321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:27 volumio-pi4.lan sudo[321]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: ___________ START PLUGINS ___________ Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: ControllerMpd::onStart: Initializing MPD Nov 23 15:29:27 volumio-pi4.lan sudo[325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 15:29:27 volumio-pi4.lan sudo[325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Creating MPD Configuration file Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 15:29:27 volumio-pi4.lan systemd[1]: Stopping mpd.service - Music Player Daemon... Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: [1732346967655] CoreMusicLibrary::Adding element Media Servers Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:29:27 volumio-pi4.lan sudo[332]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 15:29:27 volumio-pi4.lan sudo[332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:27 volumio-pi4.lan sudo[332]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:27 volumio-pi4.lan systemd[1]: mpd.service: Deactivated successfully. Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:27 volumio-pi4.lan systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 23 15:29:27 volumio-pi4.lan systemd[1]: mpd.service: Consumed 1.507s CPU time. Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:29:27 volumio-pi4.lan systemd[1]: mpd.socket: Deactivated successfully. Nov 23 15:29:27 volumio-pi4.lan systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 23 15:29:27 volumio-pi4.lan systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 23 15:29:27 volumio-pi4.lan sudo[334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 15:29:27 volumio-pi4.lan sudo[334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:29:27 volumio-pi4.lan systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 23 15:29:27 volumio-pi4.lan volumio[32728]: Starting albumart workers Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: [1732346967762] CoreMusicLibrary::Adding element Last_100 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: [1732346967767] CoreMusicLibrary::Adding element Webradio Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Initializing BBC Radios Nov 23 15:29:27 volumio-pi4.lan systemd[1]: Starting mpd.service - Music Player Daemon... Nov 23 15:29:27 volumio-pi4.lan systemd[1]: mpd.service: Deactivated successfully. Nov 23 15:29:27 volumio-pi4.lan systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 23 15:29:27 volumio-pi4.lan systemd[1]: mpd.socket: Deactivated successfully. Nov 23 15:29:27 volumio-pi4.lan systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 23 15:29:27 volumio-pi4.lan systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 23 15:29:27 volumio-pi4.lan systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 23 15:29:27 volumio-pi4.lan systemd[1]: Starting mpd.service - Music Player Daemon... Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: [1732346967894] CoreMusicLibrary::Adding element Bandcamp Discover Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: Cannot find translation for source Bandcamp Discover Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: Discovery: Getting this device information Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: [1732346967920] CoreMusicLibrary::Adding element Jellyfin Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: Cannot find translation for source Bandcamp Discover Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: Cannot find translation for source Jellyfin Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: 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://i.ytimg.com/vi/_aNpLsGksWU/sddefault.jpg?sqp=-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg&rs=AMzJL3lWR8yz2-iBc8bp_vQLPquTjFnZ9w","artist":" ZJSTV Music Channel-Welcome to subscribe-","mute":false,"status":"stop","track":"【TOP10】最受欢迎单曲《中国新歌声2》上线100天单曲排行榜 SING!CHINA S2 [浙江卫视官方HD]","volume":86},"type":"device"} Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: [1732346967930] CoreMusicLibrary::Adding element SoundCloud Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: Cannot find translation for source Bandcamp Discover Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: Cannot find translation for source Jellyfin Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: Cannot find translation for source SoundCloud Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: [squeezelite_mc] Starting proxy server... Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: [1732346967974] CoreMusicLibrary::Adding element YouTube2 Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: Cannot find translation for source Bandcamp Discover Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: Cannot find translation for source Jellyfin Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: Cannot find translation for source SoundCloud Nov 23 15:29:27 volumio-pi4.lan volumio[32652]: Cannot find translation for source YouTube2 Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: [1732346968048] CoreMusicLibrary::Adding element YouTube Music Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: Cannot find translation for source Bandcamp Discover Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: Cannot find translation for source Jellyfin Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: Cannot find translation for source SoundCloud Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: Cannot find translation for source YouTube2 Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: Cannot find translation for source YouTube Music Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: [now-playing] ConfigUpdater: config is up to date. Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: Loading i18n strings for locale en Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: Volumio Calling Home Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: [squeezelite_mc] Proxy server started on port 41413 Nov 23 15:29:28 volumio-pi4.lan sudo[363]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 23 15:29:28 volumio-pi4.lan sudo[363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 23 15:29:28 volumio-pi4.lan sudo[363]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:28 volumio-pi4.lan sudo[380]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Nov 23 15:29:28 volumio-pi4.lan sudo[380]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:28 volumio-pi4.lan sudo[382]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Nov 23 15:29:28 volumio-pi4.lan sudo[382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:28 volumio-pi4.lan sudo[386]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 23 15:29:28 volumio-pi4.lan sudo[386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:28 volumio-pi4.lan systemd[1]: Reloading. Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: [now-playing] Fetching API key... Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioRetrievevolume Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: [now-playing] App is listening on port 4004. Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: Discovery: Getting this device information Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 15:29:28 volumio-pi4.lan sudo[399]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 15:29:28 volumio-pi4.lan sudo[399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:28 volumio-pi4.lan sudo[399]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: Listing playlists Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetQueue Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreStateMachine::getQueue Nov 23 15:29:28 volumio-pi4.lan sudo[401]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 15:29:28 volumio-pi4.lan sudo[401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getQueue Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: Listing playlists Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetQueue Nov 23 15:29:28 volumio-pi4.lan sudo[401]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreStateMachine::getQueue Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getQueue Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: touch_display: No backlight interface detected. Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.142 UA: Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 11 Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: MPD Permissions set Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: MPD Permissions set Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: Volumio called home Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: 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: 12 Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: VolumeController:: Volume=86 Mute =false Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreStateMachine::pushState Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioPushState Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: [squeezelite_mc] Server discovery started Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: [squeezelite_mc] Player finder started Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:28 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: [jellyfin-poller] Polled https://jellyfin.amazingvision.com.hk: online Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: Received Get System Info Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: Discovery: Getting this device information Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetQueue Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreStateMachine::getQueue Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getQueue Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: Listing playlists Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: Received Get System Info Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: Discovery: Getting this device information Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.142 UA: Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 13 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: [squeezelite_mc] Card 0 supports the following ALSA formats: ["U8","S16_LE"] Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio-pi4.lan","dsdFormat":null,"card":"0","mixerType":"Hardware","mixer":"PCM"} Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: 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 23 15:29:29 volumio-pi4.lan volumio[32652]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Nov 23 15:29:29 volumio-pi4.lan mpd[387]: Nov 23 15:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 23 15:29:29 volumio-pi4.lan sudo[420]: 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 23 15:29:29 volumio-pi4.lan sudo[420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:29 volumio-pi4.lan sudo[423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Nov 23 15:29:29 volumio-pi4.lan sudo[420]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:29 volumio-pi4.lan sudo[423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:29 volumio-pi4.lan sudo[423]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: 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 23 15:29:29 volumio-pi4.lan volumio[32652]: info: Starting Shairport Sync Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: Starting Shairport Sync Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: Starting Shairport Sync Nov 23 15:29:29 volumio-pi4.lan sudo[430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 15:29:29 volumio-pi4.lan sudo[430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:29 volumio-pi4.lan sudo[433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 15:29:29 volumio-pi4.lan sudo[433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:29 volumio-pi4.lan sudo[438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 23 15:29:29 volumio-pi4.lan sudo[438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:29 volumio-pi4.lan sudo[435]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 15:29:29 volumio-pi4.lan sudo[435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: 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 23 15:29:29 volumio-pi4.lan volumio[32652]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Nov 23 15:29:29 volumio-pi4.lan sudo[444]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Nov 23 15:29:29 volumio-pi4.lan sudo[444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: 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: 16 Nov 23 15:29:29 volumio-pi4.lan sudo[444]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: 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 23 15:29:29 volumio-pi4.lan volumio[32652]: 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 23 15:29:29 volumio-pi4.lan volumio[32652]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.142 UA: Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 18 Nov 23 15:29:29 volumio-pi4.lan sudo[448]: 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 23 15:29:29 volumio-pi4.lan sudo[448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.142 UA: Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 19 Nov 23 15:29:29 volumio-pi4.lan sudo[448]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: 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 23 15:29:29 volumio-pi4.lan volumio[32652]: 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: 21 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 Nov 23 15:29:29 volumio-pi4.lan sudo[451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 23 15:29:29 volumio-pi4.lan sudo[451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: 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: 23 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: Received Get System Info Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: Discovery: Getting this device information Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: Received Get System Info Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: Discovery: Getting this device information Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: Listing playlists Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetQueue Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreStateMachine::getQueue Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getQueue Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Nov 23 15:29:29 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 15:29:30 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 15:29:30 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 15:29:30 volumio-pi4.lan volumio[32652]: info: [now-playing] Fetch metadata (ytmusic plugin): {"type":"song","name":"【TOP10】最受欢迎单曲《中国新歌声2》上线100天单曲排行榜 SING!CHINA S2","album":"","artist":"ZJSTV Music Channel-Welcome to subscribe-","uri":"ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22%E3%80%90TOP10%E3%80%91%E6%9C%80%E5%8F%97%E6%AC%A2%E8%BF%8E%E5%8D%95%E6%9B%B2%E3%80%8A%E4%B8%AD%E5%9B%BD%E6%96%B0%E6%AD%8C%E5%A3%B02%E3%80%8B%E4%B8%8A%E7%BA%BF100%E5%A4%A9%E5%8D%95%E6%9B%B2%E6%8E%92%E8%A1%8C%E6%A6%9C%20SING!CHINA%20S2%20%5B%E6%B5%99%E6%B1%9F%E5%8D%AB%E8%A7%86%E5%AE%98%E6%96%B9HD%5D%22%2C%22artist%22%3A%22%20ZJSTV%20Music%20Channel-Welcome%20to%20subscribe-%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F_aNpLsGksWU%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3lWR8yz2-iBc8bp_vQLPquTjFnZ9w%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22_aNpLsGksWU%22%2C%22playlistId%22%3A%22PLTd9F42X0jc6VuNjD1xQcCLLS7sTmNc5g%22%2C%22playlistSetVideoId%22%3A%2252152B4946C2F73F%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22PLTd9F42X0jc6VuNjD1xQcCLLS7sTmNc5g%22%2C%22videoId%22%3A%220Wjb2xeRcG0%22%7D%7D%7D%7D","service":"ytmusic"} Nov 23 15:29:30 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 23 15:29:30 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 23 15:29:30 volumio-pi4.lan volumio[32652]: info: [ytmusic] Fetch song info for Now Playing plugin. URI: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22%E3%80%90TOP10%E3%80%91%E6%9C%80%E5%8F%97%E6%AC%A2%E8%BF%8E%E5%8D%95%E6%9B%B2%E3%80%8A%E4%B8%AD%E5%9B%BD%E6%96%B0%E6%AD%8C%E5%A3%B02%E3%80%8B%E4%B8%8A%E7%BA%BF100%E5%A4%A9%E5%8D%95%E6%9B%B2%E6%8E%92%E8%A1%8C%E6%A6%9C%20SING!CHINA%20S2%20%5B%E6%B5%99%E6%B1%9F%E5%8D%AB%E8%A7%86%E5%AE%98%E6%96%B9HD%5D%22%2C%22artist%22%3A%22%20ZJSTV%20Music%20Channel-Welcome%20to%20subscribe-%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F_aNpLsGksWU%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3lWR8yz2-iBc8bp_vQLPquTjFnZ9w%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22_aNpLsGksWU%22%2C%22playlistId%22%3A%22PLTd9F42X0jc6VuNjD1xQcCLLS7sTmNc5g%22%2C%22playlistSetVideoId%22%3A%2252152B4946C2F73F%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22PLTd9F42X0jc6VuNjD1xQcCLLS7sTmNc5g%22%2C%22videoId%22%3A%220Wjb2xeRcG0%22%7D%7D%7D%7D Nov 23 15:29:30 volumio-pi4.lan volumio[32652]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Nov 23 15:29:30 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Nov 23 15:29:30 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:30 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:30 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 15:29:30 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Nov 23 15:29:30 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:30 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:30 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 15:29:30 volumio-pi4.lan volumio[32652]: info: CoreCommandRouter::volumioGetState Nov 23 15:29:30 volumio-pi4.lan volumio[32652]: info: CorePlayQueue::getTrack 0 Nov 23 15:29:31 volumio-pi4.lan volumio[32652]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 15:29:31 volumio-pi4.lan volumio[32652]: TypeError: Cannot read properties of undefined (reading 'getVolume') Nov 23 15:29:31 volumio-pi4.lan volumio[32652]: at MPDPlayer.doGetVolume (/data/plugins/music_service/ytcr/dist/lib/MPDPlayer.js:195:75) Nov 23 15:29:31 volumio-pi4.lan volumio[32652]: at MPDPlayer.getVolume (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/Player.js:241:30) Nov 23 15:29:31 volumio-pi4.lan volumio[32652]: at MPDPlayer.setZeroVolumeLevelOnMute (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/Player.js:266:42) Nov 23 15:29:31 volumio-pi4.lan volumio[32652]: at YouTubeApp._YouTubeApp_enforceMutePolicy (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:289:65) Nov 23 15:29:31 volumio-pi4.lan volumio[32652]: at YouTubeApp._YouTubeApp_handleIncomingMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:336:115) Nov 23 15:29:31 volumio-pi4.lan volumio[32652]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Nov 23 15:29:31 volumio-pi4.lan volumio[32652]: at async YouTubeApp._YouTubeApp_handleIncomingMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:293:13) Nov 23 15:29:31 volumio-pi4.lan volumio[32652]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 15:29:31 volumio-pi4.lan systemd[1]: Started mpd.service - Music Player Daemon. Nov 23 15:29:31 volumio-pi4.lan sudo[325]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:31 volumio-pi4.lan sudo[334]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:31 volumio-pi4.lan sudo[386]: pam_unix(sudo:session): session closed for user root Nov 23 15:29:31 volumio-pi4.lan systemd[1]: Reloading. Nov 23 15:29:31 volumio-pi4.lan sudo[486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-11-23 15:28' Nov 23 15:29:31 volumio-pi4.lan sudo[486]: 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"