-- Logs begin at Thu 2019-02-14 14:11:59 +04, end at Sun 2024-08-18 12:50:27 +04. -- Aug 18 12:49:09 joji-volumio volumio[937]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Aug 18 12:49:09 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Aug 18 12:49:09 joji-volumio volumio[937]: info: Creating Spotify config file Aug 18 12:49:09 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 12:49:09 joji-volumio volumio[937]: info: Spotify config file written Aug 18 12:49:09 joji-volumio sudo[5770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 18 12:49:09 joji-volumio sudo[5770]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 12:49:09 joji-volumio systemd[1]: Stopping go-librespot Daemon... Aug 18 12:49:09 joji-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Aug 18 12:49:09 joji-volumio systemd[1]: go-librespot-daemon.service: Succeeded. Aug 18 12:49:09 joji-volumio systemd[1]: Stopped go-librespot Daemon. Aug 18 12:49:09 joji-volumio volumio[937]: info: Connection to go-librespot Websocket closed Aug 18 12:49:09 joji-volumio systemd[1]: Started go-librespot Daemon. Aug 18 12:49:09 joji-volumio go-librespot[5772]: Librespot-go daemon starting... Aug 18 12:49:09 joji-volumio sudo[5770]: pam_unix(sudo:session): session closed for user root Aug 18 12:49:09 joji-volumio go-librespot[5772]: time="2024-08-18T12:49:09+04:00" level=info msg="generated new device id: e607c1319b970fc4e133b2050c776121e18581ed" Aug 18 12:49:09 joji-volumio go-librespot[5772]: time="2024-08-18T12:49:09+04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 18 12:49:09 joji-volumio go-librespot[5772]: time="2024-08-18T12:49:09+04:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 18 12:49:09 joji-volumio go-librespot[5772]: time="2024-08-18T12:49:09+04:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 18 12:49:09 joji-volumio go-librespot[5772]: time="2024-08-18T12:49:09+04:00" level=debug msg="zeroconf server listening on port 42493" Aug 18 12:49:12 joji-volumio volumio[937]: info: Initializing connection to go-librespot Websocket Aug 18 12:49:12 joji-volumio go-librespot[5772]: time="2024-08-18T12:49:12+04:00" level=debug msg="new websocket client" Aug 18 12:49:12 joji-volumio volumio[937]: info: Connection to go-librespot Websocket established Aug 18 12:49:12 joji-volumio volumio[937]: info: go-librespot daemon successfully initialized Aug 18 12:49:15 joji-volumio volumio[937]: info: Getting Spotify volume Aug 18 12:49:15 joji-volumio volumio[937]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 18 12:49:15 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:49:15 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:49:15 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:49:15 joji-volumio volumio[937]: info: Initializing connection to go-librespot Websocket Aug 18 12:49:15 joji-volumio go-librespot[5772]: time="2024-08-18T12:49:15+04:00" level=debug msg="new websocket client" Aug 18 12:49:15 joji-volumio volumio[937]: info: Connection to go-librespot Websocket established Aug 18 12:49:15 joji-volumio volumio[937]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Aug 18 12:49:15 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Aug 18 12:49:15 joji-volumio volumio[937]: info: Creating Spotify config file Aug 18 12:49:15 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 12:49:15 joji-volumio volumio[937]: info: Spotify config file written Aug 18 12:49:15 joji-volumio sudo[5782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 18 12:49:15 joji-volumio sudo[5782]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 12:49:15 joji-volumio systemd[1]: Stopping go-librespot Daemon... Aug 18 12:49:15 joji-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Aug 18 12:49:15 joji-volumio systemd[1]: go-librespot-daemon.service: Succeeded. Aug 18 12:49:15 joji-volumio systemd[1]: Stopped go-librespot Daemon. Aug 18 12:49:15 joji-volumio volumio[937]: info: Connection to go-librespot Websocket closed Aug 18 12:49:15 joji-volumio volumio[937]: info: Connection to go-librespot Websocket closed Aug 18 12:49:15 joji-volumio systemd[1]: Started go-librespot Daemon. Aug 18 12:49:15 joji-volumio go-librespot[5784]: Librespot-go daemon starting... Aug 18 12:49:15 joji-volumio sudo[5782]: pam_unix(sudo:session): session closed for user root Aug 18 12:49:15 joji-volumio go-librespot[5784]: time="2024-08-18T12:49:15+04:00" level=info msg="generated new device id: 06cbb6a6fcdbe4d0d8f39cda35eb2112230bb02b" Aug 18 12:49:15 joji-volumio go-librespot[5784]: time="2024-08-18T12:49:15+04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 18 12:49:15 joji-volumio go-librespot[5784]: time="2024-08-18T12:49:15+04:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 18 12:49:15 joji-volumio go-librespot[5784]: time="2024-08-18T12:49:15+04:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 18 12:49:15 joji-volumio go-librespot[5784]: time="2024-08-18T12:49:15+04:00" level=debug msg="zeroconf server listening on port 38087" Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPlay Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreStateMachine::play index undefined Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 12:49:17 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreStateMachine::startPlaybackTimer Aug 18 12:49:17 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:49:17 joji-volumio volumio[937]: info: ControllerMpd::resume Aug 18 12:49:17 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand play Aug 18 12:49:17 joji-volumio volumio[937]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 18 12:49:17 joji-volumio volumio[937]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 18 12:49:17 joji-volumio volumio[937]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 18 12:49:17 joji-volumio volumio[937]: info: Aug 18 12:49:17 joji-volumio volumio[937]: ---------------------------- MPD announces state update: player Aug 18 12:49:17 joji-volumio volumio[937]: info: sendMpdCommand play took 24 milliseconds Aug 18 12:49:17 joji-volumio volumio[937]: info: ControllerMpd::getState Aug 18 12:49:17 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:49:17 joji-volumio volumio[937]: info: Aug 18 12:49:17 joji-volumio volumio[937]: ---------------------------- MPD announces state update: player Aug 18 12:49:17 joji-volumio volumio[937]: info: ControllerMpd::getState Aug 18 12:49:17 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:49:17 joji-volumio volumio[937]: info: sendMpdCommand status took 2 milliseconds Aug 18 12:49:17 joji-volumio volumio[937]: info: sendMpdCommand status took 2 milliseconds Aug 18 12:49:17 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:49:17 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 12:49:17 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:49:17 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 12:49:17 joji-volumio volumio[937]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 18 12:49:17 joji-volumio volumio[937]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 18 12:49:17 joji-volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo Aug 18 12:49:17 joji-volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo Aug 18 12:49:17 joji-volumio volumio[937]: info: ControllerMpd::pushState Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreCommandRouter::servicePushState Aug 18 12:49:17 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:49:17 joji-volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":210457,"duration":428,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track 02","artist":"BOB MARLY","album":"LEGEND","uri":"USB/JOJI/music/BOB MARLY - Track 02.mp3","trackType":"mp3"} Aug 18 12:49:17 joji-volumio volumio[937]: verbose: CURRENT POSITION 5 Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreStateMachine::syncState stateService play Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreStateMachine::syncState currentStatus pause Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:49:17 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:49:17 joji-volumio volumio[937]: info: ControllerMpd::pushState Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreCommandRouter::servicePushState Aug 18 12:49:17 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:49:17 joji-volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":210457,"duration":428,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track 02","artist":"BOB MARLY","album":"LEGEND","uri":"USB/JOJI/music/BOB MARLY - Track 02.mp3","trackType":"mp3"} Aug 18 12:49:17 joji-volumio volumio[937]: verbose: CURRENT POSITION 5 Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreStateMachine::syncState stateService play Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play Aug 18 12:49:17 joji-volumio volumio[937]: info: Received an update from plugin. extracting info from payload Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:49:17 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:49:17 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:49:17 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:49:17 joji-volumio volumio[937]: info: ------------------------------ 18ms Aug 18 12:49:17 joji-volumio volumio[937]: info: ------------------------------ 17ms Aug 18 12:49:17 joji-volumio volumio[937]: info: FusionDsp - {"Reload":{"result":"Ok"}} Aug 18 12:49:17 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:49:17 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:49:17 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:49:18 joji-volumio volumio[937]: info: Getting Spotify volume Aug 18 12:49:18 joji-volumio volumio[937]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 18 12:49:18 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:49:18 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:49:18 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:49:18 joji-volumio volumio[937]: info: Initializing connection to go-librespot Websocket Aug 18 12:49:18 joji-volumio go-librespot[5784]: time="2024-08-18T12:49:18+04:00" level=debug msg="new websocket client" Aug 18 12:49:18 joji-volumio volumio[937]: info: Connection to go-librespot Websocket established Aug 18 12:49:18 joji-volumio volumio[937]: info: go-librespot daemon successfully initialized Aug 18 12:49:21 joji-volumio volumio[937]: info: Getting Spotify volume Aug 18 12:49:21 joji-volumio volumio[937]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 18 12:49:21 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:49:21 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:49:21 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:49:21 joji-volumio volumio[937]: info: Initializing connection to go-librespot Websocket Aug 18 12:49:21 joji-volumio go-librespot[5784]: time="2024-08-18T12:49:21+04:00" level=debug msg="new websocket client" Aug 18 12:49:21 joji-volumio volumio[937]: info: Connection to go-librespot Websocket established Aug 18 12:49:22 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 12:49:22 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 12:49:22 joji-volumio volumio[937]: info: Discovery: Getting this device information Aug 18 12:49:22 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:49:22 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:49:22 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 12:49:24 joji-volumio volumio[937]: info: Getting Spotify volume Aug 18 12:49:24 joji-volumio volumio[937]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 18 12:49:24 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:49:24 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:49:24 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:49:40 joji-volumio volumiologrotate[601]: ls: cannot access '/var/log/samba/log.wb-JOJI': No such file or directory Aug 18 12:49:40 joji-volumio volumiologrotate[601]: ls: cannot access 'VOLUMIO': No such file or directory Aug 18 12:49:42 joji-volumio ntpd[758]: Soliciting pool server 2001:ac8:81:65:0:2:0:2 Aug 18 12:49:46 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 18 12:49:46 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 18 12:49:51 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetQueue Aug 18 12:49:51 joji-volumio volumio[937]: info: CoreStateMachine::getQueue Aug 18 12:49:51 joji-volumio volumio[937]: info: CorePlayQueue::getQueue Aug 18 12:49:52 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 12:49:52 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 12:49:52 joji-volumio volumio[937]: info: Discovery: Getting this device information Aug 18 12:49:52 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:49:52 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:49:52 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 12:50:00 joji-volumio sudo[5854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 12:50:00 joji-volumio sudo[5854]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 12:50:00 joji-volumio sudo[5854]: pam_unix(sudo:session): session closed for user root Aug 18 12:50:00 joji-volumio sudo[5857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 12:50:00 joji-volumio sudo[5857]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 12:50:00 joji-volumio sudo[5857]: pam_unix(sudo:session): session closed for user root Aug 18 12:50:00 joji-volumio volumio[937]: verbose: New Socket.io Connection to 192.168.8.42 from 192.168.8.14 UA: Mozilla/5.0 (Linux; Android 14; CPH2465 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 18 12:50:00 joji-volumio volumio[937]: info: Received Get System Info Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 12:50:00 joji-volumio volumio[937]: info: Discovery: Getting this device information Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:50:00 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:50:00 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetVisibleSources Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:50:00 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 18 12:50:00 joji-volumio volumio[937]: info: Received Get System Info Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 12:50:00 joji-volumio volumio[937]: info: Discovery: Getting this device information Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:50:00 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:50:00 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:00 joji-volumio volumio[937]: info: Listing playlists Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetQueue Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreStateMachine::getQueue Aug 18 12:50:00 joji-volumio volumio[937]: info: CorePlayQueue::getQueue Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:00 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 18 12:50:02 joji-volumio sudo[5860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 12:50:02 joji-volumio sudo[5860]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 12:50:02 joji-volumio sudo[5860]: pam_unix(sudo:session): session closed for user root Aug 18 12:50:02 joji-volumio sudo[5863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 12:50:02 joji-volumio sudo[5863]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 12:50:02 joji-volumio sudo[5863]: pam_unix(sudo:session): session closed for user root Aug 18 12:50:02 joji-volumio volumio[937]: verbose: New Socket.io Connection to 192.168.8.42 from 192.168.8.14 UA: Mozilla/5.0 (Linux; Android 14; CPH2465 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 18 12:50:02 joji-volumio volumio[937]: info: Received Get System Info Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 12:50:02 joji-volumio volumio[937]: info: Discovery: Getting this device information Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:50:02 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:50:02 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetVisibleSources Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:50:02 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 18 12:50:02 joji-volumio volumio[937]: info: Received Get System Info Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 12:50:02 joji-volumio volumio[937]: info: Discovery: Getting this device information Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:50:02 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:50:02 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:02 joji-volumio volumio[937]: info: Listing playlists Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetQueue Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreStateMachine::getQueue Aug 18 12:50:02 joji-volumio volumio[937]: info: CorePlayQueue::getQueue Aug 18 12:50:02 joji-volumio volumio[937]: info: Received Get System Info Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 12:50:02 joji-volumio volumio[937]: info: Discovery: Getting this device information Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:50:02 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:50:02 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 18 12:50:02 joji-volumio volumio[937]: info: Received Get System Info Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 12:50:02 joji-volumio volumio[937]: info: Discovery: Getting this device information Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:50:02 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:02 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:07 joji-volumio volumio[937]: info: Retrieving Cloud Streaming UI Aug 18 12:50:07 joji-volumio volumio[937]: info: Getting Tidal Cloud Configuration Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:07 joji-volumio volumio[937]: info: Getting Qobuz Cloud Configuration Aug 18 12:50:07 joji-volumio volumio[937]: info: Asking plugin for UI Config Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:07 joji-volumio volumio[937]: info: Getting Spotify Cloud Configuration Aug 18 12:50:07 joji-volumio volumio[937]: info: Asking plugin for UI Config Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:07 joji-volumio volumio[937]: info: Saving Spotify Acccount Aug 18 12:50:07 joji-volumio volumio[937]: info: Got Tidal Cloud Configuration Aug 18 12:50:07 joji-volumio volumio[937]: info: Got it Aug 18 12:50:07 joji-volumio volumio[937]: info: Got it Aug 18 12:50:07 joji-volumio volumio[937]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:50:07 joji-volumio volumio[937]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetBrowseSources Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetBrowseSources Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetBrowseSources Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 18 12:50:07 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 18 12:50:10 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 18 12:50:11 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand update Aug 18 12:50:11 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:11 joji-volumio volumio[937]: info: Aug 18 12:50:11 joji-volumio volumio[937]: ---------------------------- MPD announces state update: update Aug 18 12:50:11 joji-volumio volumio[937]: info: sendMpdCommand update took 3 milliseconds Aug 18 12:50:11 joji-volumio volumio[937]: info: ControllerMpd::getState Aug 18 12:50:11 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:11 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:11 joji-volumio volumio[937]: info: Aug 18 12:50:11 joji-volumio volumio[937]: ---------------------------- MPD announces state update: update Aug 18 12:50:11 joji-volumio volumio[937]: info: ControllerMpd::getState Aug 18 12:50:11 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:11 joji-volumio volumio[937]: info: sendMpdCommand status took 4 milliseconds Aug 18 12:50:11 joji-volumio volumio[937]: info: sendMpdCommand status took 3 milliseconds Aug 18 12:50:11 joji-volumio volumio[937]: info: sendMpdCommand status took 2 milliseconds Aug 18 12:50:11 joji-volumio volumio[937]: info: sendMpdCommand status took 1 milliseconds Aug 18 12:50:11 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:11 joji-volumio volumio[937]: info: Command Router : Notfying DB Updatetrue Aug 18 12:50:11 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:11 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 12:50:11 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:11 joji-volumio volumio[937]: info: Command Router : Notfying DB Updatetrue Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreCommandRouter::Close All Modals sent Aug 18 12:50:11 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:11 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 12:50:11 joji-volumio volumio[937]: info: sendMpdCommand playlistinfo took 31 milliseconds Aug 18 12:50:11 joji-volumio volumio[937]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 18 12:50:11 joji-volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo Aug 18 12:50:11 joji-volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo Aug 18 12:50:11 joji-volumio volumio[937]: info: ControllerMpd::pushState Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreCommandRouter::servicePushState Aug 18 12:50:11 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:11 joji-volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264068,"duration":428,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track 02","artist":"BOB MARLY","album":"LEGEND","uri":"USB/JOJI/music/BOB MARLY - Track 02.mp3","trackType":"mp3"} Aug 18 12:50:11 joji-volumio volumio[937]: verbose: CURRENT POSITION 5 Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreStateMachine::syncState stateService play Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play Aug 18 12:50:11 joji-volumio volumio[937]: info: Received an update from plugin. extracting info from payload Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:11 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:11 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:11 joji-volumio volumio[937]: info: ControllerMpd::pushState Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreCommandRouter::servicePushState Aug 18 12:50:11 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:11 joji-volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264068,"duration":428,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track 02","artist":"BOB MARLY","album":"LEGEND","uri":"USB/JOJI/music/BOB MARLY - Track 02.mp3","trackType":"mp3"} Aug 18 12:50:11 joji-volumio volumio[937]: verbose: CURRENT POSITION 5 Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreStateMachine::syncState stateService play Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play Aug 18 12:50:11 joji-volumio volumio[937]: info: Received an update from plugin. extracting info from payload Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:11 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:11 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:11 joji-volumio volumio[937]: info: ------------------------------ 110ms Aug 18 12:50:11 joji-volumio volumio[937]: info: ------------------------------ 108ms Aug 18 12:50:11 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:11 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:11 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:11 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:11 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 18 12:50:12 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:12 joji-volumio volumio[937]: info: Aug 18 12:50:12 joji-volumio volumio[937]: ---------------------------- MPD announces state update: update Aug 18 12:50:12 joji-volumio volumio[937]: info: ControllerMpd::getState Aug 18 12:50:12 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:12 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:12 joji-volumio volumio[937]: info: Aug 18 12:50:12 joji-volumio volumio[937]: ---------------------------- MPD announces state update: update Aug 18 12:50:12 joji-volumio volumio[937]: info: ControllerMpd::getState Aug 18 12:50:12 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:12 joji-volumio volumio[937]: info: sendMpdCommand status took 4 milliseconds Aug 18 12:50:12 joji-volumio volumio[937]: info: sendMpdCommand status took 3 milliseconds Aug 18 12:50:12 joji-volumio volumio[937]: info: sendMpdCommand status took 2 milliseconds Aug 18 12:50:12 joji-volumio volumio[937]: info: sendMpdCommand status took 1 milliseconds Aug 18 12:50:12 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:12 joji-volumio volumio[937]: info: Command Router : Notfying DB Updatefalse Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreCommandRouter::Close All Modals sent Aug 18 12:50:12 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:12 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 12:50:12 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:12 joji-volumio volumio[937]: info: Command Router : Notfying DB Updatefalse Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreCommandRouter::Close All Modals sent Aug 18 12:50:12 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:12 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 12:50:12 joji-volumio volumio[937]: info: sendMpdCommand playlistinfo took 31 milliseconds Aug 18 12:50:12 joji-volumio volumio[937]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 18 12:50:12 joji-volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo Aug 18 12:50:12 joji-volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo Aug 18 12:50:12 joji-volumio volumio[937]: info: ControllerMpd::pushState Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreCommandRouter::servicePushState Aug 18 12:50:12 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:12 joji-volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264948,"duration":428,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track 02","artist":"BOB MARLY","album":"LEGEND","uri":"USB/JOJI/music/BOB MARLY - Track 02.mp3","trackType":"mp3"} Aug 18 12:50:12 joji-volumio volumio[937]: verbose: CURRENT POSITION 5 Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreStateMachine::syncState stateService play Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play Aug 18 12:50:12 joji-volumio volumio[937]: info: Received an update from plugin. extracting info from payload Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:12 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:12 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:12 joji-volumio volumio[937]: info: ControllerMpd::pushState Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreCommandRouter::servicePushState Aug 18 12:50:12 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:12 joji-volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264948,"duration":428,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track 02","artist":"BOB MARLY","album":"LEGEND","uri":"USB/JOJI/music/BOB MARLY - Track 02.mp3","trackType":"mp3"} Aug 18 12:50:12 joji-volumio volumio[937]: verbose: CURRENT POSITION 5 Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreStateMachine::syncState stateService play Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play Aug 18 12:50:12 joji-volumio volumio[937]: info: Received an update from plugin. extracting info from payload Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:12 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:12 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:12 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:12 joji-volumio volumio[937]: info: ------------------------------ 76ms Aug 18 12:50:12 joji-volumio volumio[937]: info: ------------------------------ 74ms Aug 18 12:50:12 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:12 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:12 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:12 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand update Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:13 joji-volumio volumio[937]: info: Aug 18 12:50:13 joji-volumio volumio[937]: ---------------------------- MPD announces state update: update Aug 18 12:50:13 joji-volumio volumio[937]: info: sendMpdCommand update took 2 milliseconds Aug 18 12:50:13 joji-volumio volumio[937]: info: ControllerMpd::getState Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:13 joji-volumio volumio[937]: info: Aug 18 12:50:13 joji-volumio volumio[937]: ---------------------------- MPD announces state update: update Aug 18 12:50:13 joji-volumio volumio[937]: info: ControllerMpd::getState Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:13 joji-volumio volumio[937]: info: sendMpdCommand status took 4 milliseconds Aug 18 12:50:13 joji-volumio volumio[937]: info: sendMpdCommand status took 3 milliseconds Aug 18 12:50:13 joji-volumio volumio[937]: info: sendMpdCommand status took 3 milliseconds Aug 18 12:50:13 joji-volumio volumio[937]: info: sendMpdCommand status took 2 milliseconds Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:13 joji-volumio volumio[937]: info: Command Router : Notfying DB Updatetrue Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::Close All Modals sent Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:13 joji-volumio volumio[937]: info: Command Router : Notfying DB Updatetrue Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::Close All Modals sent Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:13 joji-volumio volumio[937]: info: Aug 18 12:50:13 joji-volumio volumio[937]: ---------------------------- MPD announces state update: update Aug 18 12:50:13 joji-volumio volumio[937]: info: ControllerMpd::getState Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:13 joji-volumio volumio[937]: info: sendMpdCommand playlistinfo took 31 milliseconds Aug 18 12:50:13 joji-volumio volumio[937]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo Aug 18 12:50:13 joji-volumio volumio[937]: info: ControllerMpd::pushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::servicePushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:13 joji-volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":266320,"duration":428,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track 02","artist":"BOB MARLY","album":"LEGEND","uri":"USB/JOJI/music/BOB MARLY - Track 02.mp3","trackType":"mp3"} Aug 18 12:50:13 joji-volumio volumio[937]: verbose: CURRENT POSITION 5 Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::syncState stateService play Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play Aug 18 12:50:13 joji-volumio volumio[937]: info: Received an update from plugin. extracting info from payload Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:13 joji-volumio volumio[937]: info: ControllerMpd::pushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::servicePushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:13 joji-volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":266320,"duration":428,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track 02","artist":"BOB MARLY","album":"LEGEND","uri":"USB/JOJI/music/BOB MARLY - Track 02.mp3","trackType":"mp3"} Aug 18 12:50:13 joji-volumio volumio[937]: verbose: CURRENT POSITION 5 Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::syncState stateService play Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play Aug 18 12:50:13 joji-volumio volumio[937]: info: Received an update from plugin. extracting info from payload Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:13 joji-volumio volumio[937]: info: ------------------------------ 83ms Aug 18 12:50:13 joji-volumio volumio[937]: info: ------------------------------ 82ms Aug 18 12:50:13 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:13 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:13 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:13 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:13 joji-volumio volumio[937]: info: Aug 18 12:50:13 joji-volumio volumio[937]: ---------------------------- MPD announces state update: update Aug 18 12:50:13 joji-volumio volumio[937]: info: ControllerMpd::getState Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand status Aug 18 12:50:13 joji-volumio volumio[937]: info: sendMpdCommand status took 31 milliseconds Aug 18 12:50:13 joji-volumio volumio[937]: info: sendMpdCommand status took 30 milliseconds Aug 18 12:50:13 joji-volumio volumio[937]: info: sendMpdCommand status took 2 milliseconds Aug 18 12:50:13 joji-volumio volumio[937]: info: sendMpdCommand status took 1 milliseconds Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:13 joji-volumio volumio[937]: info: Command Router : Notfying DB Updatefalse Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::Close All Modals sent Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:13 joji-volumio volumio[937]: info: Command Router : Notfying DB Updatefalse Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::Close All Modals sent Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::parseState Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 12:50:13 joji-volumio volumio[937]: info: sendMpdCommand playlistinfo took 28 milliseconds Aug 18 12:50:13 joji-volumio volumio[937]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo Aug 18 12:50:13 joji-volumio volumio[937]: verbose: ControllerMpd::parseTrackInfo Aug 18 12:50:13 joji-volumio volumio[937]: info: ControllerMpd::pushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::servicePushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:13 joji-volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":266320,"duration":428,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track 02","artist":"BOB MARLY","album":"LEGEND","uri":"USB/JOJI/music/BOB MARLY - Track 02.mp3","trackType":"mp3"} Aug 18 12:50:13 joji-volumio volumio[937]: verbose: CURRENT POSITION 5 Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::syncState stateService play Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play Aug 18 12:50:13 joji-volumio volumio[937]: info: Received an update from plugin. extracting info from payload Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:13 joji-volumio volumio[937]: info: ControllerMpd::pushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::servicePushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:13 joji-volumio volumio[937]: verbose: STATE SERVICE {"status":"play","position":0,"seek":266445,"duration":428,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track 02","artist":"BOB MARLY","album":"LEGEND","uri":"USB/JOJI/music/BOB MARLY - Track 02.mp3","trackType":"mp3"} Aug 18 12:50:13 joji-volumio volumio[937]: verbose: CURRENT POSITION 5 Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::syncState stateService play Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::syncState currentStatus play Aug 18 12:50:13 joji-volumio volumio[937]: info: Received an update from plugin. extracting info from payload Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreStateMachine::pushState Aug 18 12:50:13 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:13 joji-volumio volumio[937]: info: CoreCommandRouter::volumioPushState Aug 18 12:50:13 joji-volumio volumio[937]: info: ------------------------------ 101ms Aug 18 12:50:13 joji-volumio volumio[937]: info: ------------------------------ 73ms Aug 18 12:50:13 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:13 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:13 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:13 joji-volumio volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 12:50:15 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 18 12:50:19 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 18 12:50:19 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 18 12:50:19 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 18 12:50:22 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 12:50:22 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 12:50:22 joji-volumio volumio[937]: info: Discovery: Getting this device information Aug 18 12:50:22 joji-volumio volumio[937]: info: CoreCommandRouter::volumioGetState Aug 18 12:50:22 joji-volumio volumio[937]: info: CorePlayQueue::getTrack 5 Aug 18 12:50:22 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 12:50:23 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 18 12:50:24 joji-volumio volumio[937]: info: Disabling MyMusic plugin upnp Aug 18 12:50:24 joji-volumio sudo[5888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 18 12:50:24 joji-volumio sudo[5888]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 12:50:24 joji-volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Aug 18 12:50:24 joji-volumio volumio[937]: error: Upnp client error: Error: This socket has been ended by the other party Aug 18 12:50:27 joji-volumio volumio[937]: info: Enabling MyMusic plugin upnp Aug 18 12:50:27 joji-volumio volumio[937]: info: Enabling plugin upnp Aug 18 12:50:27 joji-volumio volumio[937]: info: Loading plugin "upnp"... Aug 18 12:50:27 joji-volumio volumio[937]: info: [1723971027278] Starting Upmpd Daemon Aug 18 12:50:27 joji-volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 12:50:27 joji-volumio volumio[937]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 12:50:27 joji-volumio volumio[937]: Error: listen EADDRINUSE: address already in use :::6599 Aug 18 12:50:27 joji-volumio volumio[937]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Aug 18 12:50:27 joji-volumio volumio[937]: at listenInCluster (net.js:1366:12) Aug 18 12:50:27 joji-volumio volumio[937]: at Server.listen (net.js:1452:7) Aug 18 12:50:27 joji-volumio volumio[937]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Aug 18 12:50:27 joji-volumio volumio[937]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Aug 18 12:50:27 joji-volumio volumio[937]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 18 12:50:27 joji-volumio volumio[937]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 18 12:50:27 joji-volumio volumio[937]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Aug 18 12:50:27 joji-volumio volumio[937]: code: 'EADDRINUSE', Aug 18 12:50:27 joji-volumio volumio[937]: errno: -98, Aug 18 12:50:27 joji-volumio volumio[937]: syscall: 'listen', Aug 18 12:50:27 joji-volumio volumio[937]: address: '::', Aug 18 12:50:27 joji-volumio volumio[937]: port: 6599 Aug 18 12:50:27 joji-volumio volumio[937]: } Aug 18 12:50:27 joji-volumio volumio[937]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 12:50:27 joji-volumio sudo[5901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 12:49 Aug 18 12:50:27 joji-volumio sudo[5901]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"