-- Logs begin at Fri 2024-11-15 04:29:05 CET, end at Sat 2024-11-23 20:38:50 CET. -- Nov 23 20:37:43 volumio-salon kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Nov 23 20:37:43 volumio-salon kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Nov 23 20:37:43 volumio-salon kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Nov 23 20:37:43 volumio-salon kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Nov 23 20:37:43 volumio-salon kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Nov 23 20:37:43 volumio-salon kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Nov 23 20:37:43 volumio-salon volumio-remote-updater[560]: [2024-11-23 20:35:12] [info] Error getting remote endpoint: system:9 (Bad file descriptor) Nov 23 20:37:43 volumio-salon volumio-remote-updater[560]: [2024-11-23 20:35:19] [info] asio async_shutdown error: system:9 (Bad file descriptor) Nov 23 20:37:44 volumio-salon volumio-remote-updater[560]: [2024-11-23 20:37:44] [error] handle_connect error: Timer Expired Nov 23 20:37:46 volumio-salon systemd[1]: Stopping Music Player Daemon... Nov 23 20:37:46 volumio-salon volumio-remote-updater[560]: [2024-11-23 20:37:46] [connect] Successful connection Nov 23 20:37:47 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 23 20:37:47 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 23 20:37:47 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 23 20:37:47 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 23 20:37:47 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 23 20:37:47 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 23 20:37:47 volumio-salon volumio[865]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Nov 23 20:37:47 volumio-salon volumio[865]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 23 20:37:47 volumio-salon volumio-remote-updater[560]: [2024-11-23 20:37:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732390666 101 Nov 23 20:37:47 volumio-salon volumio[865]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 9 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 23 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 24 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 25 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 26 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 27 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 28 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 29 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 30 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 31 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 32 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 33 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 34 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 35 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 36 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 37 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 38 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 39 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 40 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 41 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 42 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 43 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 44 Nov 23 20:37:48 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:37:48 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:37:48 volumio-salon volumio[865]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 45 Nov 23 20:37:49 volumio-salon volumio[865]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 46 Nov 23 20:37:49 volumio-salon volumio[865]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 47 Nov 23 20:37:49 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:37:49 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:37:49 volumio-salon volumio[865]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 23 20:37:50 volumio-salon systemd[1]: mpd.service: Succeeded. Nov 23 20:37:50 volumio-salon systemd[1]: Stopped Music Player Daemon. Nov 23 20:37:50 volumio-salon systemd[1]: Starting Music Player Daemon... Nov 23 20:37:50 volumio-salon volumio[865]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 48 Nov 23 20:37:51 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 20:37:52 volumio-salon sudo[11689]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 23 20:37:52 volumio-salon sudo[11689]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:37:52 volumio-salon sudo[11689]: pam_unix(sudo:session): session closed for user root Nov 23 20:37:52 volumio-salon volumio[865]: info: Received Get System Info Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:37:52 volumio-salon volumio[865]: info: Discovery: Getting this device information Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:37:52 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:37:52 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 20:37:52 volumio-salon volumio[865]: info: Received Get System Info Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:37:52 volumio-salon volumio[865]: info: Discovery: Getting this device information Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:37:52 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:37:52 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:37:52 volumio-salon volumio[865]: info: Listing playlists Nov 23 20:37:52 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 23 20:37:53 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/118.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 49 Nov 23 20:37:53 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 23 20:37:54 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 20:37:54 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 20:37:54 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:37:54 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:37:54 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:37:54 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:37:54 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 23 20:37:54 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 20:37:54 volumio-salon volumio[865]: info: Received Get System Info Nov 23 20:37:54 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:37:54 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:37:54 volumio-salon volumio[865]: info: Discovery: Getting this device information Nov 23 20:37:54 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:37:54 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:37:54 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:37:54 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:37:54 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:37:54 volumio-salon volumio[865]: info: Listing playlists Nov 23 20:37:54 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetQueue Nov 23 20:37:54 volumio-salon volumio[865]: info: CoreStateMachine::getQueue Nov 23 20:37:54 volumio-salon volumio[865]: info: CorePlayQueue::getQueue Nov 23 20:37:54 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 23 20:37:57 volumio-salon mpd[11696]: Nov 23 20:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 23 20:37:58 volumio-salon systemd[1]: Started Music Player Daemon. Nov 23 20:37:58 volumio-salon mpd_monitor.sh[550]: MPD restarted due to no mpc output. Nov 23 20:38:18 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:38:18 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:38:18 volumio-salon volumio[865]: info: Discovery: Getting this device information Nov 23 20:38:18 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:38:18 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:38:18 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:38:19 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105:3000 from 192.168.1.126 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Nov 23 20:38:19 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:38:19 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:38:19 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 23 20:38:19 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 23 20:38:20 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:38:20 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:38:20 volumio-salon volumio[865]: info: Discovery: Getting this device information Nov 23 20:38:20 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:38:20 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:38:20 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:38:22 volumio-salon sudo[11754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 20:38:22 volumio-salon sudo[11752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 20:38:22 volumio-salon sudo[11752]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:38:22 volumio-salon sudo[11754]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:38:22 volumio-salon sudo[11754]: pam_unix(sudo:session): session closed for user root Nov 23 20:38:22 volumio-salon sudo[11752]: pam_unix(sudo:session): session closed for user root Nov 23 20:38:22 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.126 UA: Mozilla/5.0 (Linux; Android 13; IV2201 Build/TP1A.220905.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.108 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 23 20:38:22 volumio-salon sudo[11758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 20:38:22 volumio-salon sudo[11758]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:38:22 volumio-salon sudo[11758]: pam_unix(sudo:session): session closed for user root Nov 23 20:38:22 volumio-salon sudo[11761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 20:38:22 volumio-salon sudo[11761]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:38:22 volumio-salon sudo[11761]: pam_unix(sudo:session): session closed for user root Nov 23 20:38:22 volumio-salon volumio[865]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.126 UA: Mozilla/5.0 (Linux; Android 13; IV2201 Build/TP1A.220905.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.108 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Nov 23 20:38:22 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 23 20:38:22 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 20:38:22 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 20:38:22 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:38:22 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:38:22 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 23 20:38:22 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 20:38:22 volumio-salon volumio[865]: info: Received Get System Info Nov 23 20:38:22 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:38:22 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:38:22 volumio-salon volumio[865]: info: Discovery: Getting this device information Nov 23 20:38:22 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:38:22 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:38:22 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:38:22 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:38:22 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:38:22 volumio-salon volumio[865]: info: Listing playlists Nov 23 20:38:22 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 23 20:38:22 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 23 20:38:22 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 23 20:38:23 volumio-salon kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Nov 23 20:38:23 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:23+01:00" level=trace msg="emitting websocket event: not_playing" Nov 23 20:38:23 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:23+01:00" level=debug msg="loading track spotify:track:4i4ArYpqtsA3MJ1k0o2dxq (paused: true, position: 50ms)" Nov 23 20:38:23 volumio-salon volumio[865]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:4i4ArYpqtsA3MJ1k0o2dxq","play_origin":"go-librespot"}} Nov 23 20:38:23 volumio-salon volumio[865]: error: Failed to decode event: not_playing Nov 23 20:38:23 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:23+01:00" level=debug msg="renewing login5 access token" Nov 23 20:38:23 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:38:23 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:38:23 volumio-salon volumio[865]: info: Discovery: Getting this device information Nov 23 20:38:23 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:38:23 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:38:23 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=debug msg="authenticated as l8b287u08zs197rgaa4s2ziva" Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=trace msg="emitting websocket event: will_play" Nov 23 20:38:24 volumio-salon volumio[865]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4i4ArYpqtsA3MJ1k0o2dxq","play_origin":"go-librespot"}} Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:4i4ArYpqtsA3MJ1k0o2dxq" Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=debug msg="requested aes key for file 03e1a5f59e14ac0f3965eaf9f6e471d0cb4f371a, gid: 4i4ArYpqtsA3MJ1k0o2dxq" Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 20:38:24 volumio-salon volumio[865]: info: Received Get System Info Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:38:24 volumio-salon volumio[865]: info: Discovery: Getting this device information Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:38:24 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=debug msg="fetched first chunk of 18, total size is 9381932 bytes" Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=trace msg="seek to 50ms (diff: 50ms, samples: 2205, bytes: 0)" Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=info msg="loaded track \"If I Had A Gun…\" (uri: spotify:track:4i4ArYpqtsA3MJ1k0o2dxq, paused: true, position: 50ms, duration: 249106ms)" Nov 23 20:38:24 volumio-salon kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Nov 23 20:38:24 volumio-salon kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=debug msg="fetched chunk 1/17, size: 524288" Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=trace msg="emitting websocket event: metadata" Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=trace msg="emitting websocket event: stopped" Nov 23 20:38:24 volumio-salon volumio[865]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4i4ArYpqtsA3MJ1k0o2dxq","name":"If I Had A Gun…","artist_names":["Noel Gallagher's High Flying Birds"],"album_name":"Noel Gallagher's High Flying Birds","album_cover_url":"https://i.scdn.co/image/ab67616d00001e022c59058c29e0f2eb289b120f","position":50,"duration":249106,"release_date":"year:2011 month:10 day:17","track_number":3,"disc_number":1}} Nov 23 20:38:24 volumio-salon volumio[865]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Nov 23 20:38:24 volumio-salon volumio[865]: SPOTIFY: PUSH STATE SPOTIFY Nov 23 20:38:24 volumio-salon volumio[865]: SPOTIFY: {"status":"stop","service":"spop","title":"If I Had A Gun…","artist":"Noel Gallagher's High Flying Birds","album":"Noel Gallagher's High Flying Birds","albumart":"https://i.scdn.co/image/ab67616d00001e022c59058c29e0f2eb289b120f","uri":"spotify:track:4i4ArYpqtsA3MJ1k0o2dxq","trackType":"spotify","seek":50,"duration":249,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreCommandRouter::servicePushState Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreStateMachine::pushState Nov 23 20:38:24 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreCommandRouter::volumioPushState Nov 23 20:38:24 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 20 Nov 23 20:38:24 volumio-salon volumio[865]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"If I Had A Gun…","artist":"Noel Gallagher's High Flying Birds","album":"Noel Gallagher's High Flying Birds","albumart":"https://i.scdn.co/image/ab67616d00001e022c59058c29e0f2eb289b120f","uri":"spotify:track:4i4ArYpqtsA3MJ1k0o2dxq","trackType":"spotify","seek":50,"duration":249,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 23 20:38:24 volumio-salon volumio[865]: verbose: CURRENT POSITION 20 Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreStateMachine::syncState stateService stop Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreStateMachine::play index undefined Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreStateMachine::pushState Nov 23 20:38:24 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 38 Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:38:24 volumio-salon volumio[865]: info: CoreCommandRouter::volumioPushState Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=trace msg="emitting websocket event: paused" Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=debug msg="fetched chunk 3/17, size: 524288" Nov 23 20:38:24 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:24+01:00" level=debug msg="fetched chunk 2/17, size: 524288" Nov 23 20:38:25 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 38 Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreStateMachine::startPlaybackTimer Nov 23 20:38:25 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 38 Nov 23 20:38:25 volumio-salon volumio[865]: info: [1732390705041] ControllerSpotify::clearAddPlayTrack Nov 23 20:38:25 volumio-salon volumio[865]: info: Sending Spotify command with payload to local API: /player/play Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreStateMachine::pushState Nov 23 20:38:25 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 38 Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreCommandRouter::volumioPushState Nov 23 20:38:25 volumio-salon volumio[865]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:4i4ArYpqtsA3MJ1k0o2dxq","play_origin":"go-librespot"}} Nov 23 20:38:25 volumio-salon volumio[865]: SPOTIFY: PUSH STATE SPOTIFY Nov 23 20:38:25 volumio-salon volumio[865]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreCommandRouter::servicePushState Nov 23 20:38:25 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 38 Nov 23 20:38:25 volumio-salon volumio[865]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 23 20:38:25 volumio-salon volumio[865]: verbose: CURRENT POSITION 38 Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreStateMachine::syncState stateService pause Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreStateMachine::syncState currentStatus stop Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreStateMachine::pushState Nov 23 20:38:25 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 38 Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreCommandRouter::volumioPushState Nov 23 20:38:25 volumio-salon volumio[865]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 23 20:38:25 volumio-salon volumio[865]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 23 20:38:25 volumio-salon volumio[865]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 23 20:38:25 volumio-salon volumio[865]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=debug msg="loading track spotify:track:37oU0liybMY8aI9u6QMVM9 (paused: false, position: 0ms)" Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=trace msg="emitting websocket event: will_play" Nov 23 20:38:25 volumio-salon volumio[865]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:37oU0liybMY8aI9u6QMVM9","play_origin":"go-librespot"}} Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:37oU0liybMY8aI9u6QMVM9" Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=debug msg="requested aes key for file 5a4e0aa8ac09e890b85c9a8c01e69e1baed65699, gid: 37oU0liybMY8aI9u6QMVM9" Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 20:38:25 volumio-salon volumio[865]: info: Received Get System Info Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:38:25 volumio-salon volumio[865]: info: Discovery: Getting this device information Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:38:25 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 38 Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=debug msg="fetched first chunk of 25, total size is 12744640 bytes" Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=info msg="loaded track \"Inside of Love\" (uri: spotify:track:37oU0liybMY8aI9u6QMVM9, paused: false, position: 0ms, duration: 298533ms)" Nov 23 20:38:25 volumio-salon kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=trace msg="emitting websocket event: metadata" Nov 23 20:38:25 volumio-salon volumio[865]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:37oU0liybMY8aI9u6QMVM9","name":"Inside of Love","artist_names":["Nada Surf"],"album_name":"Let Go","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02741ceb4a6eee046248394c97","position":0,"duration":298533,"release_date":"year:2002","track_number":5,"disc_number":1}} Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=debug msg="fetched chunk 3/24, size: 524288" Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=trace msg="emitting websocket event: playing" Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=debug msg="fetched chunk 2/24, size: 524288" Nov 23 20:38:25 volumio-salon volumio[865]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:37oU0liybMY8aI9u6QMVM9","play_origin":"go-librespot"}} Nov 23 20:38:25 volumio-salon volumio[865]: SPOTIFY: PUSH STATE SPOTIFY Nov 23 20:38:25 volumio-salon volumio[865]: SPOTIFY: {"status":"play","service":"spop","title":"Inside of Love","artist":"Nada Surf","album":"Let Go","albumart":"https://i.scdn.co/image/ab67616d00001e02741ceb4a6eee046248394c97","uri":"spotify:track:37oU0liybMY8aI9u6QMVM9","trackType":"spotify","seek":0,"duration":298,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreCommandRouter::servicePushState Nov 23 20:38:25 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 38 Nov 23 20:38:25 volumio-salon volumio[865]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Inside of Love","artist":"Nada Surf","album":"Let Go","albumart":"https://i.scdn.co/image/ab67616d00001e02741ceb4a6eee046248394c97","uri":"spotify:track:37oU0liybMY8aI9u6QMVM9","trackType":"spotify","seek":0,"duration":298,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 23 20:38:25 volumio-salon volumio[865]: verbose: CURRENT POSITION 38 Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreStateMachine::syncState stateService play Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreStateMachine::syncState currentStatus stop Nov 23 20:38:25 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:25+01:00" level=debug msg="fetched chunk 1/24, size: 524288" Nov 23 20:38:25 volumio-salon volumio[865]: info: CoreCommandRouter::volumioGetState Nov 23 20:38:25 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 38 Nov 23 20:38:26 volumio-salon volumio[865]: info: touch_display: Setting screensaver timeout to 60 seconds. Nov 23 20:38:26 volumio-salon volumio[865]: SPOTIFY: PUSH STATE SPOTIFY Nov 23 20:38:26 volumio-salon volumio[865]: SPOTIFY: {"status":"play","service":"spop","title":"Inside of Love","artist":"Nada Surf","album":"Let Go","albumart":"https://i.scdn.co/image/ab67616d00001e02741ceb4a6eee046248394c97","uri":"spotify:track:37oU0liybMY8aI9u6QMVM9","trackType":"spotify","seek":0,"duration":298,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 23 20:38:26 volumio-salon volumio[865]: info: CoreCommandRouter::servicePushState Nov 23 20:38:26 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 38 Nov 23 20:38:26 volumio-salon volumio[865]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Inside of Love","artist":"Nada Surf","album":"Let Go","albumart":"https://i.scdn.co/image/ab67616d00001e02741ceb4a6eee046248394c97","uri":"spotify:track:37oU0liybMY8aI9u6QMVM9","trackType":"spotify","seek":0,"duration":298,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 23 20:38:26 volumio-salon volumio[865]: verbose: CURRENT POSITION 38 Nov 23 20:38:26 volumio-salon volumio[865]: info: CoreStateMachine::syncState stateService play Nov 23 20:38:26 volumio-salon volumio[865]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:38:26 volumio-salon volumio[865]: info: Received an update from plugin. extracting info from payload Nov 23 20:38:26 volumio-salon volumio[865]: info: CoreStateMachine::pushState Nov 23 20:38:26 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 38 Nov 23 20:38:26 volumio-salon volumio[865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:38:26 volumio-salon volumio[865]: info: CoreCommandRouter::volumioPushState Nov 23 20:38:26 volumio-salon volumio[865]: info: CoreStateMachine::pushState Nov 23 20:38:26 volumio-salon volumio[865]: info: CorePlayQueue::getTrack 38 Nov 23 20:38:26 volumio-salon volumio[865]: info: CoreCommandRouter::volumioPushState Nov 23 20:38:26 volumio-salon volumio[865]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 23 20:38:26 volumio-salon volumio[865]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 23 20:38:26 volumio-salon volumio[865]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 23 20:38:26 volumio-salon volumio[865]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Nov 23 20:38:27 volumio-salon volumio[865]: info: PLUGIN onReboot : networkfs Nov 23 20:38:31 volumio-salon sudo[11780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/zinzin-musique Nov 23 20:38:31 volumio-salon sudo[11780]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:38:32 volumio-salon sudo[11780]: pam_unix(sudo:session): session closed for user root Nov 23 20:38:34 volumio-salon systemd[1]: mnt-NAS-zinzin\x2dmusique.mount: Succeeded. Nov 23 20:38:34 volumio-salon systemd[1]: Stopping Music Player Daemon... Nov 23 20:38:35 volumio-salon volumio[865]: info: PLUGIN onReboot : audiophonicsonoff Nov 23 20:38:35 volumio-salon volumio[865]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 20:38:35 volumio-salon volumio[865]: TypeError: Cannot read property 'writeSync' of undefined Nov 23 20:38:35 volumio-salon volumio[865]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Nov 23 20:38:35 volumio-salon volumio[865]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Nov 23 20:38:35 volumio-salon volumio[865]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Nov 23 20:38:35 volumio-salon volumio[865]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 23 20:38:35 volumio-salon volumio[865]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 23 20:38:35 volumio-salon volumio[865]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Nov 23 20:38:35 volumio-salon volumio[865]: at CoreCommandRouter.reboot (/volumio/app/index.js:1345:22) Nov 23 20:38:35 volumio-salon volumio[865]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Nov 23 20:38:35 volumio-salon volumio[865]: at Socket.emit (events.js:315:20) Nov 23 20:38:35 volumio-salon volumio[865]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 23 20:38:35 volumio-salon volumio[865]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 23 20:38:35 volumio-salon volumio[865]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 20:38:37 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:37+01:00" level=debug msg="fetched chunk 4/24, size: 524288" Nov 23 20:38:43 volumio-salon volumio-remote-updater[560]: [2024-11-23 20:38:43] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Nov 23 20:38:44 volumio-salon systemd[1]: mpd.service: Succeeded. Nov 23 20:38:44 volumio-salon systemd[1]: Stopped Music Player Daemon. Nov 23 20:38:44 volumio-salon systemd[1]: Starting Music Player Daemon... Nov 23 20:38:45 volumio-salon sudo[11824]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 23 20:38:45 volumio-salon sudo[11824]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:38:45 volumio-salon sudo[11824]: pam_unix(sudo:session): session closed for user root Nov 23 20:38:49 volumio-salon volumio-remote-updater[560]: [2024-11-23 20:38:49] [connect] Successful connection Nov 23 20:38:49 volumio-salon sudo[11833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 20:37 Nov 23 20:38:50 volumio-salon go-librespot[1277]: time="2024-11-23T20:38:50+01:00" level=debug msg="fetched chunk 5/24, size: 524288" Nov 23 20:38:50 volumio-salon sudo[11833]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"