-- Logs begin at Thu 2019-02-14 18:11:59 HKT, end at Sat 2024-10-05 09:08:20 HKT. -- Oct 05 09:07:00 volumio volumio[6912]: info: MRS: STOPPING volumioStreaming Oct 05 09:07:00 volumio sudo[7601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 05 09:07:00 volumio sudo[7601]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:00 volumio systemd[1]: Stopping VolumioStreamingService... Oct 05 09:07:00 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Oct 05 09:07:00 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:00 volumio sudo[7604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 05 09:07:00 volumio sudo[7604]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:00 volumio systemd[1]: volumioStreaming.service: Succeeded. Oct 05 09:07:00 volumio systemd[1]: Stopped VolumioStreamingService. Oct 05 09:07:00 volumio sudo[7604]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:00 volumio sudo[7601]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:00 volumio volumio[6912]: info: MRS: Removed streaming files Oct 05 09:07:00 volumio volumio[6912]: info: MRS: volumioStreaming STOPPED Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 09:07:04 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 09:07:04 volumio volumio[6912]: info: Getting Alsa Cards List without I2S DAC Oct 05 09:07:04 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:04 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:04 volumio volumio[6912]: Invalid card number. Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 05 09:07:04 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 05 09:07:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:07:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:07:14 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:07:14 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:07:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:07:31 volumio volumio[6912]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 05 09:07:31 volumio volumio[6912]: info: Preparing to save Alsa Options, stopping services first Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::volumioPause Oct 05 09:07:31 volumio volumio[6912]: info: CoreStateMachine::pause Oct 05 09:07:31 volumio volumio[6912]: info: CoreStateMachine::stPlaybackTimer Oct 05 09:07:31 volumio volumio[6912]: info: CoreStateMachine::servicePause Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::servicePause Oct 05 09:07:31 volumio volumio[6912]: info: Received pause Oct 05 09:07:31 volumio volumio[6912]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"allo-boss-dac-pcm512x-audio,slave","label":"Allo BOSS"}} Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:31 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:31 volumio volumio[6912]: warn: Unable to locate the device HDMI Out a reboot may be required. Oct 05 09:07:31 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:31 volumio volumio[6912]: info: Found match in i2s Card Database: setting mixer Digital for card Allo BOSS Oct 05 09:07:31 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 05 09:07:31 volumio volumio[6912]: info: Updating Volume Controller Parameters: Device: 2 Name: Allo BOSS Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 05 09:07:31 volumio volumio[6912]: info: Disabling external Volume Control Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 09:07:31 volumio volumio[6912]: info: Preparing to generate the ALSA configuration file Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::servicePushState Oct 05 09:07:31 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:31 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:31 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:31 volumio volumio[6912]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 05 09:07:31 volumio volumio[6912]: info: Reading ALSA contributions from plugins. Oct 05 09:07:31 volumio volumio[6912]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 05 09:07:31 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"pause","title":"杯酒當歌","artist":"許冠傑","album":"寶麗金88極品音色系列 - 許冠傑 2","albumart":"https://resources.tidal.com/images/5c272d26/9094/4354/99e7/8cf6d16baeba/1280x1280.jpg","uri":"","trackType":"tidal","codec":"flac","seek":0,"duration":168,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"tidalconnect"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:31 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:31 volumio volumio[6912]: info: VolumeController:: Volume=undefined Mute =false Oct 05 09:07:31 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:31 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:31 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:31 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 09:07:31 volumio volumio[6912]: info: Getting Alsa Cards List without I2S DAC Oct 05 09:07:31 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:31 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:31 volumio volumio[6912]: Invalid card number. Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 05 09:07:31 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"pause","title":"杯酒當歌","artist":"許冠傑","album":"寶麗金88極品音色系列 - 許冠傑 2","albumart":"https://resources.tidal.com/images/5c272d26/9094/4354/99e7/8cf6d16baeba/1280x1280.jpg","uri":"","trackType":"tidal","codec":"flac","seek":0,"duration":168,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"tidalconnect"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:31 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:31 volumio volumio[6912]: info: Asound.conf file unchanged, so no further update is needed Oct 05 09:07:31 volumio volumio[6912]: info: Output device has changed, restarting MPD Oct 05 09:07:31 volumio sudo[7677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 09:07:31 volumio sudo[7677]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:31 volumio sudo[7677]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:31 volumio volumio[6912]: info: Output device has changed, restarting Shairport Sync Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:31 volumio sudo[7680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 09:07:31 volumio sudo[7680]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:31 volumio systemd[1]: musicservicesshield.service: Succeeded. Oct 05 09:07:31 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Oct 05 09:07:31 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Oct 05 09:07:31 volumio systemd[1]: Stopping Music Player Daemon... Oct 05 09:07:31 volumio systemd[1]: mpd.service: Succeeded. Oct 05 09:07:31 volumio systemd[1]: Stopped Music Player Daemon. Oct 05 09:07:31 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:31 volumio volumio[6912]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 05 09:07:31 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:31 volumio volumio[6912]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 05 09:07:31 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:31 volumio volumio[6912]: info: Relinquishing Volumio State Oct 05 09:07:31 volumio volumio[6912]: verbose: UNSET VOLATILE: Service: tidalconnect Oct 05 09:07:31 volumio volumio[6912]: info: unSetVolatile called Oct 05 09:07:31 volumio volumio[6912]: info: Relinquishing Volumio State to another service Oct 05 09:07:31 volumio systemd[1]: Starting Music Player Daemon... Oct 05 09:07:31 volumio vtcs[7374]: [2024-10-05 09:07:31.939] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 05 09:07:31 volumio vtcs[7374]: [2024-10-05 09:07:31.940] [tisoc] [error] [SpkconServer.cpp:473] socket write error. fd=3 errorno=32 error=Broken pipe Oct 05 09:07:31 volumio vtcs[7374]: [2024-10-05 09:07:31.941] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Oct 05 09:07:31 volumio vtcs[7374]: [2024-10-05 09:07:31.941] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 05 09:07:31 volumio vtcs[7374]: [2024-10-05 09:07:31] [info] asio async_shutdown error: asio.misc:2 (End of file) Oct 05 09:07:31 volumio sudo[7690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 09:07:31 volumio sudo[7690]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:32 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 05 09:07:32 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 05 09:07:32 volumio systemd[1]: vtcs.service: Succeeded. Oct 05 09:07:32 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 05 09:07:32 volumio sudo[7685]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 09:07:32 volumio sudo[7685]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:32 volumio sudo[7685]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:32 volumio sudo[7690]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:32 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:32 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 09:07:32 volumio sudo[7694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 09:07:32 volumio sudo[7694]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:32 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:32 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:32 volumio volumio[6912]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 05 09:07:32 volumio volumio[6912]: info: MPD Permissions set Oct 05 09:07:32 volumio volumio[6912]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 05 09:07:32 volumio volumio[6912]: info: Preparing to save Alsa Options, stopping services first Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:07:32 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::volumioPause Oct 05 09:07:32 volumio volumio[6912]: info: CoreStateMachine::pause Oct 05 09:07:32 volumio volumio[6912]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"allo-boss-dac-pcm512x-audio,slave","label":"Allo BOSS"}} Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:32 volumio sudo[7694]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:32 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:32 volumio volumio[6912]: warn: Unable to locate the device HDMI Out a reboot may be required. Oct 05 09:07:32 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: Found match in i2s Card Database: setting mixer Digital for card Allo BOSS Oct 05 09:07:32 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 05 09:07:32 volumio volumio[6912]: info: Updating Volume Controller Parameters: Device: 2 Name: Allo BOSS Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 05 09:07:32 volumio volumio[6912]: info: Disabling external Volume Control Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 09:07:32 volumio volumio[6912]: info: Preparing to generate the ALSA configuration file Oct 05 09:07:32 volumio volumio[6912]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 05 09:07:32 volumio volumio[6912]: info: Reading ALSA contributions from plugins. Oct 05 09:07:32 volumio volumio[6912]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: VolumeController:: Volume=undefined Mute =false Oct 05 09:07:32 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:32 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:32 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:32 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:32 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 09:07:32 volumio volumio[6912]: info: Getting Alsa Cards List without I2S DAC Oct 05 09:07:32 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:32 volumio volumio[6912]: Invalid card number. Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 05 09:07:32 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:32 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:32 volumio volumio[6912]: info: Starting Shairport Sync Oct 05 09:07:32 volumio sudo[7720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 05 09:07:32 volumio sudo[7720]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:32 volumio volumio[6912]: info: Asound.conf file unchanged, so no further update is needed Oct 05 09:07:32 volumio volumio[6912]: info: Output device has changed, restarting MPD Oct 05 09:07:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 05 09:07:32 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 05 09:07:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 05 09:07:32 volumio sudo[7724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 09:07:32 volumio sudo[7724]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 05 09:07:32 volumio sudo[7720]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:32 volumio sudo[7724]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:32 volumio volumio[6912]: info: Output device has changed, restarting Shairport Sync Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:32 volumio sudo[7727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 09:07:32 volumio sudo[7727]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:32 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 05 09:07:32 volumio systemd[1]: mpd.service: Succeeded. Oct 05 09:07:32 volumio systemd[1]: Stopped Music Player Daemon. Oct 05 09:07:32 volumio systemd[1]: Starting Music Player Daemon... Oct 05 09:07:32 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:32 volumio volumio[6912]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 05 09:07:32 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:32 volumio volumio[6912]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio sudo[7734]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 09:07:32 volumio sudo[7734]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:32 volumio sudo[7734]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:32 volumio sudo[7741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 09:07:32 volumio sudo[7741]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:32 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:32 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 09:07:32 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 09:07:32 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 09:07:32 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:32 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 09:07:32 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:32 volumio volumio[6912]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 05 09:07:32 volumio volumio[6912]: info: MPD Permissions set Oct 05 09:07:32 volumio volumio[6912]: info: Shairport-Sync Started Oct 05 09:07:32 volumio sudo[7741]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:32 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:33 volumio sudo[7743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 09:07:33 volumio sudo[7743]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:33 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:33 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:33 volumio volumio[6912]: info: Starting Shairport Sync Oct 05 09:07:33 volumio sudo[7743]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:33 volumio sudo[7752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 05 09:07:33 volumio sudo[7752]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 05 09:07:33 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 05 09:07:33 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 05 09:07:33 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 09:07:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 05 09:07:33 volumio sudo[7752]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:33 volumio volumio[6912]: info: Shairport-Sync Started Oct 05 09:07:33 volumio mpd[7739]: Oct 05 09:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 09:07:33 volumio systemd[1]: Started Music Player Daemon. Oct 05 09:07:33 volumio sudo[7727]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:33 volumio sudo[7680]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:33 volumio volumio[6912]: error: MPD error: The expression evaluated to a falsy value: Oct 05 09:07:33 volumio volumio[6912]: assert.ok(self.idling) Oct 05 09:07:33 volumio volumio[6912]: error: The expression evaluated to a falsy value: Oct 05 09:07:33 volumio volumio[6912]: assert.ok(self.idling) Oct 05 09:07:33 volumio volumio[6912]: error: updateQueue error: null Oct 05 09:07:33 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Oct 05 09:07:33 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:34 volumio volumio[7759]: cset: --> shielding system active with Oct 05 09:07:34 volumio volumio[7759]: cset: "system" cpuset of CPUSPEC(1-3) with 146 tasks running Oct 05 09:07:34 volumio volumio[7759]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Oct 05 09:07:34 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Oct 05 09:07:35 volumio volumio[6912]: info: TidalConnect service stoped! Oct 05 09:07:35 volumio volumio[6912]: info: TidalConnect service stoped! Oct 05 09:07:35 volumio volumio[6912]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 05 09:07:35 volumio volumio[6912]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 05 09:07:35 volumio sudo[7783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 05 09:07:35 volumio sudo[7783]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:35 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 05 09:07:35 volumio sudo[7783]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:35 volumio volumio[6912]: info: Executing endpoint tc_getconfig Oct 05 09:07:35 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 05 09:07:35 volumio volumio[6912]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 05 09:07:35 volumio vtcs[7785]: STARTING TidalConnect services, version: 1.4.0.34 Oct 05 09:07:35 volumio volumio[6912]: info: CoreStateMachine::getcurrentVolume Oct 05 09:07:35 volumio volumio[6912]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 09:07:35 volumio volumio[6912]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 05 09:07:35 volumio vtcs[7785]: STARTED TidalConnect services. Oct 05 09:07:35 volumio volumio[6912]: info: Executing endpoint tc_connect Oct 05 09:07:35 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 05 09:07:35 volumio volumio[6912]: info: Connecting to TidalConnect Oct 05 09:07:35 volumio volumio[6912]: info: VolumeController:: Volume=undefined Mute =false Oct 05 09:07:35 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:35 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:35 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:35 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:35 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:35 volumio volumio[6912]: info: CoreStateMachine::updateTrackBlock Oct 05 09:07:35 volumio volumio[6912]: info: CorePlayQueue::getTrackBlock Oct 05 09:07:35 volumio volumio[6912]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 09:07:35 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:35 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:35 volumio volumio[6912]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 05 09:07:35 volumio volumio[6912]: info: CoreCommandRouter::servicePushState Oct 05 09:07:35 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:35 volumio volumio[6912]: verbose: STATE SERVICE {"status":"pause","service":"tidalconnect","title":"杯酒當歌","artist":"許冠傑","album":"寶麗金88極品音色系列 - 許冠傑 2","albumart":"https://resources.tidal.com/images/5c272d26/9094/4354/99e7/8cf6d16baeba/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":0,"duration":168,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":1411200,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 05 09:07:35 volumio volumio[6912]: verbose: CURRENT POSITION 0 Oct 05 09:07:35 volumio volumio[6912]: info: CoreStateMachine::syncState stateService pause Oct 05 09:07:35 volumio volumio[6912]: info: CoreStateMachine::syncState currentStatus pause Oct 05 09:07:35 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:35 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:35 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:35 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:35 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:35 volumio volumio[6912]: info: CoreStateMachine::stPlaybackTimer Oct 05 09:07:35 volumio volumio[6912]: info: CoreCommandRouter::servicePushState Oct 05 09:07:35 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:35 volumio volumio[6912]: verbose: STATE SERVICE {"status":"pause","service":"tidalconnect","title":"杯酒當歌","artist":"許冠傑","album":"寶麗金88極品音色系列 - 許冠傑 2","albumart":"https://resources.tidal.com/images/5c272d26/9094/4354/99e7/8cf6d16baeba/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":0,"duration":168,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":1411200,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 05 09:07:35 volumio volumio[6912]: verbose: CURRENT POSITION 0 Oct 05 09:07:35 volumio volumio[6912]: info: CoreStateMachine::syncState stateService pause Oct 05 09:07:35 volumio volumio[6912]: info: CoreStateMachine::syncState currentStatus pause Oct 05 09:07:35 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:35 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:35 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:35 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:35 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:35 volumio volumio[6912]: info: CoreStateMachine::stPlaybackTimer Oct 05 09:07:35 volumio volumio[6912]: info: VolumeController:: Volume=undefined Mute =false Oct 05 09:07:35 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:35 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:35 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:35 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:35 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:35 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:35 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:35 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:35 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:35 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:35 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:36 volumio volumio[6912]: info: TidalConnect service stoped! Oct 05 09:07:36 volumio volumio[6912]: info: TidalConnect service stoped! Oct 05 09:07:36 volumio volumio[6912]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 05 09:07:36 volumio volumio[6912]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 05 09:07:36 volumio sudo[7817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 05 09:07:36 volumio sudo[7817]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:36 volumio sudo[7817]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:36 volumio volumio[6912]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 05 09:07:36 volumio volumio[6912]: info: CoreStateMachine::getcurrentVolume Oct 05 09:07:36 volumio volumio[6912]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 09:07:36 volumio volumio[6912]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 05 09:07:36 volumio volumio[6912]: info: VolumeController:: Volume=undefined Mute =false Oct 05 09:07:36 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:36 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:36 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:36 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:36 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:36 volumio volumio[6912]: info: CoreStateMachine::updateTrackBlock Oct 05 09:07:36 volumio volumio[6912]: info: CorePlayQueue::getTrackBlock Oct 05 09:07:36 volumio volumio[6912]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 09:07:36 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:36 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:36 volumio volumio[6912]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 05 09:07:36 volumio volumio[6912]: info: VolumeController:: Volume=undefined Mute =false Oct 05 09:07:36 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:36 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:36 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:36 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:36 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:36 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:36 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:36 volumio volumio[6912]: error: ONKYO-CONTROL: An error occurred trying to comminicate with the receiver: ERROR (cmd_not_exist) Command power does not exist in zone main Oct 05 09:07:37 volumio volumio[6912]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 05 09:07:37 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 05 09:07:37 volumio volumio[6912]: info: Preparing to save Alsa Options, stopping services first Oct 05 09:07:37 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:07:37 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:37 volumio volumio[6912]: info: CoreCommandRouter::volumioPause Oct 05 09:07:37 volumio volumio[6912]: info: CoreStateMachine::pause Oct 05 09:07:37 volumio volumio[6912]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"allo-boss-dac-pcm512x-audio,slave","label":"Allo BOSS"}} Oct 05 09:07:37 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 09:07:37 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 05 09:07:37 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:37 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Oct 05 09:07:37 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:37 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:37 volumio volumio[6912]: warn: Unable to locate the device HDMI Out a reboot may be required. Oct 05 09:07:38 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: Found match in i2s Card Database: setting mixer Digital for card Allo BOSS Oct 05 09:07:38 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 05 09:07:38 volumio volumio[6912]: info: Updating Volume Controller Parameters: Device: 2 Name: Allo BOSS Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 05 09:07:38 volumio volumio[6912]: info: Disabling external Volume Control Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 09:07:38 volumio volumio[6912]: info: Preparing to generate the ALSA configuration file Oct 05 09:07:38 volumio volumio[6912]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 05 09:07:38 volumio volumio[6912]: info: Reading ALSA contributions from plugins. Oct 05 09:07:38 volumio volumio[6912]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 05 09:07:38 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 09:07:38 volumio volumio[6912]: info: Getting Alsa Cards List without I2S DAC Oct 05 09:07:38 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:38 volumio volumio[6912]: Invalid card number. Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 05 09:07:38 volumio volumio[6912]: info: TidalConnect service started! Oct 05 09:07:38 volumio volumio[6912]: info: VolumeController:: Volume=undefined Mute =false Oct 05 09:07:38 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:38 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:38 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:38 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:38 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:38 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:38 volumio volumio[6912]: info: Asound.conf file unchanged, so no further update is needed Oct 05 09:07:38 volumio volumio[6912]: info: Output device has changed, restarting MPD Oct 05 09:07:38 volumio sudo[7841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 09:07:38 volumio sudo[7841]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:38 volumio sudo[7841]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:38 volumio volumio[6912]: info: Output device has changed, restarting Shairport Sync Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:38 volumio sudo[7844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 09:07:38 volumio sudo[7844]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:38 volumio systemd[1]: musicservicesshield.service: Succeeded. Oct 05 09:07:38 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Oct 05 09:07:38 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Oct 05 09:07:38 volumio systemd[1]: Stopping Music Player Daemon... Oct 05 09:07:38 volumio systemd[1]: mpd.service: Succeeded. Oct 05 09:07:38 volumio systemd[1]: Stopped Music Player Daemon. Oct 05 09:07:38 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:38 volumio volumio[6912]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 05 09:07:38 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:38 volumio volumio[6912]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:38 volumio vtcs[7785]: [2024-10-05 09:07:38.502] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 05 09:07:38 volumio vtcs[7785]: [2024-10-05 09:07:38.502] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 05 09:07:38 volumio systemd[1]: Starting Music Player Daemon... Oct 05 09:07:38 volumio sudo[7853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 09:07:38 volumio sudo[7853]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:38 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 05 09:07:38 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:38 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 09:07:38 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 09:07:38 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:38 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 05 09:07:38 volumio systemd[1]: vtcs.service: Succeeded. Oct 05 09:07:38 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 05 09:07:38 volumio volumio[6912]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 05 09:07:38 volumio volumio[6912]: info: MPD Permissions set Oct 05 09:07:38 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:38 volumio sudo[7853]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:38 volumio sudo[7856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 09:07:38 volumio sudo[7856]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:38 volumio sudo[7849]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 09:07:38 volumio sudo[7849]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:38 volumio sudo[7849]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:38 volumio volumio[6912]: info: Starting Shairport Sync Oct 05 09:07:38 volumio sudo[7856]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:38 volumio sudo[7866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 05 09:07:38 volumio sudo[7866]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 05 09:07:38 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 05 09:07:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 05 09:07:38 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:38 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 09:07:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 05 09:07:38 volumio sudo[7866]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:38 volumio volumio[6912]: info: Shairport-Sync Started Oct 05 09:07:39 volumio volumio[6912]: info: TidalConnect service started! Oct 05 09:07:39 volumio mpd[7864]: Oct 05 09:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 09:07:39 volumio systemd[1]: Started Music Player Daemon. Oct 05 09:07:39 volumio sudo[7844]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:39 volumio volumio[6912]: error: updateQueue error: null Oct 05 09:07:39 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Oct 05 09:07:39 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:39 volumio volumio[7875]: cset: --> shielding system active with Oct 05 09:07:39 volumio volumio[7875]: cset: "system" cpuset of CPUSPEC(1-3) with 142 tasks running Oct 05 09:07:39 volumio volumio[7875]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Oct 05 09:07:39 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Oct 05 09:07:41 volumio volumio[6912]: info: TidalConnect service stoped! Oct 05 09:07:41 volumio volumio[6912]: info: TidalConnect service stoped! Oct 05 09:07:41 volumio volumio[6912]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 05 09:07:41 volumio volumio[6912]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 05 09:07:41 volumio sudo[7899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 05 09:07:41 volumio sudo[7899]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:41 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 05 09:07:41 volumio sudo[7899]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:41 volumio volumio[6912]: info: Executing endpoint tc_getconfig Oct 05 09:07:41 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 05 09:07:41 volumio vtcs[7901]: STARTING TidalConnect services, version: 1.4.0.34 Oct 05 09:07:42 volumio vtcs[7901]: STARTED TidalConnect services. Oct 05 09:07:42 volumio volumio[6912]: info: Executing endpoint tc_connect Oct 05 09:07:42 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 05 09:07:42 volumio volumio[6912]: info: Connecting to TidalConnect Oct 05 09:07:42 volumio volumio[6912]: info: CoreCommandRouter::servicePushState Oct 05 09:07:42 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:42 volumio volumio[6912]: verbose: STATE SERVICE {"status":"pause","service":"tidalconnect","title":"杯酒當歌","artist":"許冠傑","album":"寶麗金88極品音色系列 - 許冠傑 2","albumart":"https://resources.tidal.com/images/5c272d26/9094/4354/99e7/8cf6d16baeba/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":0,"duration":168,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":1411200,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 05 09:07:42 volumio volumio[6912]: verbose: CURRENT POSITION 0 Oct 05 09:07:42 volumio volumio[6912]: info: CoreStateMachine::syncState stateService pause Oct 05 09:07:42 volumio volumio[6912]: info: CoreStateMachine::syncState currentStatus pause Oct 05 09:07:42 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:42 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:42 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:42 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:42 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:42 volumio volumio[6912]: info: CoreStateMachine::stPlaybackTimer Oct 05 09:07:42 volumio volumio[6912]: info: CoreCommandRouter::servicePushState Oct 05 09:07:42 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:42 volumio volumio[6912]: verbose: STATE SERVICE {"status":"pause","service":"tidalconnect","title":"杯酒當歌","artist":"許冠傑","album":"寶麗金88極品音色系列 - 許冠傑 2","albumart":"https://resources.tidal.com/images/5c272d26/9094/4354/99e7/8cf6d16baeba/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":0,"duration":168,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":1411200,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 05 09:07:42 volumio volumio[6912]: verbose: CURRENT POSITION 0 Oct 05 09:07:42 volumio volumio[6912]: info: CoreStateMachine::syncState stateService pause Oct 05 09:07:42 volumio volumio[6912]: info: CoreStateMachine::syncState currentStatus pause Oct 05 09:07:42 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:42 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:42 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:42 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:42 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:42 volumio volumio[6912]: info: CoreStateMachine::stPlaybackTimer Oct 05 09:07:42 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:42 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:42 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:42 volumio volumio[6912]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 05 09:07:42 volumio volumio[6912]: info: CoreStateMachine::getcurrentVolume Oct 05 09:07:42 volumio volumio[6912]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 09:07:42 volumio volumio[6912]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 05 09:07:42 volumio volumio[6912]: info: VolumeController:: Volume=undefined Mute =false Oct 05 09:07:42 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:42 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:42 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:42 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:42 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:42 volumio volumio[6912]: info: CoreStateMachine::updateTrackBlock Oct 05 09:07:42 volumio volumio[6912]: info: CorePlayQueue::getTrackBlock Oct 05 09:07:42 volumio volumio[6912]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 09:07:42 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:42 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:42 volumio volumio[6912]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 05 09:07:42 volumio volumio[6912]: info: VolumeController:: Volume=undefined Mute =false Oct 05 09:07:42 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:42 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:42 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:42 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:42 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:42 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:42 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:44 volumio volumio[6912]: info: TidalConnect service started! Oct 05 09:07:44 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:07:44 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:07:44 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:07:44 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:07:44 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:44 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:07:50 volumio ntpd[2537]: Soliciting pool server 2001:ac8:a:23:0:1:0:26 Oct 05 09:07:50 volumio volumio[6912]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:07:50 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:50 volumio volumio[6912]: info: Restoring Previous Volume level: 76 false false Oct 05 09:07:50 volumio volumio[6912]: info: Volume configurations have been set Oct 05 09:07:50 volumio vtcs[7901]: [2024-10-05 09:07:50.475] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 05 09:07:50 volumio vtcs[7901]: [2024-10-05 09:07:50.478] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 05 09:07:50 volumio sudo[7926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 09:07:50 volumio sudo[7926]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:50 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 05 09:07:50 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 05 09:07:50 volumio systemd[1]: vtcs.service: Succeeded. Oct 05 09:07:50 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 05 09:07:50 volumio sudo[7926]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:50 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 05 09:07:50 volumio volumio[6912]: info: Updating Volume Controller Parameters: Device: 2 Name: Allo BOSS Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 05 09:07:50 volumio volumio[6912]: info: Disabling external Volume Control Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 09:07:50 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:50 volumio volumio[6912]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 05 09:07:50 volumio sudo[7933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 09:07:50 volumio sudo[7933]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:50 volumio volumio[6912]: info: VolumeController:: Volume=undefined Mute =false Oct 05 09:07:50 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:50 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:50 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:50 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:50 volumio systemd[1]: Starting Cleanup of Temporary Directories... Oct 05 09:07:50 volumio sudo[7933]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:50 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 09:07:50 volumio volumio[6912]: info: Getting Alsa Cards List without I2S DAC Oct 05 09:07:50 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Oct 05 09:07:50 volumio systemd[1]: Started Cleanup of Temporary Directories. Oct 05 09:07:50 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:50 volumio volumio[6912]: xcb_connection_has_error() returned true Oct 05 09:07:50 volumio volumio[6912]: Invalid card number. Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 05 09:07:50 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 05 09:07:50 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:50 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:50 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:52 volumio ntpd[2537]: Soliciting pool server 209.58.185.100 Oct 05 09:07:53 volumio volumio[6912]: info: TidalConnect service stoped! Oct 05 09:07:53 volumio volumio[6912]: info: TidalConnect service stoped! Oct 05 09:07:53 volumio volumio[6912]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 05 09:07:53 volumio volumio[6912]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 05 09:07:53 volumio sudo[7955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 05 09:07:53 volumio sudo[7955]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:53 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 05 09:07:53 volumio sudo[7955]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:54 volumio volumio[6912]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Oct 05 09:07:54 volumio sudo[7961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 09:07:54 volumio sudo[7961]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:54 volumio sudo[7961]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:54 volumio sudo[7965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 09:07:54 volumio sudo[7965]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:54 volumio volumio[6912]: info: MPD Permissions set Oct 05 09:07:54 volumio volumio[6912]: info: Executing endpoint tc_getconfig Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:54 volumio vtcs[7957]: STARTING TidalConnect services, version: 1.4.0.34 Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:54 volumio systemd[1]: musicservicesshield.service: Succeeded. Oct 05 09:07:54 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Oct 05 09:07:54 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Oct 05 09:07:54 volumio systemd[1]: Stopping Music Player Daemon... Oct 05 09:07:54 volumio systemd[1]: mpd.service: Succeeded. Oct 05 09:07:54 volumio systemd[1]: Stopped Music Player Daemon. Oct 05 09:07:54 volumio vtcs[7957]: STARTED TidalConnect services. Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:07:54 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:54 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 09:07:54 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 09:07:54 volumio volumio[6912]: info: Executing endpoint tc_connect Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 05 09:07:54 volumio volumio[6912]: info: Connecting to TidalConnect Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::servicePushState Oct 05 09:07:54 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:54 volumio volumio[6912]: verbose: STATE SERVICE {"status":"pause","service":"tidalconnect","title":"杯酒當歌","artist":"許冠傑","album":"寶麗金88極品音色系列 - 許冠傑 2","albumart":"https://resources.tidal.com/images/5c272d26/9094/4354/99e7/8cf6d16baeba/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":0,"duration":168,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":1411200,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 05 09:07:54 volumio volumio[6912]: verbose: CURRENT POSITION 0 Oct 05 09:07:54 volumio volumio[6912]: info: CoreStateMachine::syncState stateService pause Oct 05 09:07:54 volumio volumio[6912]: info: CoreStateMachine::syncState currentStatus pause Oct 05 09:07:54 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:54 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:54 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:54 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:54 volumio volumio[6912]: info: CoreStateMachine::stPlaybackTimer Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::servicePushState Oct 05 09:07:54 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:54 volumio volumio[6912]: verbose: STATE SERVICE {"status":"pause","service":"tidalconnect","title":"杯酒當歌","artist":"許冠傑","album":"寶麗金88極品音色系列 - 許冠傑 2","albumart":"https://resources.tidal.com/images/5c272d26/9094/4354/99e7/8cf6d16baeba/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":0,"duration":168,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":1411200,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 05 09:07:54 volumio volumio[6912]: verbose: CURRENT POSITION 0 Oct 05 09:07:54 volumio volumio[6912]: info: CoreStateMachine::syncState stateService pause Oct 05 09:07:54 volumio volumio[6912]: info: CoreStateMachine::syncState currentStatus pause Oct 05 09:07:54 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:54 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:54 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:54 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:54 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:54 volumio volumio[6912]: info: CoreStateMachine::stPlaybackTimer Oct 05 09:07:54 volumio systemd[1]: Starting Music Player Daemon... Oct 05 09:07:54 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:54 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:54 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:54 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:54 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:54 volumio sudo[7968]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 09:07:54 volumio sudo[7968]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:07:54 volumio sudo[7968]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:54 volumio mpd[7988]: Oct 05 09:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 09:07:54 volumio systemd[1]: Started Music Player Daemon. Oct 05 09:07:54 volumio sudo[7965]: pam_unix(sudo:session): session closed for user root Oct 05 09:07:54 volumio volumio[6912]: error: updateQueue error: null Oct 05 09:07:54 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Oct 05 09:07:54 volumio volumio[6912]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 09:07:54 volumio volumio[6912]: info: VolumeController::SetAlsaVolume76 Oct 05 09:07:55 volumio volumio[6912]: info: CoreStateMachine::pushState Oct 05 09:07:55 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:55 volumio volumio[6912]: info: CoreCommandRouter::volumioPushState Oct 05 09:07:55 volumio volumio[6912]: info: MRS: Pushing multiroomSync output update for this device Oct 05 09:07:55 volumio volumio[6912]: info: MRS: Pushing multiroomSync output Oct 05 09:07:55 volumio volumio[6912]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":76,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.68.54","model":"TX-RZ830"} Oct 05 09:07:55 volumio volumio[6912]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Oct 05 09:07:55 volumio volumio[6912]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 05 09:07:55 volumio volumio[7991]: cset: --> shielding system active with Oct 05 09:07:55 volumio volumio[7991]: cset: "system" cpuset of CPUSPEC(1-3) with 156 tasks running Oct 05 09:07:55 volumio volumio[7991]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Oct 05 09:07:55 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Oct 05 09:07:56 volumio volumio[6912]: info: TidalConnect service started! Oct 05 09:07:58 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:07:58 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:07:58 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:07:58 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:07:58 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:58 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:07:58 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:07:58 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:07:58 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:07:58 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:07:58 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:07:58 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:00 volumio sudo[8008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 09:08:00 volumio sudo[8008]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:00 volumio sudo[8008]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:00 volumio sudo[8011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 09:08:00 volumio sudo[8011]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:00 volumio sudo[8011]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:01 volumio volumio[6912]: verbose: New Socket.io Connection to 192.168.68.56 from 192.168.68.55 UA: Mozilla/5.0 (Linux; Android 13; 23013PC75G Build/TKQ1.220905.001; 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: 9 Oct 05 09:08:01 volumio sudo[8014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 09:08:01 volumio sudo[8014]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:01 volumio sudo[8014]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:01 volumio sudo[8017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 09:08:01 volumio sudo[8017]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:01 volumio sudo[8017]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:01 volumio volumio[6912]: verbose: New Socket.io Connection to 192.168.68.56 from 192.168.68.55 UA: Mozilla/5.0 (Linux; Android 13; 23013PC75G Build/TKQ1.220905.001; 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: 10 Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::volumioGetVisibleSources Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:02 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::volumioGetQueue Oct 05 09:08:02 volumio volumio[6912]: info: CoreStateMachine::getQueue Oct 05 09:08:02 volumio volumio[6912]: info: CorePlayQueue::getQueue Oct 05 09:08:02 volumio volumio[6912]: info: Listing playlists Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 05 09:08:02 volumio volumio[6912]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 05 09:08:02 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:02 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:02 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:02 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 05 09:08:02 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 05 09:08:03 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 09:08:03 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:03 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:03 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:03 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:03 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:03 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:03 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:03 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 05 09:08:03 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 05 09:08:05 volumio sudo[8020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 09:08:05 volumio sudo[8020]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:05 volumio sudo[8020]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:05 volumio sudo[8023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 09:08:05 volumio sudo[8023]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:05 volumio sudo[8023]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:05 volumio volumio[6912]: verbose: New Socket.io Connection to 192.168.68.56 from 192.168.68.55 UA: Mozilla/5.0 (Linux; Android 13; 23013PC75G Build/TKQ1.220905.001; 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: 11 Oct 05 09:08:05 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:05 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:05 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:05 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::volumioGetVisibleSources Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:05 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::volumioGetQueue Oct 05 09:08:05 volumio volumio[6912]: info: CoreStateMachine::getQueue Oct 05 09:08:05 volumio volumio[6912]: info: CorePlayQueue::getQueue Oct 05 09:08:05 volumio volumio[6912]: info: Listing playlists Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 05 09:08:05 volumio volumio[6912]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 05 09:08:05 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:05 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:05 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:05 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:05 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:06 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 05 09:08:06 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 09:08:06 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:06 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:06 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:06 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:06 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:06 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:06 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 09:08:07 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:07 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:07 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 09:08:07 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:07 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:07 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 09:08:07 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:07 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:07 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:07 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:08 volumio volumio[6912]: verbose: New Socket.io Connection to wbecevccbjbhpuwbz4e5dg49snk2-26171f5f2ee3cb5ad4131f3f75bc053a.as1.myvolumio.org from 127.0.0.1 UA: Mozilla/5.0 (Linux; Android 13; 23013PC75G Build/TKQ1.220905.001; 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: 10 Oct 05 09:08:08 volumio sudo[8026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 09:08:08 volumio sudo[8026]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:08 volumio sudo[8026]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:08 volumio sudo[8029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 09:08:08 volumio sudo[8029]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:08 volumio sudo[8029]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:08 volumio volumio[6912]: verbose: New Socket.io Connection to 192.168.68.56 from 192.168.68.55 UA: Mozilla/5.0 (Linux; Android 13; 23013PC75G Build/TKQ1.220905.001; 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: 11 Oct 05 09:08:08 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:08 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:08 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:08 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetVisibleSources Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:08 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetQueue Oct 05 09:08:08 volumio volumio[6912]: info: CoreStateMachine::getQueue Oct 05 09:08:08 volumio volumio[6912]: info: CorePlayQueue::getQueue Oct 05 09:08:08 volumio volumio[6912]: info: Listing playlists Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 05 09:08:08 volumio volumio[6912]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 05 09:08:08 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:08 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:08 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:08 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:08 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:08 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:08 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:08 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:08 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:08 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:08 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:08 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetVisibleSources Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:08 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetQueue Oct 05 09:08:08 volumio volumio[6912]: info: CoreStateMachine::getQueue Oct 05 09:08:08 volumio volumio[6912]: info: CorePlayQueue::getQueue Oct 05 09:08:08 volumio volumio[6912]: info: Listing playlists Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 05 09:08:08 volumio volumio[6912]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 05 09:08:08 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:08 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:08 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:08 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 05 09:08:08 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 05 09:08:14 volumio sudo[8033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 09:08:14 volumio sudo[8033]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:14 volumio sudo[8033]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:14 volumio sudo[8036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 09:08:14 volumio sudo[8036]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:14 volumio sudo[8036]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:14 volumio volumio[6912]: verbose: New Socket.io Connection to 192.168.68.56 from 192.168.68.55 UA: Mozilla/5.0 (Linux; Android 13; 23013PC75G Build/TKQ1.220905.001; 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: 10 Oct 05 09:08:14 volumio sudo[8039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 09:08:14 volumio sudo[8039]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:14 volumio sudo[8039]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:14 volumio sudo[8042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 09:08:14 volumio sudo[8042]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:14 volumio sudo[8042]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:14 volumio volumio[6912]: verbose: New Socket.io Connection to 192.168.68.56 from 192.168.68.55 UA: Mozilla/5.0 (Linux; Android 13; 23013PC75G Build/TKQ1.220905.001; 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: 11 Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:14 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:14 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::volumioGetVisibleSources Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:14 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 05 09:08:14 volumio volumio[6912]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 05 09:08:14 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:14 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:14 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:14 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:14 volumio volumio[6912]: info: Listing playlists Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 05 09:08:14 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 05 09:08:15 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 05 09:08:15 volumio sudo[8086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 09:08:15 volumio sudo[8086]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:15 volumio sudo[8086]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:15 volumio sudo[8089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 09:08:15 volumio sudo[8089]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:15 volumio sudo[8089]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:15 volumio volumio[6912]: verbose: New Socket.io Connection to 192.168.68.56 from 192.168.68.55 UA: Mozilla/5.0 (Linux; Android 13; 23013PC75G Build/TKQ1.220905.001; 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: 12 Oct 05 09:08:15 volumio sudo[8092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 09:08:15 volumio sudo[8092]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:15 volumio sudo[8092]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:15 volumio sudo[8095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 09:08:15 volumio sudo[8095]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:08:15 volumio sudo[8095]: pam_unix(sudo:session): session closed for user root Oct 05 09:08:16 volumio volumio[6912]: verbose: New Socket.io Connection to 192.168.68.56 from 192.168.68.55 UA: Mozilla/5.0 (Linux; Android 13; 23013PC75G Build/TKQ1.220905.001; 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: 13 Oct 05 09:08:16 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 09:08:16 volumio volumio[6912]: info: CoreCommandRouter::volumioGetVisibleSources Oct 05 09:08:16 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 09:08:16 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:16 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:16 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 05 09:08:16 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 05 09:08:16 volumio volumio[6912]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 05 09:08:16 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:16 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:16 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:16 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:16 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:16 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:16 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:16 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:16 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:16 volumio volumio[6912]: info: Listing playlists Oct 05 09:08:16 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 05 09:08:16 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 05 09:08:16 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 05 09:08:17 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 05 09:08:17 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 09:08:17 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:17 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:17 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:17 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:17 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:17 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:17 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:17 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 05 09:08:17 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 09:08:17 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:17 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:17 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:17 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:17 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:17 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:17 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:18 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 09:08:18 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:18 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:18 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:18 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:18 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:18 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:18 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:18 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 09:08:18 volumio volumio[6912]: info: Received Get System Info Oct 05 09:08:18 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 09:08:18 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 09:08:18 volumio volumio[6912]: info: Discovery: Getting this device information Oct 05 09:08:18 volumio volumio[6912]: info: CoreCommandRouter::volumioGetState Oct 05 09:08:18 volumio volumio[6912]: info: CorePlayQueue::getTrack 0 Oct 05 09:08:18 volumio volumio[6912]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 09:08:20 volumio volumio[6912]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 05 09:08:20 volumio volumio[6912]: info: PLUGIN onReboot : networkfs Oct 05 09:08:20 volumio volumio[6912]: info: PLUGIN onReboot : audiophonicsonoff Oct 05 09:08:20 volumio volumio[6912]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 09:08:20 volumio volumio[6912]: TypeError: Cannot read property 'writeSync' of undefined Oct 05 09:08:20 volumio volumio[6912]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Oct 05 09:08:20 volumio volumio[6912]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Oct 05 09:08:20 volumio volumio[6912]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Oct 05 09:08:20 volumio volumio[6912]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 05 09:08:20 volumio volumio[6912]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 05 09:08:20 volumio volumio[6912]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Oct 05 09:08:20 volumio volumio[6912]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Oct 05 09:08:20 volumio volumio[6912]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Oct 05 09:08:20 volumio volumio[6912]: at Socket.emit (events.js:315:20) Oct 05 09:08:20 volumio volumio[6912]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 05 09:08:20 volumio volumio[6912]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 05 09:08:20 volumio volumio[6912]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 09:08:20 volumio sudo[8106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 09:07 Oct 05 09:08:20 volumio sudo[8106]: 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"