-- Logs begin at Tue 2024-09-24 16:56:13 HKT, end at Sat 2024-10-19 12:48:52 HKT. -- Oct 19 12:47:00 primo-v2 volumio[3090]: info: Upmpdcli Daemon Started Oct 19 12:47:00 primo-v2 volumio[3090]: info: Discovery: adding 8caae262-db51-4345-a5eb-648c08c50dd8 Oct 19 12:47:00 primo-v2 volumio[3090]: info: Discovery: Found device Primo V2 Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:00 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:00 primo-v2 volumio[3090]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Oct 19 12:47:00 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:00+08:00" level=debug msg="obtained new client token: AADwr3fllpasiScCOb2N4Qvl/36o2TsSqbBksDIOoCHzNB5S0cuToI72xjuE1GSYQVYxCUIfI5SeR/TIIsE7RJ6FQW4JEB8IzQI9PCa6JuP0XoMp9laL3eEubpBmd6jqxYNwZA8u09gZcFecTrsGd0BLeiGCD1YWqCZWZlVn1idq5qPtnZ7aw0Ag/y8VBstpLlScNkoYsWWZvWVb4BOV0SHZ16zSwPBYBLHMsFZ6dLBNRFYrUhEL5zVMIBrp" Oct 19 12:47:00 primo-v2 volumio[3090]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 19 12:47:00 primo-v2 sudo[3618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Oct 19 12:47:00 primo-v2 sudo[3618]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:00 primo-v2 volumio[3090]: info: Volumio called home Oct 19 12:47:00 primo-v2 sudo[3618]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:00 primo-v2 volumio[3090]: info: VolumeController:: Volume=undefined Mute =false Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:00 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:47:00 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:47:00 primo-v2 volumio[3090]: info: Discovery: this is already registered, 8caae262-db51-4345-a5eb-648c08c50dd8 Oct 19 12:47:00 primo-v2 volumio[3090]: info: Discovery: Found device Primo V2 Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:00 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:00 primo-v2 volumio[3090]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 19 12:47:00 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:00+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:00 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:00 primo-v2 volumio[3090]: info: Adding Inputs via Serial API Oct 19 12:47:00 primo-v2 volumio[3090]: info: Adding Advanced Audio Settings via Serial API Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 19 12:47:00 primo-v2 volumio[3090]: info: Additional UI Settings Added for plugin music_service/inputs Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::servicePushState Oct 19 12:47:00 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:00+08:00" level=debug msg="completed keyexchange" Oct 19 12:47:00 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:00 primo-v2 volumio[3090]: verbose: STATE SERVICE {"status":"play","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":true,"channels":2} Oct 19 12:47:00 primo-v2 volumio[3090]: verbose: CURRENT POSITION 0 Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreStateMachine::syncState stateService play Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreStateMachine::syncState currentStatus stop Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::volumiosetSourceActiveno-source Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:00 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:47:00 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:00 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:00 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:47:00 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:47:00 primo-v2 volumio[3090]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 19 12:47:00 primo-v2 volumio[3090]: SPOTIFY: BQDj4VB4TFsImBp6pCK5A5gdbOHWO1G22--6bhsOXhXVGMxZ2h-Cm-tIsS9dIuKvFrBKt3tZtqPZDLIbCTB5RQcLF6JP7T0sFCbEDp9vrz5igjsEPCdlnfDknRTId_RELSsNCRisCQhOQO63pLkYnCw162cw-zbBCkk6QuNmyNPoMbEIoI3VmL7pz2zpA1f_eT9iL8as18uofILGuJF__rywQetQpusWQBrq-gY9-b0P6Ugz-b-qQ19J4L54iEODBB8w83OkUqg Oct 19 12:47:00 primo-v2 volumio[3090]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 19 12:47:00 primo-v2 volumio[3090]: info: New Spotify access token = BQDj4VB4TFsImBp6pCK5A5gdbOHWO1G22--6bhsOXhXVGMxZ2h-Cm-tIsS9dIuKvFrBKt3tZtqPZDLIbCTB5RQcLF6JP7T0sFCbEDp9vrz5igjsEPCdlnfDknRTId_RELSsNCRisCQhOQO63pLkYnCw162cw-zbBCkk6QuNmyNPoMbEIoI3VmL7pz2zpA1f_eT9iL8as18uofILGuJF__rywQetQpusWQBrq-gY9-b0P6Ugz-b-qQ19J4L54iEODBB8w83OkUqg Oct 19 12:47:00 primo-v2 volumio[3090]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 19 12:47:00 primo-v2 volumio[3090]: info: MCU Signalled Playback Inactive Oct 19 12:47:00 primo-v2 volumio[3090]: SPOTIFY: User informations: {"country":"HK","display_name":"Kenny558","email":"kenny558@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/rvawsic8srd2iqzgl6p2e4pwm"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/rvawsic8srd2iqzgl6p2e4pwm","id":"rvawsic8srd2iqzgl6p2e4pwm","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85cfb1fd8ecfcb6d1dad95f4fb","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82cfb1fd8ecfcb6d1dad95f4fb","width":64}],"product":"free","type":"user","uri":"spotify:user:rvawsic8srd2iqzgl6p2e4pwm"} Oct 19 12:47:00 primo-v2 volumio[3090]: info: Spotify Successfully logged in Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 19 12:47:00 primo-v2 volumio[3090]: info: [1729313220667] CoreMusicLibrary::Adding element Spotify Oct 19 12:47:00 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 12:47:00 primo-v2 volumio[3090]: Cannot find translation for source Spotify Oct 19 12:47:00 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:00+08:00" level=debug msg="completed challenge" Oct 19 12:47:00 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:00+08:00" level=debug msg="authenticated as rvawsic8srd2iqzgl6p2e4pwm" Oct 19 12:47:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:01+08:00" level=debug msg="authenticated as rvawsic8srd2iqzgl6p2e4pwm" Oct 19 12:47:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:01+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 19 12:47:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:01+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 19 12:47:01 primo-v2 volumio[3090]: info: TidalConnect service stoped! Oct 19 12:47:01 primo-v2 mpd[3540]: Oct 19 12:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 19 12:47:01 primo-v2 volumio[3090]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 19 12:47:01 primo-v2 volumio[3090]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 19 12:47:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:01+08:00" level=debug msg="dealer connection opened" Oct 19 12:47:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:01+08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 19 12:47:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:01+08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 19 12:47:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:01+08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 19 12:47:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:01+08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 19 12:47:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:01+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Oct 19 12:47:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:01+08:00" level=debug msg="received connection id: ODFhMGVjM2QtZTAxMS00Y2YyLTlkN2MtYjg1OTIxMzBiY2NlK2RlYWxlcit0Y3A6Ly8wYWM5NDgxNi5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArNzAxRTg1NkExNzYyQkNDOTRCQzdDNUU5RkUxMzVDQTQzRjQyQjQzN0U3NDYxMDMxMzJCMUU5NkZENkM3NEM5Ng==" Oct 19 12:47:01 primo-v2 sudo[3630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 19 12:47:01 primo-v2 sudo[3630]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:01 primo-v2 systemd[1]: Started Volumio Tidal Connect Service. Oct 19 12:47:01 primo-v2 sudo[3630]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:01 primo-v2 volumio[3090]: info: [yt-cast-receiver] DIAL server listening on port 8098 Oct 19 12:47:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:01+08:00" level=debug msg="put connect state because NEW_DEVICE" Oct 19 12:47:01 primo-v2 systemd[1]: Started Music Player Daemon. Oct 19 12:47:01 primo-v2 volumio[3090]: Upnp client error: Error: This socket has been ended by the other party Oct 19 12:47:01 primo-v2 sudo[3518]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:01 primo-v2 sudo[3512]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:01 primo-v2 volumio[3090]: error: MPD error: The expression evaluated to a falsy value: Oct 19 12:47:01 primo-v2 volumio[3090]: assert.ok(self.idling) Oct 19 12:47:01 primo-v2 volumio[3090]: error: The expression evaluated to a falsy value: Oct 19 12:47:01 primo-v2 volumio[3090]: assert.ok(self.idling) Oct 19 12:47:01 primo-v2 volumio[3090]: error: updateQueue error: null Oct 19 12:47:01 primo-v2 volumio[3090]: info: MPD running with PID3540 Oct 19 12:47:01 primo-v2 volumio[3090]: ,establishing connection Oct 19 12:47:01 primo-v2 volumio[3090]: error: updateQueue error: null Oct 19 12:47:01 primo-v2 volumio[3090]: info: Initializing I2S Bus Oct 19 12:47:01 primo-v2 kernel: aml_tdm_open Oct 19 12:47:01 primo-v2 kernel: Not init audio effects Oct 19 12:47:01 primo-v2 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 19 12:47:01 primo-v2 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Oct 19 12:47:01 primo-v2 kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d27b818, id(1), clksel(1) Oct 19 12:47:01 primo-v2 kernel: aml_dai_set_tdm_fmt(), fmt not change Oct 19 12:47:01 primo-v2 kernel: dump_pcm_setting(ffffffc03d27b818) Oct 19 12:47:01 primo-v2 kernel: pcm_mode(1) Oct 19 12:47:01 primo-v2 kernel: sysclk(11289600) Oct 19 12:47:01 primo-v2 kernel: sysclk_bclk_ratio(4) Oct 19 12:47:01 primo-v2 kernel: bclk(2822400) Oct 19 12:47:01 primo-v2 kernel: bclk_lrclk_ratio(64) Oct 19 12:47:01 primo-v2 kernel: lrclk(44100) Oct 19 12:47:01 primo-v2 kernel: tx_mask(0x3) Oct 19 12:47:01 primo-v2 kernel: rx_mask(0x3) Oct 19 12:47:01 primo-v2 kernel: slots(2) Oct 19 12:47:01 primo-v2 kernel: slot_width(32) Oct 19 12:47:01 primo-v2 kernel: lane_mask_in(0x2) Oct 19 12:47:01 primo-v2 kernel: lane_mask_out(0x1) Oct 19 12:47:01 primo-v2 kernel: lane_oe_mask_in(0x0) Oct 19 12:47:01 primo-v2 kernel: lane_oe_mask_out(0x0) Oct 19 12:47:01 primo-v2 kernel: lane_lb_mask_in(0x0) Oct 19 12:47:01 primo-v2 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Oct 19 12:47:01 primo-v2 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Oct 19 12:47:01 primo-v2 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Oct 19 12:47:01 primo-v2 kernel: aml_dai_set_clkdiv, div 4, clksel(1) Oct 19 12:47:01 primo-v2 kernel: aml_dai_set_bclk_ratio, select I2S mode Oct 19 12:47:01 primo-v2 kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Oct 19 12:47:01 primo-v2 kernel: aml_tdm_prepare(), reset fddr Oct 19 12:47:01 primo-v2 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Oct 19 12:47:01 primo-v2 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 19 12:47:01 primo-v2 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Oct 19 12:47:01 primo-v2 kernel: tdm playback mute: 0, lane_cnt = 8 Oct 19 12:47:01 primo-v2 kernel: asoc-aml-card auge_sound: tdm playback enable Oct 19 12:47:01 primo-v2 kernel: spdif_a is set to enable Oct 19 12:47:01 primo-v2 volumio[3090]: info: Executing endpoint tc_getconfig Oct 19 12:47:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 19 12:47:01 primo-v2 vtcs[3632]: STARTING TidalConnect services, version: 1.4.0.34 Oct 19 12:47:01 primo-v2 vtcs[3632]: STARTED TidalConnect services. Oct 19 12:47:02 primo-v2 volumio[3090]: info: Executing endpoint tc_connect Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 19 12:47:02 primo-v2 volumio[3090]: info: Connecting to TidalConnect Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreCommandRouter::servicePushState Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:47:02 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:47:02 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:02 primo-v2 volumio[3090]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 19 12:47:02 primo-v2 volumio[3090]: verbose: CURRENT POSITION 0 Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreStateMachine::syncState stateService stop Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreStateMachine::syncState currentStatus play Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreStateMachine::stPlaybackTimer Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreCommandRouter::servicePushState Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:47:02 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:47:02 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:02 primo-v2 volumio[3090]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 19 12:47:02 primo-v2 volumio[3090]: verbose: CURRENT POSITION 0 Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreStateMachine::syncState stateService stop Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreStateMachine::syncState currentStatus stop Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:47:02 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:47:02 primo-v2 volumio[3090]: info: No code Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:47:02 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:02 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:47:02 primo-v2 volumio-remote-updater[2826]: [2024-10-19 12:47:02] [connect] Successful connection Oct 19 12:47:02 primo-v2 volumio-remote-updater[2826]: [2024-10-19 12:47:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729313222 101 Oct 19 12:47:02 primo-v2 volumio[3090]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Oct 19 12:47:02 primo-v2 sudo[3660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.123/music /mnt/NAS/DS211 Oct 19 12:47:02 primo-v2 sudo[3660]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:02 primo-v2 sudo[3663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Media,password=87654331,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.3/music /mnt/NAS/NAS02 Oct 19 12:47:02 primo-v2 sudo[3663]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:03 primo-v2 kernel: asoc-aml-card auge_sound: tdm playback stop Oct 19 12:47:03 primo-v2 kernel: spdif_a is set to disable Oct 19 12:47:03 primo-v2 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Oct 19 12:47:03 primo-v2 kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Oct 19 12:47:03 primo-v2 kernel: tdm playback mute: 1, lane_cnt = 8 Oct 19 12:47:03 primo-v2 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 19 12:47:03 primo-v2 volumio[3090]: info: Successfully initialized Primo I2S Bus Oct 19 12:47:03 primo-v2 volumio[3090]: info: go-librespot daemon successfully initialized Oct 19 12:47:03 primo-v2 volumio[3583]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 19 12:47:04 primo-v2 volumio[3090]: info: TidalConnect service started! Oct 19 12:47:04 primo-v2 volumio[3090]: info: Completed starting Core Plugins Oct 19 12:47:04 primo-v2 volumio[3090]: info: ------------------------------------------- Oct 19 12:47:04 primo-v2 volumio[3090]: info: ----- MyVolumio plugins startup ---- Oct 19 12:47:04 primo-v2 volumio[3090]: info: ------------------------------------------- Oct 19 12:47:04 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 19 12:47:06 primo-v2 volumio[3583]: .................................++++ Oct 19 12:47:06 primo-v2 volumio[3090]: info: Initializing connection to go-librespot Websocket Oct 19 12:47:06 primo-v2 go-librespot[3587]: time="2024-10-19T12:47:06+08:00" level=debug msg="new websocket client" Oct 19 12:47:06 primo-v2 volumio[3090]: info: Connection to go-librespot Websocket established Oct 19 12:47:06 primo-v2 volumio[3090]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 19 12:47:08 primo-v2 volumio[3583]: ..........................++++ Oct 19 12:47:08 primo-v2 volumio[3583]: e is 65537 (0x010001) Oct 19 12:47:08 primo-v2 volumio[3583]: writing RSA key Oct 19 12:47:08 primo-v2 sudo[3660]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:08 primo-v2 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Oct 19 12:47:08 primo-v2 kernel: CIFS VFS: cifs_mount failed w/return code = -113 Oct 19 12:47:08 primo-v2 sudo[3663]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:08 primo-v2 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Oct 19 12:47:08 primo-v2 kernel: CIFS VFS: cifs_mount failed w/return code = -113 Oct 19 12:47:08 primo-v2 volumio[3090]: info: Cannot mount NAS NAS02 at system boot, trial number 2 ,retrying in 5 seconds Oct 19 12:47:08 primo-v2 volumio[3090]: info: Cannot mount NAS DS211 at system boot, trial number 2 ,retrying in 5 seconds Oct 19 12:47:08 primo-v2 volumio[3090]: info: Checking for updated MCU Firmware Oct 19 12:47:08 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 19 12:47:08 primo-v2 volumio[3090]: info: Firware on device is on latest version, no need to update Oct 19 12:47:09 primo-v2 volumio[3090]: info: Getting Spotify volume Oct 19 12:47:09 primo-v2 volumio[3090]: info: Spotify volume: 100 Oct 19 12:47:09 primo-v2 volumio[3090]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 19 12:47:09 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:09 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:09 primo-v2 volumio[3090]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 19 12:47:13 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 19 12:47:13 primo-v2 kernel: fb: mem_free_work, free memory: addr:800000 Oct 19 12:47:14 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 19 12:47:14 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 19 12:47:14 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:14 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:14 primo-v2 volumio[3090]: info: Starting MyVolumio Remote Streaming Endpoints Oct 19 12:47:14 primo-v2 volumio[3090]: info: MyVolumio login type: Token Oct 19 12:47:14 primo-v2 systemd[1]: systemd-hostnamed.service: Succeeded. Oct 19 12:47:14 primo-v2 volumio[3090]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 19 12:47:14 primo-v2 volumio[3090]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Oct 19 12:47:15 primo-v2 sudo[3702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Media,password=87654331,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.3/music /mnt/NAS/NAS02 Oct 19 12:47:15 primo-v2 sudo[3702]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:15 primo-v2 sudo[3705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.123/music /mnt/NAS/DS211 Oct 19 12:47:15 primo-v2 sudo[3705]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:15 primo-v2 volumio[3090]: error: MyVolumio Custom Token format not valid, refreshing it Oct 19 12:47:16 primo-v2 volumio[3090]: info: MyVolumio login type: Token Oct 19 12:47:16 primo-v2 volumio[3090]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 19 12:47:17 primo-v2 volumio[3090]: info: MyVolumio token set successfully Oct 19 12:47:17 primo-v2 volumio[3090]: info: MYVOLUMIO: Adding device Oct 19 12:47:17 primo-v2 volumio[3090]: info: MYVOLUMIO: Evaluating Server Oct 19 12:47:18 primo-v2 volumio[3090]: info: MyVolumio status changed Oct 19 12:47:18 primo-v2 volumio[3090]: info: Streaming services startup Oct 19 12:47:18 primo-v2 volumio[3090]: info: Starting Streaming Daemon Oct 19 12:47:18 primo-v2 volumio[3090]: info: Removing browser output: myVolumio user plan is not superstar Oct 19 12:47:18 primo-v2 volumio[3090]: info: Removing audio output: Oct 19 12:47:18 primo-v2 volumio[3090]: info: Stoppping Tunnel 1 Oct 19 12:47:18 primo-v2 sudo[3730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 19 12:47:18 primo-v2 sudo[3730]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:18 primo-v2 sudo[3730]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:18 primo-v2 volumio[3090]: error: Cannot start Volumio Streaming Daemon Oct 19 12:47:18 primo-v2 volumio[3090]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 19 12:47:18 primo-v2 volumio[3090]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 19 12:47:18 primo-v2 sudo[3734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 19 12:47:18 primo-v2 sudo[3734]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:18 primo-v2 sudo[3734]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:18 primo-v2 volumio[3090]: info: Remote SSH Stopped Oct 19 12:47:18 primo-v2 volumio[3090]: info: Setting Geolocation for MyVolumio to as1 Oct 19 12:47:18 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:18 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:18 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:19 primo-v2 volumio[3090]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 19 12:47:19 primo-v2 volumio[3090]: info: Updating MyVolumio device info Oct 19 12:47:19 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:19 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:19 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:20 primo-v2 volumio[3090]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 19 12:47:21 primo-v2 sudo[3702]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:21 primo-v2 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Oct 19 12:47:21 primo-v2 kernel: CIFS VFS: cifs_mount failed w/return code = -113 Oct 19 12:47:21 primo-v2 volumio[3090]: info: Cannot mount NAS NAS02 at system boot, trial number 3 ,retrying in 5 seconds Oct 19 12:47:21 primo-v2 sudo[3705]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:21 primo-v2 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Oct 19 12:47:21 primo-v2 kernel: CIFS VFS: cifs_mount failed w/return code = -113 Oct 19 12:47:21 primo-v2 volumio[3090]: info: Cannot mount NAS DS211 at system boot, trial number 3 ,retrying in 5 seconds Oct 19 12:47:23 primo-v2 volumio[3090]: info: MYVOLUMIO: Adding device Oct 19 12:47:23 primo-v2 volumio[3090]: info: MYVOLUMIO: Evaluating Server Oct 19 12:47:24 primo-v2 volumio[3090]: info: Setting Geolocation for MyVolumio to as1 Oct 19 12:47:24 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:24 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:24 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:24 primo-v2 volumio[3090]: error: MyVolumio Plugin failed to start in a timely fashion Oct 19 12:47:24 primo-v2 volumio[3090]: info: BOOT COMPLETED Oct 19 12:47:24 primo-v2 volumio[3090]: [Metrics] CommandRouter: 50s 749.70ms Oct 19 12:47:24 primo-v2 volumio[3090]: info: CoreCommandRouter::volumiosetStartupVolume Oct 19 12:47:24 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:47:24 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:24 primo-v2 volumio[3090]: info: CoreCommandRouter::Close All Modals sent Oct 19 12:47:24 primo-v2 volumio[3090]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 19 12:47:24 primo-v2 volumio[3090]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Oct 19 12:47:24 primo-v2 volumio[3090]: aplay: main:828: audio open error: No such device Oct 19 12:47:24 primo-v2 volumio[3090]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 19 12:47:25 primo-v2 volumio[3090]: info: Updating MyVolumio device info Oct 19 12:47:25 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:25 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:25 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:25 primo-v2 volumio[3090]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 19 12:47:25 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 19 12:47:25 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 19 12:47:25 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 19 12:47:25 primo-v2 volumio[3090]: info: Stopping RAAT Plugin Oct 19 12:47:25 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 19 12:47:25 primo-v2 sudo[3760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Oct 19 12:47:25 primo-v2 sudo[3760]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:25 primo-v2 sudo[3760]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:25 primo-v2 volumio[3090]: info: Raat Daemon stopped successfully Oct 19 12:47:26 primo-v2 sudo[3766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Media,password=87654331,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.3/music /mnt/NAS/NAS02 Oct 19 12:47:26 primo-v2 sudo[3766]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:26 primo-v2 sudo[3771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.123/music /mnt/NAS/DS211 Oct 19 12:47:26 primo-v2 sudo[3771]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:28 primo-v2 volumiologrotate[2825]: ls: cannot access '/var/log/samba/log.wb-PRIMO': No such file or directory Oct 19 12:47:28 primo-v2 volumiologrotate[2825]: ls: cannot access 'V2': No such file or directory Oct 19 12:47:28 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 19 12:47:28 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:47:28 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 19 12:47:28 primo-v2 volumio-remote-updater[2826]: No test mode Oct 19 12:47:28 primo-v2 volumio-remote-updater[2826]: No alpha test mode Oct 19 12:47:28 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 19 12:47:29 primo-v2 volumio[3090]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 19 12:47:29 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 19 12:47:29 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 12:47:29 primo-v2 volumio[3090]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 19 12:47:29 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:29 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:32 primo-v2 sudo[3766]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:32 primo-v2 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Oct 19 12:47:32 primo-v2 kernel: CIFS VFS: cifs_mount failed w/return code = -113 Oct 19 12:47:32 primo-v2 volumio[3090]: info: Cannot mount NAS NAS02 at system boot, trial number 4 ,retrying in 5 seconds Oct 19 12:47:32 primo-v2 sudo[3771]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:32 primo-v2 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Oct 19 12:47:32 primo-v2 kernel: CIFS VFS: cifs_mount failed w/return code = -113 Oct 19 12:47:32 primo-v2 volumio[3090]: info: Cannot mount NAS DS211 at system boot, trial number 4 ,retrying in 5 seconds Oct 19 12:47:34 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 12:47:34 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 12:47:34 primo-v2 volumio[3090]: info: Discovery: Getting this device information Oct 19 12:47:34 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:34 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:34 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 12:47:34 primo-v2 volumio[3090]: verbose: New Socket.io Connection to 192.168.50.247:3000 from 192.168.50.156 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 19 12:47:34 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:34 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:34 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 19 12:47:34 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 19 12:47:34 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:47:34 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:47:34 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 19 12:47:34 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 19 12:47:34 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 19 12:47:34 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetBrowseSources Oct 19 12:47:34 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 19 12:47:37 primo-v2 sudo[3820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 12:47:37 primo-v2 sudo[3820]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:37 primo-v2 sudo[3820]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:37 primo-v2 sudo[3823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 12:47:37 primo-v2 sudo[3823]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:37 primo-v2 sudo[3823]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:37 primo-v2 sudo[3827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Media,password=87654331,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.3/music /mnt/NAS/NAS02 Oct 19 12:47:37 primo-v2 sudo[3827]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:37 primo-v2 volumio[3090]: verbose: New Socket.io Connection to 192.168.50.247 from 192.168.50.156 UA: Mozilla/5.0 (Linux; Android 14; SM-G9980 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 19 12:47:37 primo-v2 sudo[3834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.123/music /mnt/NAS/DS211 Oct 19 12:47:37 primo-v2 sudo[3834]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:37 primo-v2 sudo[3839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 12:47:37 primo-v2 sudo[3839]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:37 primo-v2 sudo[3839]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:37 primo-v2 sudo[3843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 12:47:37 primo-v2 sudo[3843]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:47:37 primo-v2 sudo[3843]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:37 primo-v2 volumio[3090]: verbose: New Socket.io Connection to 192.168.50.247 from 192.168.50.156 UA: Mozilla/5.0 (Linux; Android 14; SM-G9980 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 19 12:47:37 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 19 12:47:38 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetVisibleSources Oct 19 12:47:38 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 12:47:38 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:38 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:38 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 19 12:47:38 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 19 12:47:38 primo-v2 volumio[3090]: info: Received Get System Info Oct 19 12:47:38 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 12:47:38 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 12:47:38 primo-v2 volumio[3090]: info: Discovery: Getting this device information Oct 19 12:47:38 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:38 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:38 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 12:47:38 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:38 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:38 primo-v2 volumio[3090]: info: Listing playlists Oct 19 12:47:38 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 19 12:47:38 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 12:47:38 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 19 12:47:39 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 12:47:39 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 12:47:39 primo-v2 volumio[3090]: info: Discovery: Getting this device information Oct 19 12:47:39 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:39 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:39 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 12:47:39 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetQueue Oct 19 12:47:39 primo-v2 volumio[3090]: info: CoreStateMachine::getQueue Oct 19 12:47:39 primo-v2 volumio[3090]: info: CorePlayQueue::getQueue Oct 19 12:47:39 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 19 12:47:39 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 19 12:47:39 primo-v2 volumio[3090]: info: Received Get System Info Oct 19 12:47:39 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 12:47:39 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 12:47:39 primo-v2 volumio[3090]: info: Discovery: Getting this device information Oct 19 12:47:39 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:39 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:39 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 12:47:41 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 19 12:47:41 primo-v2 volumio[3090]: info: Preload queue cleared Oct 19 12:47:41 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 19 12:47:41 primo-v2 volumio[3090]: info: Received Get System Info Oct 19 12:47:41 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 12:47:41 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 12:47:41 primo-v2 volumio[3090]: info: Discovery: Getting this device information Oct 19 12:47:41 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:41 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:41 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 12:47:43 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 19 12:47:43 primo-v2 sudo[3827]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:43 primo-v2 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Oct 19 12:47:43 primo-v2 kernel: CIFS VFS: cifs_mount failed w/return code = -113 Oct 19 12:47:43 primo-v2 volumio[3090]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 19 12:47:43 primo-v2 sudo[3834]: pam_unix(sudo:session): session closed for user root Oct 19 12:47:43 primo-v2 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Oct 19 12:47:43 primo-v2 kernel: CIFS VFS: cifs_mount failed w/return code = -113 Oct 19 12:47:43 primo-v2 volumio[3090]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 19 12:47:43 primo-v2 volumio[3090]: info: Preload queue cleared Oct 19 12:47:45 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 19 12:47:45 primo-v2 volumio[3090]: info: Preload queue cleared Oct 19 12:47:47 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 19 12:47:49 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 19 12:47:49 primo-v2 volumio[3090]: info: Preload queue cleared Oct 19 12:47:50 primo-v2 ntpd[3292]: Soliciting pool server 2001:470:19:6ee:0:123:123:123 Oct 19 12:47:52 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 19 12:47:52 primo-v2 volumio[3090]: info: Preload queue cleared Oct 19 12:47:53 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 19 12:47:53 primo-v2 volumio[3090]: info: Preload queue cleared Oct 19 12:47:54 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preload queue cleared Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$E Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$12 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$18 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$1A Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$4 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$F Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$16 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$14 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$11 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$13 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$B Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$3 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$15 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$9 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$8 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$7 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$D Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$19 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$C Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$6 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$10 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$A Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$17 Oct 19 12:47:54 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$1B Oct 19 12:47:54 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$E in service upnp_browser Oct 19 12:47:54 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$12 in service upnp_browser Oct 19 12:47:54 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$18 in service upnp_browser Oct 19 12:47:54 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$1A in service upnp_browser Oct 19 12:47:54 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$4 in service upnp_browser Oct 19 12:47:54 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$F in service upnp_browser Oct 19 12:47:54 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$16 in service upnp_browser Oct 19 12:47:54 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$14 in service upnp_browser Oct 19 12:47:54 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$11 in service upnp_browser Oct 19 12:47:54 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$13 in service upnp_browser Oct 19 12:47:54 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$B in service upnp_browser Oct 19 12:47:55 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$3 in service upnp_browser Oct 19 12:47:55 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$15 in service upnp_browser Oct 19 12:47:55 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$9 in service upnp_browser Oct 19 12:47:55 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$8 in service upnp_browser Oct 19 12:47:55 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$7 in service upnp_browser Oct 19 12:47:55 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$D in service upnp_browser Oct 19 12:47:55 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$19 in service upnp_browser Oct 19 12:47:55 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$C in service upnp_browser Oct 19 12:47:55 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$6 in service upnp_browser Oct 19 12:47:55 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$10 in service upnp_browser Oct 19 12:47:55 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$A in service upnp_browser Oct 19 12:47:55 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$17 in service upnp_browser Oct 19 12:47:55 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$0$0$1B in service upnp_browser Oct 19 12:47:57 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 12:47:57 primo-v2 volumio[3090]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 19 12:47:57 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 19 12:47:57 primo-v2 volumio[3090]: info: Received Get System Version Oct 19 12:47:57 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 19 12:47:57 primo-v2 volumio[3090]: info: Received Get System Info Oct 19 12:47:57 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 12:47:57 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 12:47:57 primo-v2 volumio[3090]: info: Discovery: Getting this device information Oct 19 12:47:57 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:47:57 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:47:57 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 12:48:00 primo-v2 kernel: usb 1-1.1: new high-speed USB device number 4 using xhci-hcd Oct 19 12:48:00 primo-v2 kernel: usb 1-1.1: New USB device found, idVendor=245f, idProduct=0815 Oct 19 12:48:00 primo-v2 kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 19 12:48:00 primo-v2 kernel: usb 1-1.1: Product: Qutest Oct 19 12:48:00 primo-v2 kernel: usb 1-1.1: Manufacturer: Chord Electronics Ltd Oct 19 12:48:00 primo-v2 kernel: usb 1-1.1: SerialNumber: 413-001 Oct 19 12:48:00 primo-v2 kernel: usb 1-1.1: Unsupported device Oct 19 12:48:00 primo-v2 kernel: usb 1-1.1: Unsupported device Oct 19 12:48:00 primo-v2 kernel: usb 1-1.1: Unsupported device Oct 19 12:48:00 primo-v2 kernel: usb 1-1.1: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Oct 19 12:48:00 primo-v2 kernel: usb 1-1.1: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Oct 19 12:48:00 primo-v2 kernel: usb 1-1.1: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Oct 19 12:48:00 primo-v2 kernel: usb 1-1.1: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Oct 19 12:48:00 primo-v2 kernel: usbcore: registered new interface driver snd-usb-audio Oct 19 12:48:00 primo-v2 volumio[3090]: info: Oct 19 12:48:00 primo-v2 volumio[3090]: ---------------------------- USB Audio Device Attached Oct 19 12:48:00 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 19 12:48:00 primo-v2 volumio[3090]: info: CoreCommandRouter::Close All Modals sent Oct 19 12:48:00 primo-v2 volumio[3090]: info: Preparing to save Alsa Options, stopping services first Oct 19 12:48:00 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:48:00 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:00 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPause Oct 19 12:48:00 primo-v2 volumio[3090]: info: CoreStateMachine::pause Oct 19 12:48:00 primo-v2 volumio[3090]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Qutest","alsacard":"Qutest"},"i2s":false} Oct 19 12:48:00 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 19 12:48:00 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 19 12:48:00 primo-v2 volumio[3090]: info: Setting mixer PCM for card Qutest Oct 19 12:48:00 primo-v2 volumio[3090]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 19 12:48:00 primo-v2 vtcs[3632]: [2024-10-19 12:48:00.916] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 19 12:48:00 primo-v2 vtcs[3632]: [2024-10-19 12:48:00.919] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Oct 19 12:48:00 primo-v2 vtcs[3632]: [2024-10-19 12:48:00.920] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 19 12:48:00 primo-v2 sudo[3867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 19 12:48:00 primo-v2 sudo[3867]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:00 primo-v2 systemd[1]: Stopping Volumio Tidal Connect Service... Oct 19 12:48:00 primo-v2 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 19 12:48:01 primo-v2 systemd[1]: vtcs.service: Succeeded. Oct 19 12:48:01 primo-v2 systemd[1]: Stopped Volumio Tidal Connect Service. Oct 19 12:48:01 primo-v2 sudo[3867]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 19 12:48:01 primo-v2 volumio[3090]: info: Updating Volume Controller Parameters: Device: 5 Name: Qutest Mixer: PCM Max Vol: 100 Vol Curve; linear Vol Steps: 1 Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 19 12:48:01 primo-v2 volumio[3090]: info: Disabling external Volume Control Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 12:48:01 primo-v2 volumio[3090]: info: Preparing to generate the ALSA configuration file Oct 19 12:48:01 primo-v2 sudo[3874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 19 12:48:01 primo-v2 sudo[3874]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:01 primo-v2 sudo[3874]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:48:01+08:00" level=error msg="did not receive last pong from dealer, 30s passed" Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 19 12:48:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:48:01+08:00" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusServiceRestart and reason = \"\"" Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 19 12:48:01 primo-v2 volumio[3090]: info: Asound.conf file unchanged, so no further update is needed Oct 19 12:48:01 primo-v2 volumio[3090]: info: Output device has changed, restarting MPD Oct 19 12:48:01 primo-v2 sudo[3884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 19 12:48:01 primo-v2 sudo[3884]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:01 primo-v2 volumio[3090]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 sudo[3884]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:01 primo-v2 sudo[3887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 19 12:48:01 primo-v2 sudo[3887]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:01 primo-v2 systemd[1]: Stopping Music Player Daemon... Oct 19 12:48:01 primo-v2 sudo[3890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 19 12:48:01 primo-v2 sudo[3890]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:01 primo-v2 sudo[3893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 19 12:48:01 primo-v2 sudo[3893]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:01 primo-v2 sudo[3890]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: Not Reporting Auto name since its the default one Oct 19 12:48:01 primo-v2 sudo[3893]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:01 primo-v2 volumio[3090]: info: MPD Permissions set Oct 19 12:48:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:48:01+08:00" level=debug msg="re-established dealer connection" Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:48:01 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:48:01+08:00" level=debug msg="received connection id: MzBmMDBkZDQtMjUxNi00ODExLWIzZTMtZjI4ZDc3NDQ4NzMyK2RlYWxlcit0Y3A6Ly8wYWNiZDk2YS5pcC5ndWMzLnNwb3RpZnkubmV0OjU3MDArOUYxOTU2RUUwREQzMjAxMUUxNEU3QkQxOEUyM0UyQTNEMzQ2MjhEMzdDMDU0MjI2NkUwMTg3M0ExRTY2M0U2MQ==" Oct 19 12:48:01 primo-v2 volumio[3090]: info: VolumeController:: Volume=99 Mute =false Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:48:01 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:48:01 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 19 12:48:01 primo-v2 go-librespot[3587]: time="2024-10-19T12:48:01+08:00" level=debug msg="put connect state because NEW_DEVICE" Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: Not Reporting Auto name since its the default one Oct 19 12:48:01 primo-v2 volumio[3090]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 19 12:48:01 primo-v2 volumio[3090]: SPOTIFY: SPOTIFY VOLUME 100 Oct 19 12:48:01 primo-v2 volumio[3090]: SPOTIFY: VOLUMIO VOLUME 99 Oct 19 12:48:01 primo-v2 volumio[3090]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 19 12:48:01 primo-v2 systemd[1]: mpd.service: Succeeded. Oct 19 12:48:01 primo-v2 systemd[1]: Stopped Music Player Daemon. Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 systemd[1]: Starting Music Player Daemon... Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 19 12:48:01 primo-v2 sudo[3905]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 19 12:48:01 primo-v2 sudo[3905]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:01 primo-v2 sudo[3905]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:01 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 19 12:48:01 primo-v2 sudo[3918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 19 12:48:01 primo-v2 sudo[3918]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:01 primo-v2 sudo[3918]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:01 primo-v2 sudo[3923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 19 12:48:01 primo-v2 sudo[3923]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:02 primo-v2 systemd[1]: Started RAAT DAEMON. Oct 19 12:48:02 primo-v2 sudo[3923]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:02 primo-v2 sudo[3927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 19 12:48:02 primo-v2 sudo[3927]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:02 primo-v2 sudo[3927]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:02 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:02 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:02 primo-v2 sudo[3938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 19 12:48:02 primo-v2 sudo[3938]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:02 primo-v2 systemd[1]: Stopping RAAT DAEMON... Oct 19 12:48:02 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:02 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 12:48:02 primo-v2 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 19 12:48:02 primo-v2 systemd[1]: raat-daemon.service: Succeeded. Oct 19 12:48:02 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 12:48:02 primo-v2 volumio[3090]: info: Not Reporting Auto name since its the default one Oct 19 12:48:02 primo-v2 systemd[1]: Stopped RAAT DAEMON. Oct 19 12:48:02 primo-v2 volumio[3090]: info: Raat Daemon started successfully Oct 19 12:48:02 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 19 12:48:02 primo-v2 systemd[1]: Started RAAT DAEMON. Oct 19 12:48:02 primo-v2 sudo[3938]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:02 primo-v2 volumio[3090]: info: Raat Daemon started successfully Oct 19 12:48:02 primo-v2 sudo[3956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 19 12:48:02 primo-v2 sudo[3956]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:02 primo-v2 volumio[3090]: info: Executing endpoint restartRAATSocket Oct 19 12:48:02 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 19 12:48:02 primo-v2 sudo[3956]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:02 primo-v2 sudo[3959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 19 12:48:02 primo-v2 sudo[3959]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:02 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:02 primo-v2 volumio[3090]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 19 12:48:02 primo-v2 systemd[1]: Stopping RAAT DAEMON... Oct 19 12:48:02 primo-v2 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 19 12:48:02 primo-v2 systemd[1]: raat-daemon.service: Succeeded. Oct 19 12:48:02 primo-v2 systemd[1]: Stopped RAAT DAEMON. Oct 19 12:48:02 primo-v2 systemd[1]: Started RAAT DAEMON. Oct 19 12:48:02 primo-v2 sudo[3959]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:02 primo-v2 volumio[3090]: info: Raat Daemon started successfully Oct 19 12:48:02 primo-v2 volumio[3090]: info: Executing endpoint restartRAATSocket Oct 19 12:48:02 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 19 12:48:02 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:02 primo-v2 volumio[3090]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 19 12:48:03 primo-v2 mpd[3919]: Oct 19 12:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 19 12:48:03 primo-v2 systemd[1]: Started Music Player Daemon. Oct 19 12:48:03 primo-v2 sudo[3887]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:03 primo-v2 volumio[3090]: error: updateQueue error: null Oct 19 12:48:04 primo-v2 volumio[3090]: info: TidalConnect service stoped! Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 12:48:04 primo-v2 volumio[3090]: info: Discovery: Getting this device information Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:48:04 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 12:48:04 primo-v2 volumio[3090]: info: TidalConnect service stoped! Oct 19 12:48:04 primo-v2 volumio[3090]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 19 12:48:04 primo-v2 volumio[3090]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 19 12:48:04 primo-v2 sudo[3985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 19 12:48:04 primo-v2 sudo[3985]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:04 primo-v2 systemd[1]: Started Volumio Tidal Connect Service. Oct 19 12:48:04 primo-v2 sudo[3985]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:04 primo-v2 volumio[3090]: info: TidalConnect service stoped! Oct 19 12:48:04 primo-v2 volumio[3090]: info: TidalConnect service stoped! Oct 19 12:48:04 primo-v2 volumio[3090]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 19 12:48:04 primo-v2 volumio[3090]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 19 12:48:04 primo-v2 volumio[3090]: info: Executing endpoint tc_getconfig Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 19 12:48:04 primo-v2 vtcs[3987]: STARTING TidalConnect services, version: 1.4.0.34 Oct 19 12:48:04 primo-v2 sudo[4001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 19 12:48:04 primo-v2 sudo[4001]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:04 primo-v2 sudo[4001]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:04 primo-v2 vtcs[3987]: STARTED TidalConnect services. Oct 19 12:48:04 primo-v2 volumio[3090]: info: Executing endpoint tc_connect Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 19 12:48:04 primo-v2 volumio[3090]: info: Connecting to TidalConnect Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreCommandRouter::servicePushState Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:48:04 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:48:04 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:04 primo-v2 volumio[3090]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 19 12:48:04 primo-v2 volumio[3090]: verbose: CURRENT POSITION 0 Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreStateMachine::syncState stateService stop Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreStateMachine::syncState currentStatus stop Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:48:04 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:48:04 primo-v2 volumio[3090]: info: No code Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:48:04 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreCommandRouter::servicePushState Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:48:04 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:48:04 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:04 primo-v2 volumio[3090]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 19 12:48:04 primo-v2 volumio[3090]: verbose: CURRENT POSITION 0 Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreStateMachine::syncState stateService stop Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreStateMachine::syncState currentStatus stop Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:48:04 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:48:04 primo-v2 volumio[3090]: info: No code Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:48:04 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:04 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: SPOTIFY VOLUME 100 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: VOLUMIO VOLUME 99 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: SPOTIFY VOLUME 100 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: VOLUMIO VOLUME 99 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: SPOTIFY VOLUME 100 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: VOLUMIO VOLUME 99 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: SPOTIFY VOLUME 100 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: VOLUMIO VOLUME 99 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: SPOTIFY VOLUME 100 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: VOLUMIO VOLUME 99 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: SPOTIFY VOLUME 100 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: VOLUMIO VOLUME 99 Oct 19 12:48:04 primo-v2 volumio[3090]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 19 12:48:04 primo-v2 volumio[3090]: info: Updating RAAT Signal Path Oct 19 12:48:04 primo-v2 volumio[3090]: info: Updating RAAT Signal Path Oct 19 12:48:04 primo-v2 volumio[3090]: info: Updating RAAT Signal Path Oct 19 12:48:04 primo-v2 volumio[3090]: info: Updating RAAT Signal Path Oct 19 12:48:04 primo-v2 volumio[3090]: info: Updating RAAT Signal Path Oct 19 12:48:04 primo-v2 volumio[3090]: info: Updating RAAT Signal Path Oct 19 12:48:05 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 19 12:48:05 primo-v2 volumio[3090]: info: CoreStateMachine::getcurrentVolume Oct 19 12:48:05 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioRetrievevolume Oct 19 12:48:05 primo-v2 volumio[3090]: info: VolumeController:: Volume=99 Mute =false Oct 19 12:48:05 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:48:05 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:05 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:48:05 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:05 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:48:05 primo-v2 volumio[3090]: info: CoreStateMachine::updateTrackBlock Oct 19 12:48:05 primo-v2 volumio[3090]: info: CorePlayQueue::getTrackBlock Oct 19 12:48:05 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioRetrievevolume Oct 19 12:48:05 primo-v2 volumio[3090]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 19 12:48:05 primo-v2 volumio[3090]: SPOTIFY: SPOTIFY VOLUME 100 Oct 19 12:48:05 primo-v2 volumio[3090]: SPOTIFY: VOLUMIO VOLUME 99 Oct 19 12:48:05 primo-v2 volumio[3090]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 19 12:48:05 primo-v2 volumio[3090]: info: Updating RAAT Signal Path Oct 19 12:48:05 primo-v2 volumio[3090]: info: VolumeController:: Volume=99 Mute =false Oct 19 12:48:05 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:48:05 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:05 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:48:05 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:05 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:48:05 primo-v2 volumio[3090]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 19 12:48:05 primo-v2 volumio[3090]: SPOTIFY: SPOTIFY VOLUME 100 Oct 19 12:48:05 primo-v2 volumio[3090]: SPOTIFY: VOLUMIO VOLUME 99 Oct 19 12:48:05 primo-v2 volumio[3090]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 19 12:48:05 primo-v2 volumio[3090]: info: Updating RAAT Signal Path Oct 19 12:48:07 primo-v2 volumio[3090]: info: TidalConnect service started! Oct 19 12:48:07 primo-v2 volumio[3090]: info: TidalConnect service started! Oct 19 12:48:17 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPlay Oct 19 12:48:17 primo-v2 volumio[3090]: info: CoreStateMachine::play index undefined Oct 19 12:48:17 primo-v2 volumio[3090]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 12:48:17 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:26 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPlay Oct 19 12:48:26 primo-v2 volumio[3090]: info: CoreStateMachine::play index undefined Oct 19 12:48:26 primo-v2 volumio[3090]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 12:48:26 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:28 primo-v2 sudo[4024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 12:48:28 primo-v2 sudo[4024]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:28 primo-v2 sudo[4024]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:28 primo-v2 sudo[4028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 12:48:28 primo-v2 sudo[4028]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:28 primo-v2 sudo[4028]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:28 primo-v2 volumio[3090]: verbose: New Socket.io Connection to 192.168.50.247 from 192.168.50.156 UA: Mozilla/5.0 (Linux; Android 14; SM-G9980 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 19 12:48:28 primo-v2 sudo[4032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 12:48:28 primo-v2 sudo[4032]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:28 primo-v2 sudo[4032]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:28 primo-v2 sudo[4036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 12:48:28 primo-v2 sudo[4036]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 12:48:28 primo-v2 sudo[4036]: pam_unix(sudo:session): session closed for user root Oct 19 12:48:28 primo-v2 volumio[3090]: verbose: New Socket.io Connection to 192.168.50.247 from 192.168.50.156 UA: Mozilla/5.0 (Linux; Android 14; SM-G9980 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetVisibleSources Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:48:28 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetQueue Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreStateMachine::getQueue Oct 19 12:48:28 primo-v2 volumio[3090]: info: CorePlayQueue::getQueue Oct 19 12:48:28 primo-v2 volumio[3090]: info: Listing playlists Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 19 12:48:28 primo-v2 volumio[3090]: info: Received Get System Info Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 12:48:28 primo-v2 volumio[3090]: info: Discovery: Getting this device information Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:48:28 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:48:28 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 12:48:28 primo-v2 volumiologrotate[2825]: ls: cannot access '/var/log/samba/log.wb-PRIMO': No such file or directory Oct 19 12:48:28 primo-v2 volumiologrotate[2825]: ls: cannot access 'V2': No such file or directory Oct 19 12:48:28 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 19 12:48:29 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPlay Oct 19 12:48:29 primo-v2 volumio[3090]: info: CoreStateMachine::play index undefined Oct 19 12:48:29 primo-v2 volumio[3090]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 12:48:29 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:30 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 19 12:48:30 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 19 12:48:30 primo-v2 volumio[3090]: info: Received Get System Info Oct 19 12:48:30 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 12:48:30 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 12:48:30 primo-v2 volumio[3090]: info: Discovery: Getting this device information Oct 19 12:48:30 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:48:30 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:30 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 12:48:31 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 19 12:48:31 primo-v2 volumio[3090]: info: Received Get System Info Oct 19 12:48:31 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 12:48:31 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 12:48:31 primo-v2 volumio[3090]: info: Discovery: Getting this device information Oct 19 12:48:31 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:48:31 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:31 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 12:48:33 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 19 12:48:33 primo-v2 volumio[3090]: info: Preload queue cleared Oct 19 12:48:34 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 12:48:34 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 12:48:34 primo-v2 volumio[3090]: info: Discovery: Getting this device information Oct 19 12:48:34 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:48:34 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:34 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 12:48:37 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 19 12:48:37 primo-v2 volumio[3090]: info: Preload queue cleared Oct 19 12:48:37 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$0 Oct 19 12:48:37 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$1 Oct 19 12:48:37 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$2 Oct 19 12:48:37 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$3 Oct 19 12:48:37 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$4 Oct 19 12:48:37 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$5 Oct 19 12:48:37 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$6 Oct 19 12:48:37 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$7 Oct 19 12:48:37 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$8 Oct 19 12:48:37 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$9 Oct 19 12:48:37 primo-v2 volumio[3090]: info: Preloading song: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$A Oct 19 12:48:37 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$0 in service upnp_browser Oct 19 12:48:37 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$1 in service upnp_browser Oct 19 12:48:37 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$2 in service upnp_browser Oct 19 12:48:37 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$3 in service upnp_browser Oct 19 12:48:37 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$4 in service upnp_browser Oct 19 12:48:38 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$5 in service upnp_browser Oct 19 12:48:38 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$6 in service upnp_browser Oct 19 12:48:38 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$7 in service upnp_browser Oct 19 12:48:38 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$8 in service upnp_browser Oct 19 12:48:38 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$9 in service upnp_browser Oct 19 12:48:38 primo-v2 volumio[3090]: info: Exploding uri upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$A in service upnp_browser Oct 19 12:48:38 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 19 12:48:40 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPlay Oct 19 12:48:40 primo-v2 volumio[3090]: info: CoreStateMachine::play index undefined Oct 19 12:48:40 primo-v2 volumio[3090]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 12:48:40 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Preload queue cleared Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::ClearQueue Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::stop Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 12:48:43 primo-v2 volumio[3090]: info: CorePlayQueue::clearPlayQueue Oct 19 12:48:43 primo-v2 volumio[3090]: info: CorePlayQueue::saveQueue Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushQueue Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::addQueueItems Oct 19 12:48:43 primo-v2 volumio[3090]: info: CorePlayQueue::addQueueItems Oct 19 12:48:43 primo-v2 volumio[3090]: info: Preload queue cleared Oct 19 12:48:43 primo-v2 volumio[3090]: info: Adding Item to queue: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$0 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Using cached record of: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$0 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Adding Item to queue: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$1 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Using cached record of: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$1 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Adding Item to queue: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$2 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Using cached record of: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$2 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Adding Item to queue: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$3 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Using cached record of: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$3 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Adding Item to queue: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$4 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Using cached record of: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$4 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Adding Item to queue: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$5 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Using cached record of: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$5 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Adding Item to queue: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$6 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Using cached record of: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$6 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Adding Item to queue: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$7 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Using cached record of: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$7 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Adding Item to queue: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$8 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Using cached record of: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$8 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Adding Item to queue: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$9 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Using cached record of: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$9 Oct 19 12:48:43 primo-v2 volumio[3090]: info: Adding Item to queue: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$A Oct 19 12:48:43 primo-v2 volumio[3090]: info: Using cached record of: upnp/http://192.168.50.1:8200/ctl/ContentDir@64$0$2$1$A Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushQueue Oct 19 12:48:43 primo-v2 volumio[3090]: info: CorePlayQueue::saveQueue Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::updateTrackBlock Oct 19 12:48:43 primo-v2 volumio[3090]: info: CorePlayQueue::getTrackBlock Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPlay Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::play index 0 Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::stop Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::play index undefined Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 12:48:43 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::startPlaybackTimer Oct 19 12:48:43 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:43 primo-v2 volumio[3090]: info: [1729313323504] ControllerUPNPBrowser::clearAddPlayTrack Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 12:48:43 primo-v2 volumio[3090]: info: sendMpdCommand stop took 10 milliseconds Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 12:48:43 primo-v2 volumio[3090]: info: Oct 19 12:48:43 primo-v2 volumio[3090]: ---------------------------- MPD announces system playlist update Oct 19 12:48:43 primo-v2 volumio[3090]: info: Ignoring MPD Status Update Oct 19 12:48:43 primo-v2 volumio[3090]: info: sendMpdCommand clear took 5 milliseconds Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.50.1:8200/MediaItems/107.mp3" Oct 19 12:48:43 primo-v2 volumio[3090]: error: updateQueue error: null Oct 19 12:48:43 primo-v2 volumio[3090]: info: ------------------------------ 8ms Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.50.1:8200/MediaItems/107.mp3" Oct 19 12:48:43 primo-v2 volumio[3090]: info: Oct 19 12:48:43 primo-v2 volumio[3090]: ---------------------------- MPD announces system playlist update Oct 19 12:48:43 primo-v2 volumio[3090]: info: Ignoring MPD Status Update Oct 19 12:48:43 primo-v2 volumio[3090]: info: sendMpdCommand add "http://192.168.50.1:8200/MediaItems/107.mp3" took 2 milliseconds Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: ControllerMpd::sendMpdCommand play Oct 19 12:48:43 primo-v2 volumio[3090]: info: ------------------------------ 5ms Oct 19 12:48:43 primo-v2 volumio[3090]: info: sendMpdCommand play took 4 milliseconds Oct 19 12:48:43 primo-v2 volumio[3090]: info: Oct 19 12:48:43 primo-v2 volumio[3090]: ---------------------------- MPD announces state update: player Oct 19 12:48:43 primo-v2 volumio[3090]: info: ControllerMpd::getState Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: ControllerMpd::sendMpdCommand status Oct 19 12:48:43 primo-v2 volumio[3090]: info: Oct 19 12:48:43 primo-v2 volumio[3090]: ---------------------------- MPD announces system playlist update Oct 19 12:48:43 primo-v2 volumio[3090]: info: Ignoring MPD Status Update Oct 19 12:48:43 primo-v2 volumio[3090]: info: Oct 19 12:48:43 primo-v2 volumio[3090]: ---------------------------- MPD announces state update: player Oct 19 12:48:43 primo-v2 volumio[3090]: info: sendMpdCommand status took 106 milliseconds Oct 19 12:48:43 primo-v2 volumio[3090]: info: ControllerMpd::getState Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: ControllerMpd::sendMpdCommand status Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: ControllerMpd::parseState Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 12:48:43 primo-v2 volumio[3090]: info: ------------------------------ 11ms Oct 19 12:48:43 primo-v2 volumio[3090]: info: sendMpdCommand status took 7 milliseconds Oct 19 12:48:43 primo-v2 volumio[3090]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: ControllerMpd::parseState Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: ControllerMpd::parseTrackInfo Oct 19 12:48:43 primo-v2 volumio[3090]: info: ControllerMpd::pushState Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreCommandRouter::servicePushState Oct 19 12:48:43 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"You Win Again","artist":"Bee Gees","album":"Mythology: Barry","uri":"http://192.168.50.1:8200/MediaItems/107.mp3","trackType":"mp3"} Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: CURRENT POSITION 0 Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::syncState stateService play Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::syncState currentStatus stop Oct 19 12:48:43 primo-v2 volumio[3090]: info: ------------------------------ 133ms Oct 19 12:48:43 primo-v2 volumio[3090]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: ControllerMpd::parseTrackInfo Oct 19 12:48:43 primo-v2 volumio[3090]: info: ControllerMpd::pushState Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreCommandRouter::servicePushState Oct 19 12:48:43 primo-v2 volumio[3090]: info: CorePlayQueue::getTrack 0 Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"You Win Again","artist":"Bee Gees","album":"Mythology: Barry","uri":"http://192.168.50.1:8200/MediaItems/107.mp3","trackType":"mp3"} Oct 19 12:48:43 primo-v2 volumio[3090]: verbose: CURRENT POSITION 0 Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::syncState stateService play Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::syncState currentStatus play Oct 19 12:48:43 primo-v2 volumio[3090]: info: Received an update from plugin. extracting info from payload Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::pushState Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioPushState Oct 19 12:48:43 primo-v2 volumio[3090]: info: ------------------------------ 56ms Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetQueue Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::getQueue Oct 19 12:48:43 primo-v2 volumio[3090]: info: CorePlayQueue::getQueue Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetQueue Oct 19 12:48:43 primo-v2 volumio[3090]: info: CoreStateMachine::getQueue Oct 19 12:48:43 primo-v2 volumio[3090]: info: CorePlayQueue::getQueue Oct 19 12:48:43 primo-v2 volumio[3090]: info: Signalling Playback active due to playback status change Oct 19 12:48:43 primo-v2 volumio[3090]: info: Signalling Playback active due to playback status change Oct 19 12:48:43 primo-v2 volumio[3090]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 19 12:48:43 primo-v2 volumio[3090]: SPOTIFY: SPOTIFY VOLUME 100 Oct 19 12:48:43 primo-v2 volumio[3090]: SPOTIFY: VOLUMIO VOLUME 99 Oct 19 12:48:43 primo-v2 volumio[3090]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 19 12:48:43 primo-v2 volumio[3090]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 19 12:48:43 primo-v2 volumio[3090]: SPOTIFY: SPOTIFY VOLUME 100 Oct 19 12:48:43 primo-v2 volumio[3090]: SPOTIFY: VOLUMIO VOLUME 99 Oct 19 12:48:43 primo-v2 volumio[3090]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 19 12:48:43 primo-v2 volumio[3090]: info: Updating RAAT Signal Path Oct 19 12:48:43 primo-v2 volumio[3090]: info: Updating RAAT Signal Path Oct 19 12:48:43 primo-v2 volumio[3090]: info: MCU Signalled Playback Active Oct 19 12:48:46 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 12:48:46 primo-v2 volumio[3090]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 19 12:48:46 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 19 12:48:46 primo-v2 volumio[3090]: info: Received Get System Version Oct 19 12:48:46 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 19 12:48:46 primo-v2 volumio[3090]: info: Received Get System Info Oct 19 12:48:46 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 12:48:46 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 12:48:46 primo-v2 volumio[3090]: info: Discovery: Getting this device information Oct 19 12:48:46 primo-v2 volumio[3090]: info: CoreCommandRouter::volumioGetState Oct 19 12:48:46 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 12:48:52 primo-v2 volumio[3090]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 19 12:48:52 primo-v2 volumio[3090]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 12:48:52 primo-v2 volumio[3090]: TypeError: Cannot read property 'length' of undefined Oct 19 12:48:52 primo-v2 volumio[3090]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 19 12:48:52 primo-v2 volumio[3090]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 19 12:48:52 primo-v2 volumio[3090]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 19 12:48:52 primo-v2 volumio[3090]: at Parser.emit (events.js:400:28) Oct 19 12:48:52 primo-v2 volumio[3090]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 19 12:48:52 primo-v2 volumio[3090]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 19 12:48:52 primo-v2 volumio[3090]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 19 12:48:52 primo-v2 volumio[3090]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 19 12:48:52 primo-v2 volumio[3090]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 19 12:48:52 primo-v2 volumio[3090]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 19 12:48:52 primo-v2 volumio[3090]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 19 12:48:52 primo-v2 volumio[3090]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 19 12:48:52 primo-v2 volumio[3090]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 19 12:48:52 primo-v2 volumio[3090]: at IncomingMessage.emit (events.js:412:35) Oct 19 12:48:52 primo-v2 volumio[3090]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 19 12:48:52 primo-v2 volumio[3090]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 19 12:48:52 primo-v2 volumio[3090]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 12:48:52 primo-v2 sudo[4094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-19 12:47 Oct 19 12:48:52 primo-v2 sudo[4094]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 06:34:13 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="4afef88585183bd857d5aee3dd000abc"