-- 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"