Nov 23 16:13:07 volumio-pi4.lan volumio[3231]: verbose: ----------------------------------- Nov 23 16:13:07 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 34) (YouTube) Incoming message: 'noop' Nov 23 16:13:07 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 34) (YouTube) Not handled: 'noop' Nov 23 16:13:12 volumio-pi4.lan volumio[3231]: verbose: ----------------------------------- Nov 23 16:13:12 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 34) (YouTube Music) Incoming message: 'noop' Nov 23 16:13:12 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 34) (YouTube Music) Not handled: 'noop' Nov 23 16:13:37 volumio-pi4.lan volumio[3231]: verbose: ----------------------------------- Nov 23 16:13:37 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 35) (YouTube) Incoming message: 'noop' Nov 23 16:13:37 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 35) (YouTube) Not handled: 'noop' Nov 23 16:13:42 volumio-pi4.lan volumio[3231]: verbose: ----------------------------------- Nov 23 16:13:42 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 35) (YouTube Music) Incoming message: 'noop' Nov 23 16:13:42 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 35) (YouTube Music) Not handled: 'noop' Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: verbose: ----------------------------------- Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 36) (YouTube) Incoming message: 'loungeStatus' Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] Active session switched to 'YouTube'. Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: info: [yt-cast-receiver] (YouTube) Sender connected: Desktop (user: SY) Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (YouTube) Connected sender info: Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: Sender { Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: id: 'eb03a4dd-650b-4905-bdcd-e550c0d1def4#2', Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: name: 'Desktop', Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: capabilities: [ 'que', 'mus' ], Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: app: 'youtube-desktop', Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: client: { key: 'YT', theme: 'cl', name: 'YouTube' }, Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: user: { Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: name: 'SY', Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: thumbnail: 'https://yt3.ggpht.com/yti/ANjgQV9ym8XBmfAHjP5XMaoTUZkpxcEHxxnVrHErMVDyGVQ=s240' Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: }, Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: device: {} Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] Setting autoplay mode by sender(s) capabilities. Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (Some) sender(s) do not support autoplay. Autoplay support disabled. Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 36) (YouTube) Sending messages 'onAutoplayModeChanged' with payload: Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: { Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: count: 1, Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: ofs: '0', Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: req0__sc: 'onAutoplayModeChanged', Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: req0_autoplayMode: 'UNSUPPORTED' Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] Configuring player based on mute policy... Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] Mute policy is 'AUTO': checking whether sender(s) support mute... Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (All) sender(s) support mute: player will preserve volume level on mute. Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: verbose: ----------------------------------- Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 37) (YouTube) Incoming message: 'remoteConnected' Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 37) (YouTube) Not handled: 'remoteConnected' Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: info: [ytcr] ***** Sender connected ***** Nov 23 16:13:50 volumio-pi4.lan volumio[3231]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: verbose: [ytcr] Obtaining manual pairing code... Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 36) (YouTube) Response received for sent messages 'onAutoplayModeChanged'. Status: 200 Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 36) (YouTube) Sending messages 'onHasPreviousNextChanged + nowPlaying + onStateChange' with payload: Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: { Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: count: 3, Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: ofs: '1', Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: req0__sc: 'onHasPreviousNextChanged', Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: req0_hasPrevious: false, Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: req0_hasNext: false, Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: req1__sc: 'nowPlaying', Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: req2__sc: 'onStateChange', Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: req2_state: -1, Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: req2_currentTime: 0, Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: req2_duration: 0, Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: req2_loadedTime: 0, Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: req2_seekableStartTime: 0, Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: req2_seekableEndTime: 0, Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: req2_cpn: '2b0e4ec94f374100' Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 36) (YouTube) Response received for sent messages 'onHasPreviousNextChanged + nowPlaying + onStateChange'. Status: 200 Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: verbose: ----------------------------------- Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 38) (YouTube) Incoming message: 'updatePlaylist' Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] 'updatePlaylist' message payload: Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: { listId: 'RQ6f6GCqDENMhjqKgJxFxsrcpMINs' } Nov 23 16:13:51 volumio-pi4.lan volumio[3231]: verbose: [ytcr] Obtained manual pairing code. Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: ----------------------------------- Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 39) (YouTube) Incoming message: 'setPlaylist' Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] 'setPlaylist' message payload: Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: { Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: listId: 'RQ6f6GCqDENMhjqKgJxFxsrcpMINs', Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: ctt: 'APmki7TQrjHBThoMwpxOkWbx5UD0x-zMoAn0pSNAwf_fv_RHz65WhE-ejMpWkOAy0EQJqhy1jwYXvLBdPKtmJtcatf5ivY2H4T0oOlSryjej00N6UTLQkLM0sjsjgYr5xjI27AujuIg6', Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: eventDetails: '{"eventType":"PLAYLIST_SET","userAvatarUri":"https://yt3.ggpht.com/yti/ANjgQV9ym8XBmfAHjP5XMaoTUZkpxcEHxxnVrHErMVDyGVQ=s240","user":"SY","videoIds":["GdTSQfxWAGw"]}', Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: sourceDeviceType: 'REMOTE_CONTROL', Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: clickTrackingParams: 'CAAQu2kiEwj49MOhgfKJAxWKTfUFHWL8Hns=', Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: videoId: 'GdTSQfxWAGw', Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: videoIds: 'GdTSQfxWAGw', Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: currentIndex: '0' Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 39) (YouTube) Sending message 'autoplayUpNext' with payload: Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: { count: 1, ofs: '4', req0__sc: 'autoplayUpNext' } Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: ----------------------------------- Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 40) (YouTube) Incoming message: 'getVolume' Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 39) (YouTube) Response received for sent message 'autoplayUpNext'. Status: 200 Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 40) (YouTube) Sending messages 'onVolumeChanged' with payload: Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: { Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: count: 1, Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: ofs: '5', Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: req0__sc: 'onVolumeChanged', Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: req0_volume: 86, Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: req0_muted: false Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: ----------------------------------- Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 41) (YouTube) Incoming message: 'setSubtitlesTrack' Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 41) (YouTube) Not handled: 'setSubtitlesTrack' Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: ----------------------------------- Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 42) (YouTube) Incoming message: 'setVolume' Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: ----------------------------------- Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 43) (YouTube) Incoming message: 'getSubtitlesTrack' Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 43) (YouTube) Not handled: 'getSubtitlesTrack' Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 40) (YouTube) Response received for sent messages 'onVolumeChanged'. Status: 200 Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] DefaultPlaylistRequestHandler.getPreviousNextVideos: Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: { Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: id: 'GdTSQfxWAGw', Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: client: { key: 'YT', theme: 'cl', name: 'YouTube' }, Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: context: { Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: playlistId: 'RQ6f6GCqDENMhjqKgJxFxsrcpMINs', Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: index: 0, Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: ctt: 'APmki7TQrjHBThoMwpxOkWbx5UD0x-zMoAn0pSNAwf_fv_RHz65WhE-ejMpWkOAy0EQJqhy1jwYXvLBdPKtmJtcatf5ivY2H4T0oOlSryjej00N6UTLQkLM0sjsjgYr5xjI27AujuIg6' Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:52 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] Marking video GdTSQfxWAGw as watched (1st run)... Nov 23 16:13:53 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] Marking video GdTSQfxWAGw as watched (2nd run - without playlist info)... Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: ----------------------------------- Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 44) (YouTube) Incoming message: 'setVolume' Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: [yt-cast-receiver] Player.setVolume(): Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: { level: 86, muted: true } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreCommandRouter::volumioGetState Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CorePlayQueue::getTrack 0 Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [ytcr] setVolume command received, but we are not the current service. Putting player to sleep... Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [ytcr] MPDPlayer going to sleep... Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] Previous / Next endpoints for video Id: GdTSQfxWAGw Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: previous: null, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: next: NavigationEndpoint { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: type: 'NavigationEndpoint', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: payload: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: videoId: 'fjECaVoHCes', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: params: 'wAEB', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: ustreamerConfig: 'GgIIBQ==', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: watchEndpointSupportedAuthorizationTokenConfig: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: videoAuthorizationToken: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: credentialTransferTokens: [ Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: scope: 'VIDEO', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: token: 'APmki7RfeAEbV0shQRSLH3-QnNTzP72R-egvgniZn1yLydSLkLBK3xbwATA1Qvt_hEyBXKUABWq7x9bouTFGXL7usdEV0glWKbhLHoPZJoaooBozvczFNFVpC9IRe5YSxD1SrW6xcxjz' Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: ] Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: watchEndpointSupportedOnesieConfig: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: html5PlaybackOnesieConfig: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: commonConfig: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: url: 'https://rr3---sn-i3b7knlk.googlevideo.com/initplayback?source=youtube&oeis=1&c=TVHTML5&oad=3200&ovd=3200&oaad=3200&oavd=3200&ocs=700&oewis=1&oputc=1&ofpcc=1&siu=1&msp=1&odepv=1&id=7e3102695a0709eb&ip=42.200.182.243&initcwndbps=1761250&mt=1732349114&pxtags=Cg4KAnR4Egg1MTMyNjY1NA&rxtags=Cg4KAnR4Egg1MTMyNjY1MA%2CCg4KAnR4Egg1MTMyNjY1MQ%2CCg4KAnR4Egg1MTMyNjY1Mg%2CCg4KAnR4Egg1MTMyNjY1Mw%2CCg4KAnR4Egg1MTMyNjY1NA%2CCg4KAnR4Egg1MTMyNjY1NQ%2CCg4KAnR4Egg1MTMyNjY1Ng' Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: watchEndpointRemotePlaybackSupportedConfigs: { remotePlaybackConfig: { isMdxPlayback: true } } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: metadata: { api_url: '/player' } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] Previous / Next videos for video Id: GdTSQfxWAGw Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: previous: null, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: next: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: id: 'fjECaVoHCes', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: client: { key: 'YT', theme: 'cl', name: 'YouTube' }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: context: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: params: 'wAEB', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: ctt: 'APmki7RfeAEbV0shQRSLH3-QnNTzP72R-egvgniZn1yLydSLkLBK3xbwATA1Qvt_hEyBXKUABWq7x9bouTFGXL7usdEV0glWKbhLHoPZJoaooBozvczFNFVpC9IRe5YSxD1SrW6xcxjz' Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: [yt-cast-receiver] Player.play(): GdTSQfxWAGw @ 0s Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreCommandRouter::volumioGetState Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CorePlayQueue::getTrack 0 Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] Player state changed from: Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: null Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: To: Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: status: 3, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: queue: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: id: 'RQ6f6GCqDENMhjqKgJxFxsrcpMINs', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: videoIds: [ 'GdTSQfxWAGw' ], Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: previous: null, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: current: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: id: 'GdTSQfxWAGw', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: client: { key: 'YT', theme: 'cl', name: 'YouTube' }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: context: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: playlistId: 'RQ6f6GCqDENMhjqKgJxFxsrcpMINs', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: index: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: ctt: 'APmki7TQrjHBThoMwpxOkWbx5UD0x-zMoAn0pSNAwf_fv_RHz65WhE-ejMpWkOAy0EQJqhy1jwYXvLBdPKtmJtcatf5ivY2H4T0oOlSryjej00N6UTLQkLM0sjsjgYr5xjI27AujuIg6' Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: next: null, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: autoplay: null Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: position: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: duration: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: volume: { level: 86, muted: false }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: cpn: '2b0e4ec94f374100' Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 39) (YouTube) Sending messages 'onStateChange + nowPlaying + onHasPreviousNextChanged + onVolumeChanged + autoplayUpNext' with payload: Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: count: 5, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: ofs: '6', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req0__sc: 'onStateChange', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req0_state: 3, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req0_currentTime: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req0_duration: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req0_loadedTime: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req0_seekableStartTime: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req0_seekableEndTime: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req0_cpn: '2b0e4ec94f374100', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req1__sc: 'nowPlaying', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req1_currentTime: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req1_duration: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req1_cpn: '2b0e4ec94f374100', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req1_loadedTime: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req1_videoId: 'GdTSQfxWAGw', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req1_state: 3, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req1_seekableStartTime: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req1_seekableEndTime: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req1_listId: 'RQ6f6GCqDENMhjqKgJxFxsrcpMINs', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req1_currentIndex: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req1_ctt: 'APmki7TQrjHBThoMwpxOkWbx5UD0x-zMoAn0pSNAwf_fv_RHz65WhE-ejMpWkOAy0EQJqhy1jwYXvLBdPKtmJtcatf5ivY2H4T0oOlSryjej00N6UTLQkLM0sjsjgYr5xjI27AujuIg6', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req2__sc: 'onHasPreviousNextChanged', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req2_hasPrevious: false, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req2_hasNext: false, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req3__sc: 'onVolumeChanged', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req3_volume: 86, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req3_muted: false, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: req4__sc: 'autoplayUpNext' Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [ytcr] Cancelling prefetch (abortIfPrefetching: true, clearIfPrefetched: true) Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreCommandRouter::volumioGetState Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CorePlayQueue::getTrack 0 Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [ytcr] 'play' command received while not being the current service. Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [ytcr] Stopping playback by current service... Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreCommandRouter::volumioStop Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreStateMachine::stop Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [ytcr] MPDPlayer: play GdTSQfxWAGw at position 0s Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: [ytcr] InnertubeLoader: creating Innertube instance... Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [ytcr] Setting ourselves as the current service... Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreCommandRouter::volumioGetState Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CorePlayQueue::getTrack 0 Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [ytcr] Pushing idle state... Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreCommandRouter::servicePushState Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreStateMachine::pushState Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreCommandRouter::volumioPushState Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreCommandRouter::servicePushState Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreStateMachine::pushState Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreCommandRouter::volumioPushState Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: info: CoreCommandRouter::volumioGetState Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [ytcr] Received state change event from MPDPlayer: Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: status: 3, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: queue: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: id: 'RQ6f6GCqDENMhjqKgJxFxsrcpMINs', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: videoIds: [ 'GdTSQfxWAGw' ], Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: previous: null, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: current: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: id: 'GdTSQfxWAGw', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: client: { key: 'YT', theme: 'cl', name: 'YouTube' }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: context: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: playlistId: 'RQ6f6GCqDENMhjqKgJxFxsrcpMINs', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: index: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: ctt: 'APmki7TQrjHBThoMwpxOkWbx5UD0x-zMoAn0pSNAwf_fv_RHz65WhE-ejMpWkOAy0EQJqhy1jwYXvLBdPKtmJtcatf5ivY2H4T0oOlSryjej00N6UTLQkLM0sjsjgYr5xjI27AujuIg6' Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: next: null, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: autoplay: null Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: position: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: duration: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: volume: { level: 86, muted: false }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: cpn: '2b0e4ec94f374100' Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] Player state changed from: Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: status: 3, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: queue: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: id: 'RQ6f6GCqDENMhjqKgJxFxsrcpMINs', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: videoIds: [ 'GdTSQfxWAGw' ], Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: previous: null, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: current: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: id: 'GdTSQfxWAGw', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: client: { key: 'YT', theme: 'cl', name: 'YouTube' }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: context: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: playlistId: 'RQ6f6GCqDENMhjqKgJxFxsrcpMINs', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: index: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: ctt: 'APmki7TQrjHBThoMwpxOkWbx5UD0x-zMoAn0pSNAwf_fv_RHz65WhE-ejMpWkOAy0EQJqhy1jwYXvLBdPKtmJtcatf5ivY2H4T0oOlSryjej00N6UTLQkLM0sjsjgYr5xjI27AujuIg6' Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: next: null, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: autoplay: null Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: position: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: duration: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: volume: { level: 86, muted: false }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: cpn: '2b0e4ec94f374100' Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: To: Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: status: 3, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: queue: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: id: 'RQ6f6GCqDENMhjqKgJxFxsrcpMINs', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: videoIds: [ 'GdTSQfxWAGw' ], Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: previous: null, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: current: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: id: 'GdTSQfxWAGw', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: client: { key: 'YT', theme: 'cl', name: 'YouTube' }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: context: { Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: playlistId: 'RQ6f6GCqDENMhjqKgJxFxsrcpMINs', Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: index: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: ctt: 'APmki7TQrjHBThoMwpxOkWbx5UD0x-zMoAn0pSNAwf_fv_RHz65WhE-ejMpWkOAy0EQJqhy1jwYXvLBdPKtmJtcatf5ivY2H4T0oOlSryjej00N6UTLQkLM0sjsjgYr5xjI27AujuIg6' Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: next: null, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: autoplay: null Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: position: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: duration: 0, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: volume: { level: 86, muted: false }, Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: cpn: '2b0e4ec94f374100' Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: } Nov 23 16:13:54 volumio-pi4.lan volumio[3231]: verbose: [yt-cast-receiver] (AID: 39) (YouTube) Response received for sent messages 'onStateChange + nowPlaying + onHasPreviousNextChanged + onVolumeChanged + autoplayUpNext'. Status: 200 Nov 23 16:13:57 volumio-pi4.lan volumio[3231]: info: [ytcr] InnertubeLoader: obtaining po_token by visitorData... Nov 23 16:13:58 volumio-pi4.lan volumio[3231]: info: [ytcr] InnertubeLoader: obtained po_token (expires in 43200 seconds) Nov 23 16:13:58 volumio-pi4.lan volumio[3231]: info: [ytcr] InnertubeLoader: re-create Innertube instance with po_token Nov 23 16:13:58 volumio-pi4.lan volumio[3231]: info: [ytcr] InnertubeLoader: creating Innertube instance with po_token... Nov 23 16:14:00 volumio-pi4.lan volumio[3231]: info: [ytcr] InnertubeLoader: going to refresh po_token in 43100 seconds Nov 23 16:14:00 volumio-pi4.lan volumio[3231]: info: [ytcr] InnertubeLoader: creating Innertube instance... Nov 23 16:14:02 volumio-pi4.lan volumio[3231]: info: [ytcr] InnertubeLoader: obtaining po_token by visitorData... Nov 23 16:14:03 volumio-pi4.lan volumio[3231]: info: [ytcr] InnertubeLoader: obtained po_token (expires in 43200 seconds) Nov 23 16:14:03 volumio-pi4.lan volumio[3231]: info: [ytcr] InnertubeLoader: re-create Innertube instance with po_token Nov 23 16:14:03 volumio-pi4.lan volumio[3231]: info: [ytcr] InnertubeLoader: creating Innertube instance with po_token... Nov 23 16:14:05 volumio-pi4.lan volumio[3231]: info: [ytcr] InnertubeLoader: going to refresh po_token in 43100 seconds Nov 23 16:14:05 volumio-pi4.lan volumio[3231]: verbose: [ytcr] VideoLoader.getInfo: GdTSQfxWAGw Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: verbose: [ytcr] MPDPLayer obtained info for GdTSQfxWAGw: Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: { Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: id: 'GdTSQfxWAGw', Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: src: 'yt', Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: title: '周興哲《想念你想我》(電視劇《冬至》片頭曲) 一鏡到底深情版', Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: channel: 'Eric周興哲', Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: errMsg: undefined, Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: thumbnail: 'https://i.ytimg.com/vi_webp/GdTSQfxWAGw/maxresdefault.webp', Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: isLive: false, Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: streamUrl: 'https://rr3---sn-i3b7kns6.googlevideo.com/videoplayback?expire=1732371246&ei=zo5BZ63KELTYs8IP27rCiQI&ip=42.200.182.243&id=o-AIcQh9QmHZFfI1RmBtnqeBeRKut9nUImLHVmy5kFJCX0&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1732349646%2C&mh=IP&mm=31%2C26&mn=sn-i3b7kns6%2Csn-2gb7sne6&ms=au%2Conr&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=hk&initcwndbps=1761250&siu=1&bui=AQn3pFSBJbvkZK7LIGuLHdiC87i2g8mau94F7BIYx86czSs5H6gynPSWi2DnQyUxZAlIz5Mkxg&spc=qtApAWy8Z6GDynNfVHDyFdPIJlTNjQ8zMsx3hqYLZ64WmzItgY87F_Av7cL98ZDAkw6At5l5jg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=H6NFW8pyvQCWxfnWR-ym9hcQ&rqh=1&gir=yes&clen=4205491&dur=251.401&lmt=1731784307042886&mt=1732349114&fvip=3&keepalive=yes&fexp=51326932%2C51335594&c=WEB&sefc=1&txp=4532434&n=v1mw2eLQBj7ZMg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AGluJ3MwRAIgUDL4tM5cudQSsr24kb4uq-T64ouYQDHKeYEOknYW73UCIEkaAxS0YqwQgW9GKEYDNfj2Jy5PaYlIlwzzvcsLxuOK&sig=AJfQdSswRQIgdH4a9-odJvzy_qdf4Kk6kTS_fetvFXKLtX2QOUsQlFUCIQD8j6TmeenM7gGCctKrlLpKba4LDWSesgMbCVF_o_T2fQ%3D%3D&pot=Mnj-IJ7Rd9OFuv4ck61_GUq3gfLZrti-3tR4qyatL80KvXPER5YQ465Q8yzQdN6pdIfQelBILhjReUzIs6QE9oOPaJYjxTfutDeoGpeE88O94yG_HbtKDAwCWBCQpLEHPTv40or32k814LTsMYq0g33_aZl3ZaxJttI%3D&cver=2.20240111.09.00', Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: duration: 251, Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: bitrate: 'VBR 160 kbps', Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: samplerate: 48000, Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: channels: 2, Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: streamExpires: 2024-11-23T14:13:06.569Z Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: } Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: info: Ignoring MPD Status Update Nov 23 16:14:06 volumio-pi4.lan volumio[3231]: verbose: [ytcr] MPDPlayer waking up... Nov 23 16:14:07 volumio-pi4.lan mpd[3429]: exception: Failed to decode https://rr3---sn-i3b7kns6.googlevideo.com/videoplayback?expire=1732371246&ei=zo5BZ63KELTYs8IP27rCiQI&ip=42.200.182.243&id=o-AIcQh9QmHZFfI1RmBtnqeBeRKut9nUImLHVmy5kFJCX0&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1732349646%2C&mh=IP&mm=31%2C26&mn=sn-i3b7kns6%2Csn-2gb7sne6&ms=au%2Conr&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=hk&initcwndbps=1761250&siu=1&bui=AQn3pFSBJbvkZK7LIGuLHdiC87i2g8mau94F7BIYx86czSs5H6gynPSWi2DnQyUxZAlIz5Mkxg&spc=qtApAWy8Z6GDynNfVHDyFdPIJlTNjQ8zMsx3hqYLZ64WmzItgY87F_Av7cL98ZDAkw6At5l5jg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=H6NFW8pyvQCWxfnWR-ym9hcQ&rqh=1&gir=yes&clen=4205491&dur=251.401&lmt=1731784307042886&mt=1732349114&fvip=3&keepalive=yes&fexp=51326932%2C51335594&c=WEB&sefc=1&txp=4532434&n=v1mw2eLQBj7ZMg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AGluJ3MwRAIgUDL4tM5cudQSsr24kb4uq-T64; CURL failed: The requested URL returned error: 403 Nov 23 16:14:07 volumio-pi4.lan mpd[3429]: player: played "https://rr3---sn-i3b7kns6.googlevideo.com/videoplayback?expire=1732371246&ei=zo5BZ63KELTYs8IP27rCiQI&ip=42.200.182.243&id=o-AIcQh9QmHZFfI1RmBtnqeBeRKut9nUImLHVmy5kFJCX0&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1732349646%2C&mh=IP&mm=31%2C26&mn=sn-i3b7kns6%2Csn-2gb7sne6&ms=au%2Conr&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=hk&initcwndbps=1761250&siu=1&bui=AQn3pFSBJbvkZK7LIGuLHdiC87i2g8mau94F7BIYx86czSs5H6gynPSWi2DnQyUxZAlIz5Mkxg&spc=qtApAWy8Z6GDynNfVHDyFdPIJlTNjQ8zMsx3hqYLZ64WmzItgY87F_Av7cL98ZDAkw6At5l5jg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=H6NFW8pyvQCWxfnWR-ym9hcQ&rqh=1&gir=yes&clen=4205491&dur=251.401&lmt=1731784307042886&mt=1732349114&fvip=3&keepalive=yes&fexp=51326932%2C51335594&c=WEB&sefc=1&txp=4532434&n=v1mw2eLQBj7ZMg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AGluJ3MwRAIgUDL4tM5cudQSsr24kb4uq-T64ouYQDHKeYEOknYW73UCIEkaAxS0YqwQgW9GKEYDNfj2Jy5PaYlIlwzzvcsLxuOK&sig=AJfQdSswRQIgdH4a9-odJvzy_qdf4Kk6kTS_fetvFXKLtX2QOUsQlFUCIQD8j6TmeenM7gGCctKrlLpKba4LDWSesgMbCVF_o_T2fQ%3D%3D&pot=Mnj-IJ7Rd9OFuv4ck61_GUq3gfLZrti-3tR4qyatL80KvXPER5YQ465Q8yzQdN6pdIfQelBILhjReUzIs6QE9oOPaJYjxTfutDeoGpeE88O94yG_HbtKDAwCWBCQpLEHPTv40or32k814LTsMYq0g33_aZl3ZaxJttI%3D&cver=2.20240111.09.00" Nov 23 16:14:07 volumio-pi4.lan mpd[3429]: exception: Failed to decode https://rr3---sn-i3b7kns6.googlevideo.com/videoplayback?expire=1732371246&ei=zo5BZ63KELTYs8IP27rCiQI&ip=42.200.182.243&id=o-AIcQh9QmHZFfI1RmBtnqeBeRKut9nUImLHVmy5kFJCX0&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1732349646%2C&mh=IP&mm=31%2C26&mn=sn-i3b7kns6%2Csn-2gb7sne6&ms=au%2Conr&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=hk&initcwndbps=1761250&siu=1&bui=AQn3pFSBJbvkZK7LIGuLHdiC87i2g8mau94F7BIYx86czSs5H6gynPSWi2DnQyUxZAlIz5Mkxg&spc=qtApAWy8Z6GDynNfVHDyFdPIJlTNjQ8zMsx3hqYLZ64WmzItgY87F_Av7cL98ZDAkw6At5l5jg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=H6NFW8pyvQCWxfnWR-ym9hcQ&rqh=1&gir=yes&clen=4205491&dur=251.401&lmt=1731784307042886&mt=1732349114&fvip=3&keepalive=yes&fexp=51326932%2C51335594&c=WEB&sefc=1&txp=4532434&n=v1mw2eLQBj7ZMg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AGluJ3MwRAIgUDL4tM5cudQSsr24kb4uq-T64; CURL failed: The requested URL returned error: 403 Nov 23 16:14:07 volumio-pi4.lan volumio[3231]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 16:14:07 volumio-pi4.lan volumio[3231]: MPDError: Failed to decode https://rr3---sn-i3b7kns6.googlevideo.com/videoplayback?expire=1732371246&ei=zo5BZ63KELTYs8IP27rCiQI&ip=42.200.182.243&id=o-AIcQh9QmHZFfI1RmBtnqeBeRKut9nUImLHVmy5kFJCX0&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1732349646%2C&mh=IP&mm=31%2C26&mn=sn-i3b7kns6%2Csn-2gb7sne6&ms=au%2Conr&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=hk&initcwndbps=1761250&siu=1&bui=AQn3pFSBJbvkZK7LIGuLHdiC87i2g8mau94F7BIYx86czSs5H6gynPSWi2DnQyUxZAlIz5Mkxg&spc=qtApAWy8Z6GDynNfVHDyFdPIJlTNjQ8zMsx3hqYLZ64WmzItgY87F_Av7cL98ZDAkw6At5l5jg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=H6NFW8pyvQCWxfnWR-ym9hcQ&rqh=1&gir=yes&clen=4205491&dur=251.401&lmt=1731784307042886&mt=1732349114&fvip=3&keepalive=yes&fexp=51326932%2C51335594&c=WEB&sefc=1&txp=4532434&n=v1mw2eLQBj7ZMg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AGluJ3MwRAIgUDL4tM5cudQSsr24kb4uq-T64; CURL failed: The requested URL returned error: 403 Nov 23 16:14:07 volumio-pi4.lan volumio[3231]: at MPDClient._receive (/data/plugins/music_service/ytcr/node_modules/mpd2/lib/index.js:147:24) Nov 23 16:14:07 volumio-pi4.lan volumio[3231]: at Socket.emit (node:events:514:28) Nov 23 16:14:07 volumio-pi4.lan volumio[3231]: at addChunk (node:internal/streams/readable:343:12) Nov 23 16:14:07 volumio-pi4.lan volumio[3231]: at readableAddChunk (node:internal/streams/readable:312:11) Nov 23 16:14:07 volumio-pi4.lan volumio[3231]: at Readable.push (node:internal/streams/readable:253:10) Nov 23 16:14:07 volumio-pi4.lan volumio[3231]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) { Nov 23 16:14:07 volumio-pi4.lan volumio[3231]: code: 'UNKNOWN', Nov 23 16:14:07 volumio-pi4.lan volumio[3231]: errno: 5, Nov 23 16:14:07 volumio-pi4.lan volumio[3231]: cmd_list_num: 0, Nov 23 16:14:07 volumio-pi4.lan volumio[3231]: current_command: 'playid' Nov 23 16:14:07 volumio-pi4.lan volumio[3231]: } Nov 23 16:14:07 volumio-pi4.lan volumio[3231]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 16:14:07 volumio-pi4.lan sudo[4883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-11-23 16:13' Nov 23 16:14:07 volumio-pi4.lan sudo[4883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:07 volumio-pi4.lan sudo[4883]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:08 volumio-pi4.lan volumio-remote-updater[594]: [2024-11-23 16:14:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 23 16:14:08 volumio-pi4.lan volumio-remote-updater[594]: [2024-11-23 16:14:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 23 16:14:08 volumio-pi4.lan systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 23 16:14:08 volumio-pi4.lan systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 23 16:14:08 volumio-pi4.lan systemd[1]: volumio.service: Consumed 1min 3.130s CPU time. Nov 23 16:14:08 volumio-pi4.lan systemd[1]: Started dynamicswap.service - dynamicswap service. Nov 23 16:14:08 volumio-pi4.lan systemd[1]: dynamicswap.service: Deactivated successfully. Nov 23 16:14:08 volumio-pi4.lan systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Nov 23 16:14:08 volumio-pi4.lan systemd[1]: Started dynamicswap.service - dynamicswap service. Nov 23 16:14:08 volumio-pi4.lan systemd[1]: Stopped volumio.service - Volumio Backend Module. Nov 23 16:14:08 volumio-pi4.lan systemd[1]: volumio.service: Consumed 1min 3.130s CPU time. Nov 23 16:14:08 volumio-pi4.lan systemd[1]: Started volumio.service - Volumio Backend Module. Nov 23 16:14:08 volumio-pi4.lan systemd[1]: dynamicswap.service: Deactivated successfully. Nov 23 16:14:09 volumio-pi4.lan volumio[4930]: info: ------------------------------------------- Nov 23 16:14:09 volumio-pi4.lan volumio[4930]: info: ----- Volumio3 ---- Nov 23 16:14:09 volumio-pi4.lan volumio[4930]: info: ------------------------------------------- Nov 23 16:14:09 volumio-pi4.lan volumio[4930]: info: ----- System startup ---- Nov 23 16:14:09 volumio-pi4.lan volumio[4930]: info: ------------------------------------------- Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: MYVOLUMIO Environment detected Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Plugin folders cleanup Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Scanning into folder /volumio/app/plugins/ Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Scanning category audio_interface Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Scanning category miscellanea Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Scanning category music_service Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Scanning category plugins.json Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Scanning category system_controller Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Scanning category user_interface Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Scanning into folder /data/plugins/ Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Scanning category music_service Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Scanning category user_interface Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Plugin folders cleanup completed Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: ------------------------------------------- Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: ----- Core plugins startup ---- Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: ------------------------------------------- Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Loading plugins from folder /volumio/app/plugins/ Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Adding plugin upnp to MyMusic Plugins Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Loading plugins from folder /data/plugins/ Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Loading plugin "system"... Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Loading plugin "appearance"... Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Loading plugin "network"... Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Refreshing Cached IP Addresses Nov 23 16:14:10 volumio-pi4.lan sudo[4958]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 16:14:10 volumio-pi4.lan sudo[4958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:10 volumio-pi4.lan sudo[4960]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 16:14:10 volumio-pi4.lan sudo[4960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:10 volumio-pi4.lan sudo[4958]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:10 volumio-pi4.lan sudo[4960]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Loading plugin "services"... Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Loading plugin "alsa_controller"... Nov 23 16:14:10 volumio-pi4.lan sudo[4974]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 23 16:14:10 volumio-pi4.lan sudo[4974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Loading plugin "wizard"... Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Loading plugin "networkfs"... Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Starting Udev Watcher for removable devices Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Ignoring mount for partition: boot Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Ignoring mount for partition: volumio Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Ignoring mount for partition: volumio_data Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Loading plugin "volumio_command_line_client"... Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Loading plugin "upnp"... Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: [1732349650942] Starting Upmpd Daemon Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Loading plugin "my_music"... Nov 23 16:14:10 volumio-pi4.lan volumio[4930]: info: Loading plugin "mpd"... Nov 23 16:14:11 volumio-pi4.lan volumio[4930]: info: Loading plugin "upnp_browser"... Nov 23 16:14:11 volumio-pi4.lan volumio[4930]: info: Loading plugin "alarm-clock"... Nov 23 16:14:11 volumio-pi4.lan volumio[4930]: info: Loading plugin "airplay_emulation"... Nov 23 16:14:11 volumio-pi4.lan volumio[4930]: info: Starting Shairport Sync Nov 23 16:14:11 volumio-pi4.lan volumio[4930]: info: Loading plugin "last_100"... Nov 23 16:14:11 volumio-pi4.lan volumio[4930]: info: Loading plugin "webradio"... Nov 23 16:14:12 volumio-pi4.lan volumio[4930]: info: Loading plugin "i2s_dacs"... Nov 23 16:14:12 volumio-pi4.lan volumio[4930]: info: I2S DAC not set, start Auto-detection Nov 23 16:14:12 volumio-pi4.lan volumio[4930]: info: Loading plugin "volumiodiscovery"... Nov 23 16:14:12 volumio-pi4.lan volumio[4930]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 23 16:14:12 volumio-pi4.lan volumio[4930]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 23 16:14:12 volumio-pi4.lan volumio[4930]: *** WARNING *** For more information see Nov 23 16:14:12 volumio-pi4.lan volumio[4930]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 23 16:14:12 volumio-pi4.lan volumio[4930]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 23 16:14:12 volumio-pi4.lan volumio[4930]: *** WARNING *** For more information see Nov 23 16:14:12 volumio-pi4.lan volumio[4930]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 23 16:14:12 volumio-pi4.lan volumio[4930]: info: Discovery: Started advertising with name: volumio-pi4.lan Nov 23 16:14:12 volumio-pi4.lan node[4930]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 23 16:14:12 volumio-pi4.lan node[4930]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 23 16:14:12 volumio-pi4.lan node[4930]: *** WARNING *** For more information see Nov 23 16:14:12 volumio-pi4.lan node[4930]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 23 16:14:12 volumio-pi4.lan node[4930]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 23 16:14:12 volumio-pi4.lan node[4930]: *** WARNING *** For more information see Nov 23 16:14:12 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 23 16:14:12 volumio-pi4.lan volumio[4930]: info: Loading plugin "bandcamp"... Nov 23 16:14:12 volumio-pi4.lan volumio[4930]: info: Loading plugin "jellyfin"... Nov 23 16:14:13 volumio-pi4.lan volumio[4930]: info: Loading plugin "soundcloud"... Nov 23 16:14:13 volumio-pi4.lan volumio-remote-updater[594]: [2024-11-23 16:14:13] [connect] Successful connection Nov 23 16:14:13 volumio-pi4.lan volumio[4930]: info: Loading plugin "squeezelite_mc"... Nov 23 16:14:13 volumio-pi4.lan volumio[4930]: info: Loading plugin "youtube2"... Nov 23 16:14:14 volumio-pi4.lan sudo[4974]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:15 volumio-pi4.lan volumio[4930]: info: Loading plugin "ytcr"... Nov 23 16:14:17 volumio-pi4.lan volumio[4930]: info: Loading plugin "ytmusic"... Nov 23 16:14:18 volumio-pi4.lan volumio[4930]: info: Loading plugin "now_playing"... Nov 23 16:14:19 volumio-pi4.lan volumio[4930]: info: Loading plugin "outputs"... Nov 23 16:14:19 volumio-pi4.lan volumio[4930]: info: Loading plugin "albumart"... Nov 23 16:14:19 volumio-pi4.lan volumio[4930]: info: Plugin example_plugin is not enabled Nov 23 16:14:19 volumio-pi4.lan volumio[4930]: info: Loading plugin "inputs"... Nov 23 16:14:19 volumio-pi4.lan volumio[4930]: info: Loading plugin "updater_comm"... Nov 23 16:14:19 volumio-pi4.lan volumio[4930]: info: Plugin mpdemulation is not enabled Nov 23 16:14:19 volumio-pi4.lan volumio[4930]: info: Loading plugin "rest_api"... Nov 23 16:14:19 volumio-pi4.lan volumio[4930]: info: Loading plugin "websocket"... Nov 23 16:14:19 volumio-pi4.lan volumio[4930]: info: Starting Socket.io Server version 1.7.4 Nov 23 16:14:19 volumio-pi4.lan volumio[4930]: info: Loading plugin "touch_display"... Nov 23 16:14:19 volumio-pi4.lan volumio[4930]: info: Applying required configuration parameters for plugin touch_display Nov 23 16:14:19 volumio-pi4.lan volumio[4993]: Forking 3 albumart workers Nov 23 16:14:19 volumio-pi4.lan volumio[4930]: info: Loading i18n strings for locale en Nov 23 16:14:19 volumio-pi4.lan volumio[4930]: Updating browse sources language Nov 23 16:14:19 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::initPlayerControls Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: Express server listening on port 3000 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: [Metrics] WebUI: 10s 708.89ms Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreStateMachine::resetVolumioState Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreStateMachine::getcurrentVolume Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioRetrievevolume Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: Volumio Network Manager: Network status updated: 1 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: 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: 1 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: VolumeController:: Volume=86 Mute =false Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreStateMachine::pushState Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioPushState Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreStateMachine::updateTrackBlock Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrackBlock Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioRetrievevolume Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 2 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: Reloading queue from file Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 3 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreStateMachine::setRepeat null single undefined Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreStateMachine::pushState Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioPushState Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreStateMachine::setRandom null Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreStateMachine::pushState Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioPushState Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: Setting Device type: Raspberry PI Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 4 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: VolumeController:: Volume=86 Mute =false Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreStateMachine::pushState Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioPushState Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 6 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: 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: 7 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: Discovery: adding 1358be95-2884-47b0-81c3-d56a8172e712 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: Discovery: Found device volumio-pi Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: Discovery: Connecting to remote: 192.168.11.5 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: Discovery: adding fd2b5306-afba-4e6d-8744-6279d60e667c Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: Discovery: Found device volumio-pve Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: Discovery: Connecting to remote: 192.168.11.130 Nov 23 16:14:20 volumio-pi4.lan volumio[5005]: Starting albumart workers Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: verbose: New Socket.io Connection to 192.168.11.15 from 192.168.11.132 UA: Mozilla/5.0 (iPad; CPU OS 14_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/14.1.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 8 Nov 23 16:14:20 volumio-pi4.lan volumio[5004]: Starting albumart workers Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: Discovery: adding bf033306-fb20-41b9-8a87-e3c9dc6f5773 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: Discovery: Found device volumio-pi4.lan Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: Completed loading Core Plugins Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: Preparing to generate the ALSA configuration file Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:20 volumio-pi4.lan volumio[4930]: 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 16:14:20 volumio-pi4.lan volumio[4930]: info: Discovery: Connected to remote: 192.168.11.130 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Discovery: Connected to remote: 192.168.11.5 Nov 23 16:14:21 volumio-pi4.lan volumio[5003]: Starting albumart workers Nov 23 16:14:21 volumio-pi4.lan sudo[5067]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 16:14:21 volumio-pi4.lan sudo[5067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:21 volumio-pi4.lan sudo[5069]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 16:14:21 volumio-pi4.lan sudo[5069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:21 volumio-pi4.lan sudo[5067]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:21 volumio-pi4.lan sudo[5069]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Received Get System Info Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Discovery: Getting this device information Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Received Get System Info Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Discovery: Getting this device information Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Listing playlists Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetQueue Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreStateMachine::getQueue Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getQueue Nov 23 16:14:21 volumio-pi4.lan dhcpcd[773]: eth0: failed to renew DHCP, rebinding Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: 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 16:14:21 volumio-pi4.lan dhcpcd[773]: eth0: leased 192.168.11.15 for 7200 seconds Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Discovery: this is already registered, bf033306-fb20-41b9-8a87-e3c9dc6f5773 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Discovery: Found device volumio-pi4.lan Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Discovery: this is already registered, bf033306-fb20-41b9-8a87-e3c9dc6f5773 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Discovery: Found device volumio-pi4.lan Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: 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: 12 Nov 23 16:14:21 volumio-pi4.lan systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Nov 23 16:14:21 volumio-pi4.lan systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Nov 23 16:14:21 volumio-pi4.lan systemd[1]: welcome.service: Deactivated successfully. Nov 23 16:14:21 volumio-pi4.lan systemd[1]: Stopped welcome.service - Show a welcome message on console. Nov 23 16:14:21 volumio-pi4.lan systemd[1]: Stopping welcome.service - Show a welcome message on console... Nov 23 16:14:21 volumio-pi4.lan systemd[1]: Starting welcome.service - Show a welcome message on console... Nov 23 16:14:21 volumio-pi4.lan welcome[5098]: Resolved ip:[3] 192.168.11.15 192.168.211.1 169.254.157.217 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: 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 16:14:21 volumio-pi4.lan systemd[1]: Finished welcome.service - Show a welcome message on console. Nov 23 16:14:21 volumio-pi4.lan systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'has') Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: 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: 14 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Asound.conf file unchanged, so no further update is needed Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Output device has changed, restarting MPD Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Output device has changed, restarting Shairport Sync Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:14:21 volumio-pi4.lan sudo[5112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 16:14:21 volumio-pi4.lan sudo[5112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:21 volumio-pi4.lan sudo[5112]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: ___________ START PLUGINS ___________ Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: ControllerMpd::onStart: Initializing MPD Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Creating MPD Configuration file Nov 23 16:14:21 volumio-pi4.lan sudo[5114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 16:14:21 volumio-pi4.lan sudo[5114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:21 volumio-pi4.lan sudo[5127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 16:14:21 volumio-pi4.lan sudo[5127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: [1732349661675] CoreMusicLibrary::Adding element Media Servers Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:14:21 volumio-pi4.lan sudo[5131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 16:14:21 volumio-pi4.lan sudo[5131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:14:21 volumio-pi4.lan sudo[5127]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:14:21 volumio-pi4.lan systemd[1]: Stopping mpd.service - Music Player Daemon... Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: [1732349661741] CoreMusicLibrary::Adding element Last_100 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:14:21 volumio-pi4.lan systemd[1]: mpd.service: Deactivated successfully. Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: [1732349661744] CoreMusicLibrary::Adding element Webradio Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:14:21 volumio-pi4.lan systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 23 16:14:21 volumio-pi4.lan systemd[1]: mpd.service: Consumed 1.460s CPU time. Nov 23 16:14:21 volumio-pi4.lan systemd[1]: mpd.socket: Deactivated successfully. Nov 23 16:14:21 volumio-pi4.lan systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 23 16:14:21 volumio-pi4.lan systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Initializing BBC Radios Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: [1732349661794] CoreMusicLibrary::Adding element Bandcamp Discover Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source Bandcamp Discover Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Discovery: Getting this device information Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: [1732349661808] CoreMusicLibrary::Adding element Jellyfin Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source Bandcamp Discover Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source Jellyfin Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: 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 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: [1732349661816] CoreMusicLibrary::Adding element SoundCloud Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source Bandcamp Discover Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source Jellyfin Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source SoundCloud Nov 23 16:14:21 volumio-pi4.lan systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: [squeezelite_mc] Starting proxy server... Nov 23 16:14:21 volumio-pi4.lan systemd[1]: Starting mpd.service - Music Player Daemon... Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: [1732349661835] CoreMusicLibrary::Adding element YouTube2 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source Bandcamp Discover Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source Jellyfin Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source SoundCloud Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source YouTube2 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: [1732349661868] CoreMusicLibrary::Adding element YouTube Music Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source Bandcamp Discover Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source Jellyfin Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source SoundCloud Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source YouTube2 Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: Cannot find translation for source YouTube Music Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: [now-playing] ConfigUpdater: config is up to date. Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Loading i18n strings for locale en Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: Volumio Calling Home Nov 23 16:14:21 volumio-pi4.lan volumio[4930]: info: [squeezelite_mc] Proxy server started on port 45827 Nov 23 16:14:21 volumio-pi4.lan sudo[5157]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 23 16:14:21 volumio-pi4.lan sudo[5157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 23 16:14:21 volumio-pi4.lan sudo[5157]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:21 volumio-pi4.lan sudo[5164]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Nov 23 16:14:21 volumio-pi4.lan sudo[5164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:21 volumio-pi4.lan sudo[5166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Nov 23 16:14:21 volumio-pi4.lan sudo[5166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:22 volumio-pi4.lan sudo[5169]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 23 16:14:22 volumio-pi4.lan sudo[5169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:22 volumio-pi4.lan systemd[1]: Reloading. Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] Setting app pid to stored value: 041c0c8a-3c70-4683-929e-7be998fcbb49 Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] Starting YouTubeApp... Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: [now-playing] Fetching API key... Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] (YouTube) Configuring session with stored MDX context: Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: { Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: deviceId: 'e51b1e88-f053-4186-962f-ce8ccf3afe93', Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: screenId: '32e1gnpip48vt1vl414pl5uueb' Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: } Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] (YouTube Music) Configuring session with stored MDX context: Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: { Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: deviceId: 'd853d13c-b91f-4aad-9acb-da67a89e0159', Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: screenId: '9uoemca4112tcmoagt22mi16u1' Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: } Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioRetrievevolume Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: [now-playing] App is listening on port 4004. Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: 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: 15 Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: Listing playlists Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetQueue Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreStateMachine::getQueue Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getQueue Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: Discovery: Getting this device information Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: Listing playlists Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetQueue Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreStateMachine::getQueue Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getQueue Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: 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: 16 Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: 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: 17 Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: 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 16:14:22 volumio-pi4.lan volumio[4930]: info: touch_display: No backlight interface detected. Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: MPD Permissions set Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: MPD Permissions set Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: 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 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: Volumio called home Nov 23 16:14:22 volumio-pi4.lan sudo[5176]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 23 16:14:22 volumio-pi4.lan sudo[5176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: 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: 20 Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: VolumeController:: Volume=86 Mute =false Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreStateMachine::pushState Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioPushState Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: [squeezelite_mc] Server discovery started Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: [squeezelite_mc] Player finder started Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: info: [jellyfin-poller] Polled https://jellyfin.amazingvision.com.hk: online Nov 23 16:14:22 volumio-pi4.lan volumio[4930]: 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: 21 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: 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: 22 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: 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: 23 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: [squeezelite_mc] Card 0 supports the following ALSA formats: ["U8","S16_LE"] Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio-pi4.lan","dsdFormat":null,"card":"0","mixerType":"Hardware","mixer":"PCM"} Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: 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 16:14:23 volumio-pi4.lan volumio[4930]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Nov 23 16:14:23 volumio-pi4.lan sudo[5192]: 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 16:14:23 volumio-pi4.lan sudo[5192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:23 volumio-pi4.lan sudo[5192]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:23 volumio-pi4.lan sudo[5194]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:23 volumio-pi4.lan sudo[5194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:23 volumio-pi4.lan sudo[5194]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:23 volumio-pi4.lan mpd[5162]: Nov 23 16:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: Starting Shairport Sync Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: Starting Shairport Sync Nov 23 16:14:23 volumio-pi4.lan sudo[5200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: Starting Shairport Sync Nov 23 16:14:23 volumio-pi4.lan sudo[5200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:23 volumio-pi4.lan sudo[5205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 16:14:23 volumio-pi4.lan sudo[5205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:23 volumio-pi4.lan sudo[5202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 16:14:23 volumio-pi4.lan sudo[5202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: Received Get System Info Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: Discovery: Getting this device information Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetQueue Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreStateMachine::getQueue Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getQueue Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: Listing playlists Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: Received Get System Info Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: Discovery: Getting this device information Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Nov 23 16:14:23 volumio-pi4.lan sudo[5210]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Nov 23 16:14:23 volumio-pi4.lan sudo[5210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:23 volumio-pi4.lan sudo[5210]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: 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: 24 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: 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: 25 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: 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 16:14:23 volumio-pi4.lan volumio[4930]: 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: 26 Nov 23 16:14:23 volumio-pi4.lan sudo[5215]: 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 16:14:23 volumio-pi4.lan sudo[5215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:23 volumio-pi4.lan sudo[5215]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: 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: 27 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: 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: 28 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Nov 23 16:14:23 volumio-pi4.lan sudo[5218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 23 16:14:23 volumio-pi4.lan sudo[5218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: 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: 29 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: 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: 30 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] (YouTube Music) Obtained lounge token: Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: { Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: screenId: '9uoemca4112tcmoagt22mi16u1', Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: refreshIntervalInMillis: 1123200000, Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: remoteRefreshIntervalMs: 79200000, Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: refreshIntervalMs: 1123200000, Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: loungeTokenLifespanMs: 1209600000, Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: loungeToken: 'AGdO5p-rnYHvSu0kUntClWt6BQxdRHKOTEo_zPAwGcMBRuvAsk-saOuYU9dufAjfJsGunGr224xs7k0d01w9JX8TQ9vDmE4ZpuKC4U-PxdDPe6PFK_8i6wY', Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: remoteRefreshIntervalInMillis: 79200000, Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: expiration: 1733472863000 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: } Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: 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: 31 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: 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: 32 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: 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: 33 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: 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: 34 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] (YouTube) Obtained lounge token: Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: { Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: screenId: '32e1gnpip48vt1vl414pl5uueb', Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: refreshIntervalInMillis: 1123200000, Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: remoteRefreshIntervalMs: 79200000, Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: refreshIntervalMs: 1123200000, Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: loungeTokenLifespanMs: 1209600000, Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: loungeToken: 'AGdO5p8rEHExkaT61xb91HzuQsgdx8ec_DbqvfbS-EPSCCNYvv9oSeHD_acuF83aOK8Lu-YMHsLqoxI1Mohc0i6u72KFVbPT-Y5P63XLOKt0wWtebb2B7rQ', Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: remoteRefreshIntervalInMillis: 79200000, Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: expiration: 1733472862977 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: } Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 35 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 36 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:23 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: 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 16:14:24 volumio-pi4.lan volumio[4930]: 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 16:14:24 volumio-pi4.lan volumio[4930]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: info: CoreCommandRouter::volumioGetState Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: info: CorePlayQueue::getTrack 0 Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] (YouTube) Received messages for establishing session: Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: [ Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: Message { AID: 0, name: 'c', payload: [ '6B4E3CA6115C97C1', '', 8 ] }, Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: Message { Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: AID: 1, Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: name: 'S', Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: payload: 'dK4R4WGLdZQLJmjFMqRDNg6wnAvVOEWW' Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: }, Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: Message { Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: AID: 2, Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: name: 'loungeStatus', Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: payload: { Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: queueId: 'RQ6f6GCqDENMhjqKgJxFxsrcpMINs', Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: devices: '[{"app":"ytcr","capabilities":"dsp,mic,dpa,ntb,que,mus","clientName":"unknown","experiments":"","name":"YouTube on volumio-pi4.lan","theme":"cl","id":"e51b1e88-f053-4186-962f-ce8ccf3afe93","type":"LOUNGE_SCREEN","hasCc":"true","deviceInfo":"{\\"year\\":0,\\"os\\":\\"Windows\\",\\"osVersion\\":\\"10.0\\",\\"chipset\\":\\"\\",\\"clientName\\":\\"TVHTML5\\",\\"dialAdditionalDataSupportLevel\\":\\"unsupported\\",\\"mdxDialServerType\\":\\"MDX_DIAL_SERVER_TYPE_UNKNOWN\\",\\"brand\\":\\"Generic\\",\\"model\\":\\"SmartTV\\"}","receiverIdentityMatchStatus":"IS_RECEIVER"},{"app":"youtube-desktop","capabilities":"que,mus","clientName":"web","experiments":"","userAvatarUri":"https://yt3.ggpht.com/yti/ANjgQV9ym8XBmfAHjP5XMaoTUZkpxcEHxxnVrHErMVDyGVQ=s240","type":"REMOTE_CONTROL","localChannelEncryptionKey":"zrvQLXTjhZPa3O9tRsYFf90D3vhdSJvWEpytrNCQPYU","pairingType":"unknown","name":"Desktop","obfuscatedGaiaId":"103262769316257368550","theme":"cl","id":"eb03a4dd-650b-4905-bdcd-e550c0d1def4#2","user":"SY","receiverIdentityMatchStatus":"DOES_NOT_MATCH_RECEIVER"}]', Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: connectionEventDetails: '{"deviceId":"e51b1e88-f053-4186-962f-ce8ccf3afe93"}' Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: } Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: }, Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: Message { AID: 3, name: 'getNowPlaying', payload: {} } Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: ] Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: verbose: ----------------------------------- Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] (AID: 2) (YouTube) Incoming message: 'loungeStatus' Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] (YouTube) Updated connected senders info with 'loungeStatus' message: Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: [ Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: Sender { Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: id: 'eb03a4dd-650b-4905-bdcd-e550c0d1def4#2', Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: name: 'Desktop', Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: capabilities: [ 'que', 'mus' ], Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: app: 'youtube-desktop', Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: client: { key: 'YT', theme: 'cl', name: 'YouTube' }, Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: user: { Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: name: 'SY', Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: thumbnail: 'https://yt3.ggpht.com/yti/ANjgQV9ym8XBmfAHjP5XMaoTUZkpxcEHxxnVrHErMVDyGVQ=s240' Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: }, Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: device: {} Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: } Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: ] Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] Active session switched to 'YouTube'. Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] Setting autoplay mode by sender(s) capabilities. Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] (Some) sender(s) do not support autoplay. Autoplay support disabled. Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=3&CI=0&RID=rpc&SID=6B4E3CA6115C97C1&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=dK4R4WGLdZQLJmjFMqRDNg6wnAvVOEWW&id=e51b1e88-f053-4186-962f-ce8ccf3afe93&loungeIdToken=AGdO5p8rEHExkaT61xb91HzuQsgdx8ec_DbqvfbS-EPSCCNYvv9oSeHD_acuF83aOK8Lu-YMHsLqoxI1Mohc0i6u72KFVbPT-Y5P63XLOKt0wWtebb2B7rQ&mdxVersion=2&name=YouTube%20on%20volumio-pi4.lan&obfuscatedGaiaId=&t=1&theme=cl&v=2&zx=45cc5867f105 Nov 23 16:14:24 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 16:14:24 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 16:14:24 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] (AID: 2) (YouTube) Sending messages 'onAutoplayModeChanged' with payload: Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: { Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: count: 1, Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: ofs: '0', Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: req0__sc: 'onAutoplayModeChanged', Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: req0_autoplayMode: 'UNSUPPORTED' Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: } Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] Configuring player based on mute policy... Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] Mute policy is 'AUTO': checking whether sender(s) support mute... Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: verbose: [yt-cast-receiver] (All) sender(s) support mute: player will preserve volume level on mute. Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: TypeError: Cannot read properties of undefined (reading 'getVolume') Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: at MPDPlayer.doGetVolume (/data/plugins/music_service/ytcr/dist/lib/MPDPlayer.js:195:75) Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: at MPDPlayer.getVolume (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/Player.js:241:30) Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: at MPDPlayer.setZeroVolumeLevelOnMute (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/Player.js:266:42) Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: at YouTubeApp._YouTubeApp_enforceMutePolicy (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:289:65) Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: at YouTubeApp._YouTubeApp_handleIncomingMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:336:115) Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Nov 23 16:14:24 volumio-pi4.lan volumio[4930]: 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 16:14:24 volumio-pi4.lan volumio[4930]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 16:14:24 volumio-pi4.lan systemd[1]: Started mpd.service - Music Player Daemon. Nov 23 16:14:24 volumio-pi4.lan sudo[5169]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:24 volumio-pi4.lan sudo[5114]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:24 volumio-pi4.lan systemd[1]: Reloading. Nov 23 16:14:24 volumio-pi4.lan sudo[5131]: pam_unix(sudo:session): session closed for user root Nov 23 16:14:25 volumio-pi4.lan sudo[5250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-11-23 16:13' Nov 23 16:14:25 volumio-pi4.lan sudo[5250]: 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"