-- Logs begin at Sun 2024-06-23 18:01:27 EEST, end at Fri 2024-07-05 17:41:51 EEST. -- Jul 05 17:40:59 primo-2 volumiologrotate[2823]: ls: cannot access '/var/log/samba/log.wb-PRIMO': No such file or directory Jul 05 17:40:59 primo-2 volumiologrotate[2823]: ls: cannot access '2': No such file or directory Jul 05 17:41:36 primo-2 sudo[12944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 17:41:36 primo-2 sudo[12944]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 17:41:36 primo-2 sudo[12944]: pam_unix(sudo:session): session closed for user root Jul 05 17:41:36 primo-2 sudo[12947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 17:41:36 primo-2 sudo[12947]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 17:41:36 primo-2 sudo[12947]: pam_unix(sudo:session): session closed for user root Jul 05 17:41:37 primo-2 volumio[3155]: verbose: New Socket.io Connection to 10.13.21.155 from 192.168.211.131 UA: Mozilla/5.0 (Linux; Android 13; 23106RN0DA Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/126.0.6478.133 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jul 05 17:41:37 primo-2 volumio[3155]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 17:41:37 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 17:41:37 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 17:41:37 primo-2 volumio[3155]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 05 17:41:37 primo-2 volumio[3155]: info: CoreCommandRouter::volumioGetState Jul 05 17:41:37 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 05 17:41:37 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 05 17:41:37 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 05 17:41:37 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 05 17:41:37 primo-2 sudo[12952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 17:41:37 primo-2 sudo[12952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 17:41:37 primo-2 sudo[12952]: pam_unix(sudo:session): session closed for user root Jul 05 17:41:37 primo-2 sudo[12955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 17:41:37 primo-2 sudo[12955]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 17:41:37 primo-2 sudo[12955]: pam_unix(sudo:session): session closed for user root Jul 05 17:41:37 primo-2 volumio[3155]: verbose: New Socket.io Connection to 10.13.21.155 from 192.168.211.131 UA: Mozilla/5.0 (Linux; Android 13; 23106RN0DA Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/126.0.6478.133 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 05 17:41:37 primo-2 volumio[3155]: info: CoreCommandRouter::volumioGetState Jul 05 17:41:38 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 17:41:38 primo-2 volumio[3155]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 05 17:41:38 primo-2 volumio[3155]: info: Listing playlists Jul 05 17:41:38 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 05 17:41:38 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 05 17:41:38 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 05 17:41:38 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 05 17:41:40 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPlay Jul 05 17:41:40 primo-2 volumio[3155]: info: CoreStateMachine::play index undefined Jul 05 17:41:40 primo-2 volumio[3155]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 17:41:40 primo-2 volumio[3155]: info: CorePlayQueue::getTrack 1 Jul 05 17:41:40 primo-2 volumio[3155]: info: CoreStateMachine::startPlaybackTimer Jul 05 17:41:40 primo-2 volumio[3155]: info: CorePlayQueue::getTrack 1 Jul 05 17:41:40 primo-2 volumio[3155]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=1532443612 Jul 05 17:41:40 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand stop Jul 05 17:41:40 primo-2 volumio[3155]: info: sendMpdCommand stop took 1 milliseconds Jul 05 17:41:40 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand clear Jul 05 17:41:40 primo-2 volumio[3155]: info: sendMpdCommand clear took 1 milliseconds Jul 05 17:41:40 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-hls-opus-media.sndcdn.com/playlist/3b88adb5-0c68-437b-bd19-5a1faaee6b19.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvM2I4OGFkYjUtMGM2OC00MzdiLWJkMTktNWExZmFhZWU2YjE5LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3MjAxOTI4MTN9fX1dfQ__&Signature=NCtbJrjyNLEbPBj2p5HWCUCbghqJ2-8gR-NZ9eF-mcRNTuKDlfRMqpEqBub71TiONUfRx4zi4k5C69L-sKfa-LQHJqYNBBpHrSwVt9Un19q1yvoOFvJvVxlocoN8BgEeCKeWUbRUU3gmxijDDM6NRrlfoJXiQOGjJGc2ltM0m9vDgxHDT1dHAuwrJRIRv4hksuZ3cZlQklDtyz4KBb0Zd2WHrO2jZN0miIeiraoGm2pct06JZuI8wSOefxQQh1~Jy7B1lYtrrh704ONb4lqZleshT14o3ec8nm8choOXWWPknorzg-lRgxZxY2t-fMVF0nBgyHLRg18p43qxenqjfg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus" Jul 05 17:41:40 primo-2 volumio[3155]: info: Jul 05 17:41:40 primo-2 volumio[3155]: ---------------------------- MPD announces system playlist update Jul 05 17:41:40 primo-2 volumio[3155]: info: Ignoring MPD Status Update Jul 05 17:41:40 primo-2 volumio[3155]: info: Jul 05 17:41:40 primo-2 volumio[3155]: ---------------------------- MPD announces system playlist update Jul 05 17:41:40 primo-2 volumio[3155]: info: Ignoring MPD Status Update Jul 05 17:41:40 primo-2 volumio[3155]: info: sendMpdCommand addid "https://cf-hls-opus-media.sndcdn.com/playlist/3b88adb5-0c68-437b-bd19-5a1faaee6b19.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvM2I4OGFkYjUtMGM2OC00MzdiLWJkMTktNWExZmFhZWU2YjE5LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3MjAxOTI4MTN9fX1dfQ__&Signature=NCtbJrjyNLEbPBj2p5HWCUCbghqJ2-8gR-NZ9eF-mcRNTuKDlfRMqpEqBub71TiONUfRx4zi4k5C69L-sKfa-LQHJqYNBBpHrSwVt9Un19q1yvoOFvJvVxlocoN8BgEeCKeWUbRUU3gmxijDDM6NRrlfoJXiQOGjJGc2ltM0m9vDgxHDT1dHAuwrJRIRv4hksuZ3cZlQklDtyz4KBb0Zd2WHrO2jZN0miIeiraoGm2pct06JZuI8wSOefxQQh1~Jy7B1lYtrrh704ONb4lqZleshT14o3ec8nm8choOXWWPknorzg-lRgxZxY2t-fMVF0nBgyHLRg18p43qxenqjfg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus" took 6 milliseconds Jul 05 17:41:40 primo-2 volumio[3155]: verbose: MPD COMMAND [object Object] Jul 05 17:41:40 primo-2 volumio[3155]: verbose: MPD COMMAND [object Object] Jul 05 17:41:40 primo-2 volumio[3155]: verbose: MPD COMMAND [object Object] Jul 05 17:41:40 primo-2 volumio[3155]: info: Jul 05 17:41:40 primo-2 volumio[3155]: ---------------------------- MPD announces system playlist update Jul 05 17:41:40 primo-2 volumio[3155]: info: Ignoring MPD Status Update Jul 05 17:41:40 primo-2 volumio[3155]: info: Jul 05 17:41:40 primo-2 volumio[3155]: ---------------------------- MPD announces system playlist update Jul 05 17:41:40 primo-2 volumio[3155]: info: Ignoring MPD Status Update Jul 05 17:41:40 primo-2 volumio[3155]: error: Upnp client error: Error: This socket has been ended by the other party Jul 05 17:41:40 primo-2 volumio[3155]: info: Jul 05 17:41:40 primo-2 volumio[3155]: ---------------------------- MPD announces system playlist update Jul 05 17:41:40 primo-2 volumio[3155]: info: Ignoring MPD Status Update Jul 05 17:41:40 primo-2 volumio[3155]: info: ------------------------------ 17ms Jul 05 17:41:40 primo-2 volumio[3155]: info: ------------------------------ 15ms Jul 05 17:41:40 primo-2 volumio[3155]: info: ------------------------------ 10ms Jul 05 17:41:40 primo-2 volumio[3155]: info: ------------------------------ 7ms Jul 05 17:41:40 primo-2 volumio[3155]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 05 17:41:40 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand play Jul 05 17:41:40 primo-2 volumio[3155]: info: Jul 05 17:41:40 primo-2 volumio[3155]: ---------------------------- MPD announces system playlist update Jul 05 17:41:40 primo-2 volumio[3155]: info: Ignoring MPD Status Update Jul 05 17:41:40 primo-2 volumio[3155]: info: Jul 05 17:41:40 primo-2 volumio[3155]: ---------------------------- MPD announces system playlist update Jul 05 17:41:40 primo-2 volumio[3155]: info: Ignoring MPD Status Update Jul 05 17:41:40 primo-2 volumio[3155]: info: ------------------------------ 9ms Jul 05 17:41:40 primo-2 volumio[3155]: info: sendMpdCommand play took 6 milliseconds Jul 05 17:41:40 primo-2 volumio[3155]: info: ------------------------------ 5ms Jul 05 17:41:40 primo-2 volumio[3155]: info: ------------------------------ 4ms Jul 05 17:41:42 primo-2 kernel: aml_tdm_open Jul 05 17:41:42 primo-2 kernel: Not init audio effects Jul 05 17:41:42 primo-2 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 05 17:41:42 primo-2 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 05 17:41:42 primo-2 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 05 17:41:42 primo-2 kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Jul 05 17:41:42 primo-2 kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc0508b7618, id(1), clksel(1) Jul 05 17:41:42 primo-2 kernel: aml_dai_set_tdm_fmt(), fmt not change Jul 05 17:41:42 primo-2 kernel: dump_pcm_setting(ffffffc0508b7618) Jul 05 17:41:42 primo-2 kernel: pcm_mode(1) Jul 05 17:41:42 primo-2 kernel: sysclk(12288000) Jul 05 17:41:42 primo-2 kernel: sysclk_bclk_ratio(4) Jul 05 17:41:42 primo-2 kernel: bclk(3072000) Jul 05 17:41:42 primo-2 kernel: bclk_lrclk_ratio(64) Jul 05 17:41:42 primo-2 kernel: lrclk(48000) Jul 05 17:41:42 primo-2 kernel: tx_mask(0x3) Jul 05 17:41:42 primo-2 kernel: rx_mask(0x3) Jul 05 17:41:42 primo-2 kernel: slots(2) Jul 05 17:41:42 primo-2 kernel: slot_width(32) Jul 05 17:41:42 primo-2 kernel: lane_mask_in(0x2) Jul 05 17:41:42 primo-2 kernel: lane_mask_out(0x1) Jul 05 17:41:42 primo-2 kernel: lane_oe_mask_in(0x0) Jul 05 17:41:42 primo-2 kernel: lane_oe_mask_out(0x0) Jul 05 17:41:42 primo-2 kernel: lane_lb_mask_in(0x0) Jul 05 17:41:42 primo-2 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 05 17:41:42 primo-2 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 05 17:41:42 primo-2 kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Jul 05 17:41:42 primo-2 kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jul 05 17:41:42 primo-2 kernel: aml_dai_set_bclk_ratio, select I2S mode Jul 05 17:41:42 primo-2 kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jul 05 17:41:42 primo-2 kernel: aml_tdm_prepare(), reset fddr Jul 05 17:41:42 primo-2 kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Jul 05 17:41:42 primo-2 kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Jul 05 17:41:42 primo-2 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 05 17:41:42 primo-2 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 05 17:41:42 primo-2 kernel: aml_tdm_prepare(), reset fddr Jul 05 17:41:42 primo-2 kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Jul 05 17:41:42 primo-2 kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Jul 05 17:41:42 primo-2 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 05 17:41:42 primo-2 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 05 17:41:42 primo-2 volumio[3155]: info: Jul 05 17:41:42 primo-2 volumio[3155]: ---------------------------- MPD announces state update: player Jul 05 17:41:42 primo-2 volumio[3155]: info: ControllerMpd::getState Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:41:42 primo-2 volumio[3155]: info: Jul 05 17:41:42 primo-2 volumio[3155]: ---------------------------- MPD announces state update: player Jul 05 17:41:42 primo-2 volumio[3155]: info: ControllerMpd::getState Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:41:42 primo-2 volumio[3155]: info: Jul 05 17:41:42 primo-2 volumio[3155]: ---------------------------- MPD announces state update: player Jul 05 17:41:42 primo-2 volumio[3155]: info: ControllerMpd::getState Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:41:42 primo-2 volumio[3155]: info: Jul 05 17:41:42 primo-2 volumio[3155]: ---------------------------- MPD announces state update: player Jul 05 17:41:42 primo-2 volumio[3155]: info: ControllerMpd::getState Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:41:42 primo-2 volumio[3155]: info: sendMpdCommand status took 8 milliseconds Jul 05 17:41:42 primo-2 volumio[3155]: info: sendMpdCommand status took 5 milliseconds Jul 05 17:41:42 primo-2 volumio[3155]: info: sendMpdCommand status took 4 milliseconds Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::parseState Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::parseState Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::parseState Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:41:42 primo-2 volumio[3155]: info: Jul 05 17:41:42 primo-2 volumio[3155]: ---------------------------- MPD announces state update: player Jul 05 17:41:42 primo-2 volumio[3155]: info: ControllerMpd::getState Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:41:42 primo-2 volumio[3155]: info: Jul 05 17:41:42 primo-2 volumio[3155]: ---------------------------- MPD announces state update: player Jul 05 17:41:42 primo-2 volumio[3155]: info: ControllerMpd::getState Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:41:42 primo-2 volumio[3155]: info: sendMpdCommand status took 13 milliseconds Jul 05 17:41:42 primo-2 volumio[3155]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 05 17:41:42 primo-2 volumio[3155]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 05 17:41:42 primo-2 volumio[3155]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 05 17:41:42 primo-2 volumio[3155]: info: sendMpdCommand status took 13 milliseconds Jul 05 17:41:42 primo-2 volumio[3155]: info: sendMpdCommand status took 11 milliseconds Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::parseState Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::parseState Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::parseState Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:41:42 primo-2 volumio[3155]: info: ControllerMpd::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::servicePushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CorePlayQueue::getTrack 1 Jul 05 17:41:42 primo-2 volumio[3155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2193,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"EL FIN DE DANCA PARA DEUS (KEVIN ALVAREZ).mp3","artist":"Kevin Alvarez dj","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/3b88adb5-0c68-437b-bd19-5a1faaee6b19.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvM2I4OGFkYjUtMGM2OC00MzdiLWJkMTktNWExZmFhZWU2YjE5LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3MjAxOTI4MTN9fX1dfQ__&Signature=NCtbJrjyNLEbPBj2p5HWCUCbghqJ2-8gR-NZ9eF-mcRNTuKDlfRMqpEqBub71TiONUfRx4zi4k5C69L-sKfa-LQHJqYNBBpHrSwVt9Un19q1yvoOFvJvVxlocoN8BgEeCKeWUbRUU3gmxijDDM6NRrlfoJXiQOGjJGc2ltM0m9vDgxHDT1dHAuwrJRIRv4hksuZ3cZlQklDtyz4KBb0Zd2WHrO2jZN0miIeiraoGm2pct06JZuI8wSOefxQQh1~Jy7B1lYtrrh704ONb4lqZleshT14o3ec8nm8choOXWWPknorzg-lRgxZxY2t-fMVF0nBgyHLRg18p43qxenqjfg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Jul 05 17:41:42 primo-2 volumio[3155]: verbose: CURRENT POSITION 1 Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::syncState stateService play Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:41:42 primo-2 volumio[3155]: info: ControllerMpd::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::servicePushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CorePlayQueue::getTrack 1 Jul 05 17:41:42 primo-2 volumio[3155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2193,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"EL FIN DE DANCA PARA DEUS (KEVIN ALVAREZ).mp3","artist":"Kevin Alvarez dj","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/3b88adb5-0c68-437b-bd19-5a1faaee6b19.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvM2I4OGFkYjUtMGM2OC00MzdiLWJkMTktNWExZmFhZWU2YjE5LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3MjAxOTI4MTN9fX1dfQ__&Signature=NCtbJrjyNLEbPBj2p5HWCUCbghqJ2-8gR-NZ9eF-mcRNTuKDlfRMqpEqBub71TiONUfRx4zi4k5C69L-sKfa-LQHJqYNBBpHrSwVt9Un19q1yvoOFvJvVxlocoN8BgEeCKeWUbRUU3gmxijDDM6NRrlfoJXiQOGjJGc2ltM0m9vDgxHDT1dHAuwrJRIRv4hksuZ3cZlQklDtyz4KBb0Zd2WHrO2jZN0miIeiraoGm2pct06JZuI8wSOefxQQh1~Jy7B1lYtrrh704ONb4lqZleshT14o3ec8nm8choOXWWPknorzg-lRgxZxY2t-fMVF0nBgyHLRg18p43qxenqjfg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Jul 05 17:41:42 primo-2 volumio[3155]: verbose: CURRENT POSITION 1 Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::syncState stateService play Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:41:42 primo-2 volumio[3155]: info: Received an update from plugin. extracting info from payload Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPushState Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output update for this device Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPushState Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output update for this device Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output Jul 05 17:41:42 primo-2 volumio[3155]: info: ControllerMpd::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::servicePushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CorePlayQueue::getTrack 1 Jul 05 17:41:42 primo-2 volumio[3155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2193,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"EL FIN DE DANCA PARA DEUS (KEVIN ALVAREZ).mp3","artist":"Kevin Alvarez dj","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/3b88adb5-0c68-437b-bd19-5a1faaee6b19.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvM2I4OGFkYjUtMGM2OC00MzdiLWJkMTktNWExZmFhZWU2YjE5LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3MjAxOTI4MTN9fX1dfQ__&Signature=NCtbJrjyNLEbPBj2p5HWCUCbghqJ2-8gR-NZ9eF-mcRNTuKDlfRMqpEqBub71TiONUfRx4zi4k5C69L-sKfa-LQHJqYNBBpHrSwVt9Un19q1yvoOFvJvVxlocoN8BgEeCKeWUbRUU3gmxijDDM6NRrlfoJXiQOGjJGc2ltM0m9vDgxHDT1dHAuwrJRIRv4hksuZ3cZlQklDtyz4KBb0Zd2WHrO2jZN0miIeiraoGm2pct06JZuI8wSOefxQQh1~Jy7B1lYtrrh704ONb4lqZleshT14o3ec8nm8choOXWWPknorzg-lRgxZxY2t-fMVF0nBgyHLRg18p43qxenqjfg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Jul 05 17:41:42 primo-2 volumio[3155]: verbose: CURRENT POSITION 1 Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::syncState stateService play Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:41:42 primo-2 volumio[3155]: info: Received an update from plugin. extracting info from payload Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPushState Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output update for this device Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPushState Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output update for this device Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output Jul 05 17:41:42 primo-2 volumio[3155]: info: ------------------------------ 75ms Jul 05 17:41:42 primo-2 volumio[3155]: info: ------------------------------ 88ms Jul 05 17:41:42 primo-2 volumio[3155]: info: ------------------------------ 88ms Jul 05 17:41:42 primo-2 volumio[3155]: info: sendMpdCommand playlistinfo took 62 milliseconds Jul 05 17:41:42 primo-2 volumio[3155]: info: sendMpdCommand playlistinfo took 59 milliseconds Jul 05 17:41:42 primo-2 volumio[3155]: info: sendMpdCommand playlistinfo took 60 milliseconds Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:41:42 primo-2 volumio[3155]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:41:42 primo-2 volumio[3155]: info: ControllerMpd::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::servicePushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CorePlayQueue::getTrack 1 Jul 05 17:41:42 primo-2 volumio[3155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2193,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"EL FIN DE DANCA PARA DEUS (KEVIN ALVAREZ).mp3","artist":"Kevin Alvarez dj","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/3b88adb5-0c68-437b-bd19-5a1faaee6b19.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvM2I4OGFkYjUtMGM2OC00MzdiLWJkMTktNWExZmFhZWU2YjE5LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3MjAxOTI4MTN9fX1dfQ__&Signature=NCtbJrjyNLEbPBj2p5HWCUCbghqJ2-8gR-NZ9eF-mcRNTuKDlfRMqpEqBub71TiONUfRx4zi4k5C69L-sKfa-LQHJqYNBBpHrSwVt9Un19q1yvoOFvJvVxlocoN8BgEeCKeWUbRUU3gmxijDDM6NRrlfoJXiQOGjJGc2ltM0m9vDgxHDT1dHAuwrJRIRv4hksuZ3cZlQklDtyz4KBb0Zd2WHrO2jZN0miIeiraoGm2pct06JZuI8wSOefxQQh1~Jy7B1lYtrrh704ONb4lqZleshT14o3ec8nm8choOXWWPknorzg-lRgxZxY2t-fMVF0nBgyHLRg18p43qxenqjfg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Jul 05 17:41:42 primo-2 volumio[3155]: verbose: CURRENT POSITION 1 Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::syncState stateService play Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:41:42 primo-2 volumio[3155]: info: Received an update from plugin. extracting info from payload Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPushState Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output update for this device Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPushState Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output update for this device Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output Jul 05 17:41:42 primo-2 volumio[3155]: info: ControllerMpd::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::servicePushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CorePlayQueue::getTrack 1 Jul 05 17:41:42 primo-2 volumio[3155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2193,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"EL FIN DE DANCA PARA DEUS (KEVIN ALVAREZ).mp3","artist":"Kevin Alvarez dj","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/3b88adb5-0c68-437b-bd19-5a1faaee6b19.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvM2I4OGFkYjUtMGM2OC00MzdiLWJkMTktNWExZmFhZWU2YjE5LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3MjAxOTI4MTN9fX1dfQ__&Signature=NCtbJrjyNLEbPBj2p5HWCUCbghqJ2-8gR-NZ9eF-mcRNTuKDlfRMqpEqBub71TiONUfRx4zi4k5C69L-sKfa-LQHJqYNBBpHrSwVt9Un19q1yvoOFvJvVxlocoN8BgEeCKeWUbRUU3gmxijDDM6NRrlfoJXiQOGjJGc2ltM0m9vDgxHDT1dHAuwrJRIRv4hksuZ3cZlQklDtyz4KBb0Zd2WHrO2jZN0miIeiraoGm2pct06JZuI8wSOefxQQh1~Jy7B1lYtrrh704ONb4lqZleshT14o3ec8nm8choOXWWPknorzg-lRgxZxY2t-fMVF0nBgyHLRg18p43qxenqjfg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Jul 05 17:41:42 primo-2 volumio[3155]: verbose: CURRENT POSITION 1 Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::syncState stateService play Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:41:42 primo-2 volumio[3155]: info: Received an update from plugin. extracting info from payload Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPushState Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output update for this device Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPushState Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output update for this device Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output Jul 05 17:41:42 primo-2 volumio[3155]: info: ControllerMpd::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::servicePushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CorePlayQueue::getTrack 1 Jul 05 17:41:42 primo-2 volumio[3155]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2193,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"EL FIN DE DANCA PARA DEUS (KEVIN ALVAREZ).mp3","artist":"Kevin Alvarez dj","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/3b88adb5-0c68-437b-bd19-5a1faaee6b19.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvM2I4OGFkYjUtMGM2OC00MzdiLWJkMTktNWExZmFhZWU2YjE5LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3MjAxOTI4MTN9fX1dfQ__&Signature=NCtbJrjyNLEbPBj2p5HWCUCbghqJ2-8gR-NZ9eF-mcRNTuKDlfRMqpEqBub71TiONUfRx4zi4k5C69L-sKfa-LQHJqYNBBpHrSwVt9Un19q1yvoOFvJvVxlocoN8BgEeCKeWUbRUU3gmxijDDM6NRrlfoJXiQOGjJGc2ltM0m9vDgxHDT1dHAuwrJRIRv4hksuZ3cZlQklDtyz4KBb0Zd2WHrO2jZN0miIeiraoGm2pct06JZuI8wSOefxQQh1~Jy7B1lYtrrh704ONb4lqZleshT14o3ec8nm8choOXWWPknorzg-lRgxZxY2t-fMVF0nBgyHLRg18p43qxenqjfg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Jul 05 17:41:42 primo-2 volumio[3155]: verbose: CURRENT POSITION 1 Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::syncState stateService play Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:41:42 primo-2 volumio[3155]: info: Received an update from plugin. extracting info from payload Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPushState Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output update for this device Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPushState Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output update for this device Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output Jul 05 17:41:42 primo-2 kernel: asoc-aml-card auge_sound: tdm playback enable Jul 05 17:41:42 primo-2 kernel: spdif_a is set to enable Jul 05 17:41:42 primo-2 volumio[3155]: info: ------------------------------ 146ms Jul 05 17:41:42 primo-2 volumio[3155]: info: ------------------------------ 139ms Jul 05 17:41:42 primo-2 volumio[3155]: info: ------------------------------ 137ms Jul 05 17:41:42 primo-2 volumio[3155]: info: Signalling Playback active due to playback status change Jul 05 17:41:42 primo-2 volumio[3155]: info: Updating RAAT Signal Path Jul 05 17:41:42 primo-2 volumio[3155]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jul 05 17:41:42 primo-2 volumio[3155]: info: Signalling Playback active due to playback status change Jul 05 17:41:42 primo-2 volumio[3155]: info: Signalling Playback active due to playback status change Jul 05 17:41:42 primo-2 volumio[3155]: info: Signalling Playback active due to playback status change Jul 05 17:41:42 primo-2 volumio[3155]: info: Signalling Playback active due to playback status change Jul 05 17:41:42 primo-2 volumio[3155]: info: Signalling Playback active due to playback status change Jul 05 17:41:42 primo-2 volumio[3155]: info: Signalling Playback active due to playback status change Jul 05 17:41:42 primo-2 volumio[3155]: info: Signalling Playback active due to playback status change Jul 05 17:41:42 primo-2 volumio[3155]: info: Signalling Playback active due to playback status change Jul 05 17:41:42 primo-2 volumio[3155]: info: Signalling Playback active due to playback status change Jul 05 17:41:42 primo-2 volumio[3155]: info: Updating RAAT Signal Path Jul 05 17:41:42 primo-2 volumio[3155]: info: Updating RAAT Signal Path Jul 05 17:41:42 primo-2 volumio[3155]: info: Updating RAAT Signal Path Jul 05 17:41:42 primo-2 volumio[3155]: info: Updating RAAT Signal Path Jul 05 17:41:42 primo-2 volumio[3155]: info: Updating RAAT Signal Path Jul 05 17:41:42 primo-2 volumio[3155]: info: Updating RAAT Signal Path Jul 05 17:41:42 primo-2 volumio[3155]: info: Updating RAAT Signal Path Jul 05 17:41:42 primo-2 volumio[3155]: info: Updating RAAT Signal Path Jul 05 17:41:42 primo-2 volumio[3155]: info: Updating RAAT Signal Path Jul 05 17:41:42 primo-2 volumio[3155]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jul 05 17:41:42 primo-2 volumio[3155]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jul 05 17:41:42 primo-2 volumio[3155]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jul 05 17:41:42 primo-2 volumio[3155]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jul 05 17:41:42 primo-2 volumio[3155]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jul 05 17:41:42 primo-2 volumio[3155]: info: MCU Signalled Playback Active Jul 05 17:41:42 primo-2 volumio[3155]: info: MCU Signalled Sleep Mode Disabled Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreStateMachine::pushState Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 17:41:42 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPushState Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output update for this device Jul 05 17:41:42 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output Jul 05 17:41:42 primo-2 sudo[12963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jul 05 17:41:42 primo-2 sudo[12963]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 17:41:42 primo-2 volumio[3155]: info: Signalling Playback active due to playback status change Jul 05 17:41:42 primo-2 volumio[3155]: info: Updating RAAT Signal Path Jul 05 17:41:42 primo-2 volumio[3155]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jul 05 17:41:42 primo-2 volumio[3155]: SPOTIFY: SPOTIFY VOLUME 0 Jul 05 17:41:42 primo-2 volumio[3155]: SPOTIFY: VOLUMIO VOLUME 25 Jul 05 17:41:42 primo-2 volumio[3155]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 05 17:41:42 primo-2 volumio[3155]: info: Setting Spotify Volume from Volumio: 25 Jul 05 17:41:42 primo-2 kernel: fb: osd[0] enable: 1 (Xorg) Jul 05 17:41:42 primo-2 kernel: fb: osd[0] enable: 1 (Xorg) Jul 05 17:41:42 primo-2 sudo[12963]: pam_unix(sudo:session): session closed for user root Jul 05 17:41:42 primo-2 sudo[12966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jul 05 17:41:42 primo-2 sudo[12966]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 17:41:42 primo-2 sudo[12966]: pam_unix(sudo:session): session closed for user root Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPause Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::pause Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::stPlaybackTimer Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::servicePause Jul 05 17:41:43 primo-2 volumio[3155]: info: CorePlayQueue::getTrack 1 Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreCommandRouter::servicePause Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 05 17:41:43 primo-2 volumio[3155]: info: ControllerMpd::pause Jul 05 17:41:43 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand pause Jul 05 17:41:43 primo-2 volumio[3155]: info: Jul 05 17:41:43 primo-2 volumio[3155]: ---------------------------- MPD announces state update: player Jul 05 17:41:43 primo-2 volumio[3155]: info: sendMpdCommand pause took 4 milliseconds Jul 05 17:41:43 primo-2 kernel: asoc-aml-card auge_sound: tdm playback stop Jul 05 17:41:43 primo-2 kernel: spdif_a is set to disable Jul 05 17:41:43 primo-2 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 05 17:41:43 primo-2 kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jul 05 17:41:43 primo-2 kernel: tdm playback mute: 1, lane_cnt = 8 Jul 05 17:41:43 primo-2 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 05 17:41:43 primo-2 volumio[3155]: info: ControllerMpd::getState Jul 05 17:41:43 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:41:43 primo-2 volumio[3155]: info: Jul 05 17:41:43 primo-2 volumio[3155]: ---------------------------- MPD announces state update: player Jul 05 17:41:43 primo-2 volumio[3155]: info: ControllerMpd::getState Jul 05 17:41:43 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:41:43 primo-2 volumio[3155]: info: Jul 05 17:41:43 primo-2 volumio[3155]: ---------------------------- MPD announces state update: player Jul 05 17:41:43 primo-2 volumio[3155]: info: ControllerMpd::getState Jul 05 17:41:43 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:41:43 primo-2 volumio[3155]: info: sendMpdCommand status took 7 milliseconds Jul 05 17:41:43 primo-2 volumio[3155]: info: sendMpdCommand status took 5 milliseconds Jul 05 17:41:43 primo-2 volumio[3155]: info: sendMpdCommand status took 2 milliseconds Jul 05 17:41:43 primo-2 volumio[3155]: verbose: ControllerMpd::parseState Jul 05 17:41:43 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:41:43 primo-2 volumio[3155]: verbose: ControllerMpd::parseState Jul 05 17:41:43 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:41:43 primo-2 volumio[3155]: verbose: ControllerMpd::parseState Jul 05 17:41:43 primo-2 volumio[3155]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:41:43 primo-2 volumio[3155]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 05 17:41:43 primo-2 volumio[3155]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 05 17:41:43 primo-2 volumio[3155]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 05 17:41:43 primo-2 volumio[3155]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:41:43 primo-2 volumio[3155]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:41:43 primo-2 volumio[3155]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:41:43 primo-2 volumio[3155]: info: ControllerMpd::pushState Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreCommandRouter::servicePushState Jul 05 17:41:43 primo-2 volumio[3155]: info: CorePlayQueue::getTrack 1 Jul 05 17:41:43 primo-2 volumio[3155]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2488,"duration":2193,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"EL FIN DE DANCA PARA DEUS (KEVIN ALVAREZ).mp3","artist":"Kevin Alvarez dj","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/3b88adb5-0c68-437b-bd19-5a1faaee6b19.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvM2I4OGFkYjUtMGM2OC00MzdiLWJkMTktNWExZmFhZWU2YjE5LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3MjAxOTI4MTN9fX1dfQ__&Signature=NCtbJrjyNLEbPBj2p5HWCUCbghqJ2-8gR-NZ9eF-mcRNTuKDlfRMqpEqBub71TiONUfRx4zi4k5C69L-sKfa-LQHJqYNBBpHrSwVt9Un19q1yvoOFvJvVxlocoN8BgEeCKeWUbRUU3gmxijDDM6NRrlfoJXiQOGjJGc2ltM0m9vDgxHDT1dHAuwrJRIRv4hksuZ3cZlQklDtyz4KBb0Zd2WHrO2jZN0miIeiraoGm2pct06JZuI8wSOefxQQh1~Jy7B1lYtrrh704ONb4lqZleshT14o3ec8nm8choOXWWPknorzg-lRgxZxY2t-fMVF0nBgyHLRg18p43qxenqjfg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Jul 05 17:41:43 primo-2 volumio[3155]: verbose: CURRENT POSITION 1 Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::syncState stateService pause Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::syncState currentStatus pause Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::pushState Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPushState Jul 05 17:41:43 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output update for this device Jul 05 17:41:43 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::stPlaybackTimer Jul 05 17:41:43 primo-2 volumio[3155]: info: ControllerMpd::pushState Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreCommandRouter::servicePushState Jul 05 17:41:43 primo-2 volumio[3155]: info: CorePlayQueue::getTrack 1 Jul 05 17:41:43 primo-2 volumio[3155]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2488,"duration":2193,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"EL FIN DE DANCA PARA DEUS (KEVIN ALVAREZ).mp3","artist":"Kevin Alvarez dj","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/3b88adb5-0c68-437b-bd19-5a1faaee6b19.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvM2I4OGFkYjUtMGM2OC00MzdiLWJkMTktNWExZmFhZWU2YjE5LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3MjAxOTI4MTN9fX1dfQ__&Signature=NCtbJrjyNLEbPBj2p5HWCUCbghqJ2-8gR-NZ9eF-mcRNTuKDlfRMqpEqBub71TiONUfRx4zi4k5C69L-sKfa-LQHJqYNBBpHrSwVt9Un19q1yvoOFvJvVxlocoN8BgEeCKeWUbRUU3gmxijDDM6NRrlfoJXiQOGjJGc2ltM0m9vDgxHDT1dHAuwrJRIRv4hksuZ3cZlQklDtyz4KBb0Zd2WHrO2jZN0miIeiraoGm2pct06JZuI8wSOefxQQh1~Jy7B1lYtrrh704ONb4lqZleshT14o3ec8nm8choOXWWPknorzg-lRgxZxY2t-fMVF0nBgyHLRg18p43qxenqjfg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Jul 05 17:41:43 primo-2 volumio[3155]: verbose: CURRENT POSITION 1 Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::syncState stateService pause Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::syncState currentStatus pause Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::pushState Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPushState Jul 05 17:41:43 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output update for this device Jul 05 17:41:43 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::stPlaybackTimer Jul 05 17:41:43 primo-2 volumio[3155]: info: ControllerMpd::pushState Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreCommandRouter::servicePushState Jul 05 17:41:43 primo-2 volumio[3155]: info: CorePlayQueue::getTrack 1 Jul 05 17:41:43 primo-2 volumio[3155]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2488,"duration":2193,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"EL FIN DE DANCA PARA DEUS (KEVIN ALVAREZ).mp3","artist":"Kevin Alvarez dj","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/3b88adb5-0c68-437b-bd19-5a1faaee6b19.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvM2I4OGFkYjUtMGM2OC00MzdiLWJkMTktNWExZmFhZWU2YjE5LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3MjAxOTI4MTN9fX1dfQ__&Signature=NCtbJrjyNLEbPBj2p5HWCUCbghqJ2-8gR-NZ9eF-mcRNTuKDlfRMqpEqBub71TiONUfRx4zi4k5C69L-sKfa-LQHJqYNBBpHrSwVt9Un19q1yvoOFvJvVxlocoN8BgEeCKeWUbRUU3gmxijDDM6NRrlfoJXiQOGjJGc2ltM0m9vDgxHDT1dHAuwrJRIRv4hksuZ3cZlQklDtyz4KBb0Zd2WHrO2jZN0miIeiraoGm2pct06JZuI8wSOefxQQh1~Jy7B1lYtrrh704ONb4lqZleshT14o3ec8nm8choOXWWPknorzg-lRgxZxY2t-fMVF0nBgyHLRg18p43qxenqjfg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Jul 05 17:41:43 primo-2 volumio[3155]: verbose: CURRENT POSITION 1 Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::syncState stateService pause Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::syncState currentStatus pause Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::pushState Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreCommandRouter::volumioPushState Jul 05 17:41:43 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output update for this device Jul 05 17:41:43 primo-2 volumio[3155]: info: MRS: Pushing multiroomSync output Jul 05 17:41:43 primo-2 volumio[3155]: info: CoreStateMachine::stPlaybackTimer Jul 05 17:41:43 primo-2 volumio[3155]: info: ------------------------------ 59ms Jul 05 17:41:43 primo-2 volumio[3155]: info: ------------------------------ 53ms Jul 05 17:41:43 primo-2 volumio[3155]: info: ------------------------------ 52ms Jul 05 17:41:43 primo-2 volumio[3155]: info: Updating RAAT Signal Path Jul 05 17:41:43 primo-2 volumio[3155]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jul 05 17:41:43 primo-2 volumio[3155]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jul 05 17:41:43 primo-2 volumio[3155]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jul 05 17:41:43 primo-2 volumio[3155]: info: Updating RAAT Signal Path Jul 05 17:41:43 primo-2 volumio[3155]: info: Updating RAAT Signal Path Jul 05 17:41:43 primo-2 volumio[3155]: info: MCU Signalled Playback Inactive Jul 05 17:41:43 primo-2 volumio[3155]: SPOTIFY: SETTING SPOTIFY VOLUME 25 Jul 05 17:41:43 primo-2 volumio[3155]: info: Sending Spotify command with payload to local API: /player/volume Jul 05 17:41:46 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jul 05 17:41:46 primo-2 volumio[3155]: info: Playing Remote Device: http://10.13.21.158 Jul 05 17:41:46 primo-2 volumio[3155]: info: Done playing: Jul 05 17:41:48 primo-2 volumio[3155]: info: MRS: Updating multiroomSync output Jul 05 17:41:48 primo-2 volumio[3155]: info: MRS: Updating multiroomSync output Jul 05 17:41:48 primo-2 volumio[3155]: info: MRS: Updating multiroomSync output Jul 05 17:41:48 primo-2 volumio[3155]: info: MRS: Updating multiroomSync output Jul 05 17:41:48 primo-2 volumio[3155]: info: MRS: Updating multiroomSync output Jul 05 17:41:49 primo-2 volumio[3155]: info: MRS: Updating multiroomSync output Jul 05 17:41:50 primo-2 volumio[3155]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Jul 05 17:41:50 primo-2 volumio[3155]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Jul 05 17:41:50 primo-2 volumio[3155]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 17:41:50 primo-2 volumio[3155]: TypeError: Cannot read property 'then' of undefined Jul 05 17:41:50 primo-2 volumio[3155]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Jul 05 17:41:50 primo-2 volumio[3155]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2260:30) Jul 05 17:41:50 primo-2 volumio[3155]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1435:26) Jul 05 17:41:50 primo-2 volumio[3155]: at Socket.emit (events.js:400:28) Jul 05 17:41:50 primo-2 volumio[3155]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 05 17:41:50 primo-2 volumio[3155]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jul 05 17:41:50 primo-2 volumio[3155]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 17:41:51 primo-2 sudo[12988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-05 17:40 Jul 05 17:41:51 primo-2 sudo[12988]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 10:05:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="8bc35545f162f3e4d5b68abc51ef9937"