-- Logs begin at Thu 2019-02-14 13:12:00 +03, end at Thu 2024-10-17 21:23:02 +03. -- Oct 17 21:22:00 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:00 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:00 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:00 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:01 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:01 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:01 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:01 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:02 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:02 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:02 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:02 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:03 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:03 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:03 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:03 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:04 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:04 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:04 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:04 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:05 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:05 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:05 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:05 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:06 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:06 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:06 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:06 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:07 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:07 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:07 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:07 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:08 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:08 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:08 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:08 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:09 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:09 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:09 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:09 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:10 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:10 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:10 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:10 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:11 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:11 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:11 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:11 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:12 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:12 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:12 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:12 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:13 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:13 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:13 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:13 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:13 volumio-filaudio volumio[936]: info: Enabling plugin peppyspectrum Oct 17 21:22:13 volumio-filaudio volumio[936]: info: Loading plugin "peppyspectrum"... Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Preparing to generate the ALSA configuration file Oct 17 21:22:14 volumio-filaudio volumio[936]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 17 21:22:14 volumio-filaudio volumio[936]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Reading ALSA contributions from plugins. Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Asound.conf file written Oct 17 21:22:14 volumio-filaudio sudo[2863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 17 21:22:14 volumio-filaudio sudo[2863]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:14 volumio-filaudio sudo[2863]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Output device has changed, restarting MPD Oct 17 21:22:14 volumio-filaudio sudo[2868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 21:22:14 volumio-filaudio sudo[2868]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:14 volumio-filaudio sudo[2868]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:14 volumio-filaudio volumio[936]: info: PLUGIN START: peppyspectrum Oct 17 21:22:14 volumio-filaudio sudo[2871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 21:22:14 volumio-filaudio sudo[2871]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Preparing to generate the ALSA configuration file Oct 17 21:22:14 volumio-filaudio systemd[1]: musicservicesshield.service: Succeeded. Oct 17 21:22:14 volumio-filaudio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Oct 17 21:22:14 volumio-filaudio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Oct 17 21:22:14 volumio-filaudio systemd[1]: Stopping Music Player Daemon... Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Done. Oct 17 21:22:14 volumio-filaudio systemd[1]: mpd.service: Succeeded. Oct 17 21:22:14 volumio-filaudio systemd[1]: Stopped Music Player Daemon. Oct 17 21:22:14 volumio-filaudio volumio[936]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 17 21:22:14 volumio-filaudio volumio[936]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Reading ALSA contributions from plugins. Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreStateMachine::getQueue Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CorePlayQueue::getQueue Oct 17 21:22:14 volumio-filaudio volumio[936]: info: MPD Permissions set Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:14 volumio-filaudio volumio[936]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:14 volumio-filaudio systemd[1]: Starting Music Player Daemon... Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Asound.conf file unchanged, so no further update is needed Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Output device has changed, restarting MPD Oct 17 21:22:14 volumio-filaudio sudo[2873]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 21:22:14 volumio-filaudio sudo[2873]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:14 volumio-filaudio sudo[2873]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:14 volumio-filaudio sudo[2882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 21:22:14 volumio-filaudio sudo[2882]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:14 volumio-filaudio sudo[2882]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:14 volumio-filaudio sudo[2885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 21:22:14 volumio-filaudio sudo[2885]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:14 volumio-filaudio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 17 21:22:14 volumio-filaudio systemd[1]: mpd.service: Succeeded. Oct 17 21:22:14 volumio-filaudio systemd[1]: Stopped Music Player Daemon. Oct 17 21:22:14 volumio-filaudio volumio[936]: info: MPD Permissions set Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:14 volumio-filaudio systemd[1]: Starting Music Player Daemon... Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Stopping peppyspectrum service Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreStateMachine::stop Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreStateMachine::serviceStop Oct 17 21:22:14 volumio-filaudio volumio[936]: info: CoreCommandRouter::serviceStop Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Spotify Stop Oct 17 21:22:14 volumio-filaudio volumio[936]: SPOTIFY: SPOTIFY STOP Oct 17 21:22:14 volumio-filaudio volumio[936]: SPOTIFY: {"status":"play","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","codec":"ogg","seek":132000,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Sending Spotify command to local API: /player/pause Oct 17 21:22:14 volumio-filaudio sudo[2888]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 21:22:14 volumio-filaudio sudo[2888]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:14 volumio-filaudio sudo[2888]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Disabling plugin peppyspectrum Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Preparing to generate the ALSA configuration file Oct 17 21:22:14 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:14+03:00" level=debug msg="pause track at 0ms" Oct 17 21:22:14 volumio-filaudio volumio[936]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Reading ALSA contributions from plugins. Oct 17 21:22:14 volumio-filaudio sudo[2898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 17 21:22:14 volumio-filaudio sudo[2898]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:14 volumio-filaudio volumio[936]: info: Asound.conf file written Oct 17 21:22:15 volumio-filaudio sudo[2898]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:15 volumio-filaudio sudo[2901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 17 21:22:15 volumio-filaudio sudo[2901]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:15 volumio-filaudio sudo[2901]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:15 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:15+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 21:22:15 volumio-filaudio volumio[936]: info: Output device has changed, restarting MPD Oct 17 21:22:15 volumio-filaudio volumio[936]: info: Done. Oct 17 21:22:15 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:15+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 21:22:15 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:15+03:00" level=trace msg="emitting websocket event: paused" Oct 17 21:22:15 volumio-filaudio sudo[2906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 21:22:15 volumio-filaudio sudo[2906]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:15 volumio-filaudio volumio[936]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2NYP7rhvuEWjBGDwoNHZcW","play_origin":"collection-songs"}} Oct 17 21:22:15 volumio-filaudio volumio[936]: SPOTIFY: PUSH STATE SPOTIFY Oct 17 21:22:15 volumio-filaudio volumio[936]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":236000,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::servicePushState Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreStateMachine::pushState Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioPushState Oct 17 21:22:15 volumio-filaudio sudo[2906]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:15 volumio-filaudio sudo[2908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 21:22:15 volumio-filaudio sudo[2908]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:15 volumio-filaudio volumio[936]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 17 21:22:15 volumio-filaudio volumio[936]: info: MPD Permissions set Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 17 21:22:15 volumio-filaudio systemd[1]: mpd.service: Succeeded. Oct 17 21:22:15 volumio-filaudio systemd[1]: Stopped Music Player Daemon. Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio systemd[1]: Starting Music Player Daemon... Oct 17 21:22:15 volumio-filaudio sudo[2912]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 21:22:15 volumio-filaudio sudo[2912]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:15 volumio-filaudio sudo[2912]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:15 volumio-filaudio volumio[936]: info: Enabling plugin peppyspectrum Oct 17 21:22:15 volumio-filaudio volumio[936]: info: Loading plugin "peppyspectrum"... Oct 17 21:22:15 volumio-filaudio volumio[936]: info: Preparing to generate the ALSA configuration file Oct 17 21:22:15 volumio-filaudio volumio[936]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 17 21:22:15 volumio-filaudio volumio[936]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 17 21:22:15 volumio-filaudio volumio[936]: info: Reading ALSA contributions from plugins. Oct 17 21:22:15 volumio-filaudio volumio[936]: info: Asound.conf file written Oct 17 21:22:15 volumio-filaudio sudo[2919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 17 21:22:15 volumio-filaudio sudo[2919]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:15 volumio-filaudio sudo[2919]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:15 volumio-filaudio volumio[936]: info: Output device has changed, restarting MPD Oct 17 21:22:15 volumio-filaudio volumio[936]: info: PLUGIN START: peppyspectrum Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 17 21:22:15 volumio-filaudio sudo[2924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 21:22:15 volumio-filaudio sudo[2924]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:15 volumio-filaudio volumio[936]: info: Preparing to generate the ALSA configuration file Oct 17 21:22:15 volumio-filaudio sudo[2924]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:15 volumio-filaudio volumio[936]: info: Done. Oct 17 21:22:15 volumio-filaudio volumio[936]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 17 21:22:15 volumio-filaudio volumio[936]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 17 21:22:15 volumio-filaudio volumio[936]: info: Reading ALSA contributions from plugins. Oct 17 21:22:15 volumio-filaudio volumio[936]: info: MPD Permissions set Oct 17 21:22:15 volumio-filaudio volumio[936]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:15 volumio-filaudio sudo[2927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 21:22:15 volumio-filaudio sudo[2927]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:16 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 21:22:16 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:16 volumio-filaudio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 17 21:22:16 volumio-filaudio systemd[1]: mpd.service: Succeeded. Oct 17 21:22:16 volumio-filaudio systemd[1]: Stopped Music Player Daemon. Oct 17 21:22:16 volumio-filaudio volumio[936]: info: Asound.conf file unchanged, so no further update is needed Oct 17 21:22:16 volumio-filaudio volumio[936]: info: Output device has changed, restarting MPD Oct 17 21:22:16 volumio-filaudio sudo[2935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 21:22:16 volumio-filaudio sudo[2935]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:16 volumio-filaudio systemd[1]: Starting Music Player Daemon... Oct 17 21:22:16 volumio-filaudio sudo[2935]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:16 volumio-filaudio sudo[2941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 21:22:16 volumio-filaudio sudo[2941]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:16 volumio-filaudio volumio[936]: info: MPD Permissions set Oct 17 21:22:16 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:16 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:16 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:16 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:16 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:16 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:16 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:16 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:16 volumio-filaudio sudo[2933]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 21:22:16 volumio-filaudio sudo[2933]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:16 volumio-filaudio sudo[2933]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:16 volumio-filaudio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 17 21:22:16 volumio-filaudio systemd[1]: mpd.service: Succeeded. Oct 17 21:22:16 volumio-filaudio systemd[1]: Stopped Music Player Daemon. Oct 17 21:22:16 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 21:22:16 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:16 volumio-filaudio systemd[1]: Starting Music Player Daemon... Oct 17 21:22:16 volumio-filaudio sudo[2946]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 21:22:16 volumio-filaudio sudo[2946]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:16 volumio-filaudio sudo[2946]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:16 volumio-filaudio mpd[2951]: Oct 17 21:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 21:22:16 volumio-filaudio systemd[1]: Started Music Player Daemon. Oct 17 21:22:16 volumio-filaudio sudo[2871]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:16 volumio-filaudio sudo[2908]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:16 volumio-filaudio sudo[2885]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:16 volumio-filaudio sudo[2941]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:16 volumio-filaudio sudo[2927]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:17 volumio-filaudio volumio[936]: error: MPD error: The expression evaluated to a falsy value: Oct 17 21:22:17 volumio-filaudio volumio[936]: assert.ok(self.idling) Oct 17 21:22:17 volumio-filaudio volumio[936]: error: The expression evaluated to a falsy value: Oct 17 21:22:17 volumio-filaudio volumio[936]: assert.ok(self.idling) Oct 17 21:22:17 volumio-filaudio volumio[936]: error: MPD error: The expression evaluated to a falsy value: Oct 17 21:22:17 volumio-filaudio volumio[936]: assert.ok(self.idling) Oct 17 21:22:17 volumio-filaudio volumio[936]: error: The expression evaluated to a falsy value: Oct 17 21:22:17 volumio-filaudio volumio[936]: assert.ok(self.idling) Oct 17 21:22:17 volumio-filaudio volumio[936]: error: MPD error: The expression evaluated to a falsy value: Oct 17 21:22:17 volumio-filaudio volumio[936]: assert.ok(self.idling) Oct 17 21:22:17 volumio-filaudio volumio[936]: error: The expression evaluated to a falsy value: Oct 17 21:22:17 volumio-filaudio volumio[936]: assert.ok(self.idling) Oct 17 21:22:17 volumio-filaudio volumio[936]: error: MPD error: The expression evaluated to a falsy value: Oct 17 21:22:17 volumio-filaudio volumio[936]: assert.ok(self.idling) Oct 17 21:22:17 volumio-filaudio volumio[936]: error: The expression evaluated to a falsy value: Oct 17 21:22:17 volumio-filaudio volumio[936]: assert.ok(self.idling) Oct 17 21:22:17 volumio-filaudio volumio[936]: error: updateQueue error: null Oct 17 21:22:17 volumio-filaudio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Oct 17 21:22:17 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 21:22:17 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 21:22:17 volumio-filaudio volumio[936]: info: Discovery: Getting this device information Oct 17 21:22:17 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:17 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 21:22:17 volumio-filaudio volumio[2954]: cset: --> shielding system active with Oct 17 21:22:17 volumio-filaudio volumio[2954]: cset: "system" cpuset of CPUSPEC(1-3) with 138 tasks running Oct 17 21:22:17 volumio-filaudio volumio[2954]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Oct 17 21:22:17 volumio-filaudio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Oct 17 21:22:17 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:17 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 21:22:17 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 21:22:17 volumio-filaudio volumio[936]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 17 21:22:17 volumio-filaudio volumio[936]: warn: [squeezelite_mc] No supported ALSA formats found for card 2 Oct 17 21:22:17 volumio-filaudio volumio[936]: warn: [squeezelite_mc] No ALSA formats returned for card 2 Oct 17 21:22:21 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesRandomizer Oct 17 21:22:21 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 21:22:21 volumio-filaudio volumio[936]: Cannot find translation for source Calm Radio Oct 17 21:22:21 volumio-filaudio volumio[936]: Cannot find translation for source YouTube2 Oct 17 21:22:21 volumio-filaudio volumio[936]: Cannot find translation for source YouTube Music Oct 17 21:22:21 volumio-filaudio volumio[936]: Cannot find translation for source 80s80s Radio Oct 17 21:22:21 volumio-filaudio volumio[936]: Cannot find translation for source Personal Radio Oct 17 21:22:21 volumio-filaudio volumio[936]: Cannot find translation for source Podcast Oct 17 21:22:21 volumio-filaudio volumio[936]: Cannot find translation for source SWARM Station Oct 17 21:22:21 volumio-filaudio volumio[936]: Cannot find translation for source Volusonic Oct 17 21:22:21 volumio-filaudio volumio[936]: Cannot find translation for source LastFM Oct 17 21:22:21 volumio-filaudio volumio[936]: Cannot find translation for source SoundCloud Oct 17 21:22:21 volumio-filaudio volumio[936]: info: Disabling plugin randomizer Oct 17 21:22:21 volumio-filaudio volumio[936]: info: Done. Oct 17 21:22:34 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:34+03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Oct 17 21:22:34 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:34+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 340" Oct 17 21:22:34 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:34+03:00" level=debug msg="put connect state inactive" Oct 17 21:22:34 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:34+03:00" level=trace msg="emitting websocket event: inactive" Oct 17 21:22:34 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:34+03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Oct 17 21:22:34 volumio-filaudio volumio[936]: SPOTIFY: received: {"type":"inactive","data":null} Oct 17 21:22:34 volumio-filaudio volumio[936]: error: Failed to decode event: inactive Oct 17 21:22:34 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:34+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1756" Oct 17 21:22:34 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:34+03:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.10.6:34760->34.158.1.133:4070: use of closed network connection" Oct 17 21:22:40 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:40+03:00" level=debug msg="obtained new client token: AAA7r4QBaVMSa7ctrH1iiVK6FQdklUnRLDocsmzxErUEvDgbtOfgKRE1HPSgarC0h9BekD1LQHUf7GQ/sixRiVrlW9QlYNxsXFlmtLgNaQDkw75Gg5STNs2jAkjomckfr1WrluHZ1q+j5TZNo8mcy/T1GrS5ZLwDP7E4/fiKaubQBTBbWyFcEXwUk3Uo8MDq1LO6ERmeuhpnQUeJDR/3IW3nnpUea4K+XHIuCaFmZCRVRU8cH4VI4bkKKHpv" Oct 17 21:22:40 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:40+03:00" level=info msg="connected to ap-gew4.spotify.com:4070" Oct 17 21:22:40 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:40+03:00" level=debug msg="completed keyexchange" Oct 17 21:22:40 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:40+03:00" level=debug msg="completed challenge" Oct 17 21:22:40 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:40+03:00" level=debug msg="authenticated as 31vinttbkfj6bx73ynq7csgaqtqy" Oct 17 21:22:40 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:40+03:00" level=debug msg="authenticated as 31vinttbkfj6bx73ynq7csgaqtqy" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="dealer connection opened" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=info msg="accepted zeroconf user 31vinttbkfj6bx73ynq7csgaqtqy from 2201116TG" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="autoplay enabled: false" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="received connection id: MjM3ZTVmYzYtMmY4MS00Mzk0LWFiNGItMjNmNzVhNjk2MTJhK2RlYWxlcit0Y3A6Ly8wYWNhNThlMS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArREY4ODY5MDNBODRDNTA1NkNFQUFDRjdDRjc5MDgzQjg2QTJCMjU3QkUzNEMzMDgxMUQ1MDU2OTA5RjA4RkIxNA==" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="put connect state because NEW_DEVICE" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="handling transfer player command from c3b7e4d2eacb5883496ea59c012a55920801114d" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="resolved context of track" uri="spotify:user:31vinttbkfj6bx73ynq7csgaqtqy:collection" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=trace msg="fetched new page 0 with 112 items (list: 112)" uri="spotify:user:31vinttbkfj6bx73ynq7csgaqtqy:collection" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="loading track (paused: false, position: 23889ms)" uri="spotify:track:2NYP7rhvuEWjBGDwoNHZcW" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=trace msg="emitting websocket event: will_play" Oct 17 21:22:41 volumio-filaudio volumio[936]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2NYP7rhvuEWjBGDwoNHZcW","play_origin":"collection-songs"}} Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="selected format OGG_VORBIS_320 (f472ba41e9515a60d1487b9bba6f2cf2557755b4)" uri="spotify:track:2NYP7rhvuEWjBGDwoNHZcW" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="requested aes key for file f472ba41e9515a60d1487b9bba6f2cf2557755b4, gid: 2NYP7rhvuEWjBGDwoNHZcW" Oct 17 21:22:41 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:41+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1725" Oct 17 21:22:42 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:42+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1321" Oct 17 21:22:42 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:42+03:00" level=debug msg="fetched first chunk of 22, total size is 11401452 bytes" uri="spotify:track:2NYP7rhvuEWjBGDwoNHZcW" Oct 17 21:22:42 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:42+03:00" level=debug msg="fetched chunk 1/21, size: 524288" uri="spotify:track:2NYP7rhvuEWjBGDwoNHZcW" Oct 17 21:22:42 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:42+03:00" level=trace msg="seek to 23889ms (diff: 93ms, samples: 1053504, bytes: 743806)" uri="spotify:track:2NYP7rhvuEWjBGDwoNHZcW" Oct 17 21:22:42 volumio-filaudio go-librespot[1330]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Oct 17 21:22:42 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:42+03:00" level=warning msg="failed handling dealer request" error="failed loading current track (transfer): failed setting stream for spotify:track:2NYP7rhvuEWjBGDwoNHZcW: ALSA error at snd_pcm_open: No such device" Oct 17 21:22:42 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:42+03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Oct 17 21:22:42 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:42+03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Oct 17 21:22:42 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:42+03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Oct 17 21:22:43 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:43+03:00" level=debug msg="fetched chunk 4/21, size: 524288" uri="spotify:track:2NYP7rhvuEWjBGDwoNHZcW" Oct 17 21:22:43 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:43+03:00" level=debug msg="fetched chunk 3/21, size: 524288" uri="spotify:track:2NYP7rhvuEWjBGDwoNHZcW" Oct 17 21:22:43 volumio-filaudio go-librespot[1330]: time="2024-10-17T21:22:43+03:00" level=debug msg="fetched chunk 2/21, size: 524288" uri="spotify:track:2NYP7rhvuEWjBGDwoNHZcW" Oct 17 21:22:47 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 21:22:47 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 21:22:47 volumio-filaudio volumio[936]: info: Discovery: Getting this device information Oct 17 21:22:47 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:47 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 21:22:52 volumio-filaudio sudo[3017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 21:22:52 volumio-filaudio sudo[3017]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:52 volumio-filaudio sudo[3017]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:52 volumio-filaudio sudo[3020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 21:22:52 volumio-filaudio sudo[3020]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:53 volumio-filaudio sudo[3020]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:53 volumio-filaudio volumio[936]: verbose: New Socket.io Connection to 192.168.10.6 from 192.168.10.12 UA: Mozilla/5.0 (Linux; Android 13; 2201116TG Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.81 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Oct 17 21:22:53 volumio-filaudio sudo[3023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 21:22:53 volumio-filaudio sudo[3023]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:53 volumio-filaudio sudo[3023]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:53 volumio-filaudio sudo[3026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 21:22:53 volumio-filaudio sudo[3026]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:22:53 volumio-filaudio sudo[3026]: pam_unix(sudo:session): session closed for user root Oct 17 21:22:53 volumio-filaudio volumio[936]: verbose: New Socket.io Connection to 192.168.10.6 from 192.168.10.12 UA: Mozilla/5.0 (Linux; Android 13; 2201116TG Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.81 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Oct 17 21:22:53 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 21:22:53 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 21:22:53 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 21:22:53 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:53 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 17 21:22:53 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 21:22:53 volumio-filaudio volumio[936]: info: Received Get System Info Oct 17 21:22:53 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 21:22:53 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 21:22:53 volumio-filaudio volumio[936]: info: Discovery: Getting this device information Oct 17 21:22:53 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:53 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 21:22:53 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:53 volumio-filaudio volumio[936]: info: Listing playlists Oct 17 21:22:53 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 17 21:22:53 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 17 21:22:53 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 17 21:22:55 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 17 21:22:55 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 21:22:55 volumio-filaudio volumio[936]: info: Received Get System Info Oct 17 21:22:55 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 21:22:55 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 21:22:55 volumio-filaudio volumio[936]: info: Discovery: Getting this device information Oct 17 21:22:55 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:55 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 21:22:56 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 21:22:56 volumio-filaudio volumio[936]: info: Received Get System Info Oct 17 21:22:56 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 21:22:56 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 21:22:56 volumio-filaudio volumio[936]: info: Discovery: Getting this device information Oct 17 21:22:56 volumio-filaudio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 17 21:22:56 volumio-filaudio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 21:23:01 volumio-filaudio volumio[936]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 17 21:23:01 volumio-filaudio volumio[936]: info: PLUGIN onReboot : networkfs Oct 17 21:23:01 volumio-filaudio volumio[936]: info: PLUGIN onReboot : audiophonicsonoff Oct 17 21:23:01 volumio-filaudio volumio[936]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 21:23:01 volumio-filaudio volumio[936]: TypeError: Cannot read property 'writeSync' of undefined Oct 17 21:23:01 volumio-filaudio volumio[936]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Oct 17 21:23:01 volumio-filaudio volumio[936]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Oct 17 21:23:01 volumio-filaudio volumio[936]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Oct 17 21:23:01 volumio-filaudio volumio[936]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 17 21:23:01 volumio-filaudio volumio[936]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 17 21:23:01 volumio-filaudio volumio[936]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Oct 17 21:23:01 volumio-filaudio volumio[936]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Oct 17 21:23:01 volumio-filaudio volumio[936]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Oct 17 21:23:01 volumio-filaudio volumio[936]: at Socket.emit (events.js:315:20) Oct 17 21:23:01 volumio-filaudio volumio[936]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 17 21:23:01 volumio-filaudio volumio[936]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 17 21:23:01 volumio-filaudio volumio[936]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 21:23:02 volumio-filaudio sudo[3037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-17 21:22 Oct 17 21:23:02 volumio-filaudio sudo[3037]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"