-- Logs begin at Sat 2024-11-23 19:09:40 WET, end at Sun 2024-11-24 19:13:27 WET. -- Nov 24 19:12:01 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:01 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:01 volumio volumio[2739]: info: Listing playlists Nov 24 19:12:01 volumio volumio[2739]: info: Listing playlists Nov 24 19:12:11 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:11 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:19 volumio kernel: usb 1-1.3: new full-speed USB device number 63 using xhci_hcd Nov 24 19:12:19 volumio kernel: usb 1-1.3: device descriptor read/64, error -32 Nov 24 19:12:21 volumio kernel: usb 1-1.3: new high-speed USB device number 64 using xhci_hcd Nov 24 19:12:21 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:21 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:21 volumio volumio[2739]: info: Listing playlists Nov 24 19:12:21 volumio volumio[2739]: info: Listing playlists Nov 24 19:12:22 volumio kernel: usb 1-1.3: New USB device found, idVendor=22e8, idProduct=ca04, bcdDevice= 6.15 Nov 24 19:12:22 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Nov 24 19:12:22 volumio kernel: usb 1-1.3: Product: CA Edge 2.0 Nov 24 19:12:22 volumio kernel: usb 1-1.3: Manufacturer: CA Nov 24 19:12:22 volumio kernel: usb 1-1.3: 1:3 : unsupported format bits 0x100000000 Nov 24 19:12:22 volumio volumio[2739]: info: Nov 24 19:12:22 volumio volumio[2739]: ---------------------------- USB Audio Device Attached Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Nov 24 19:12:22 volumio volumio[2739]: xcb_connection_has_error() returned true Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::Close All Modals sent Nov 24 19:12:22 volumio volumio[2739]: info: Preparing to save Alsa Options, stopping services first Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:22 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::volumioPause Nov 24 19:12:22 volumio volumio[2739]: info: CoreStateMachine::pause Nov 24 19:12:22 volumio volumio[2739]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"CA Edge 2.0","alsacard":"C20"},"i2s":false} Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 24 19:12:22 volumio volumio[2739]: xcb_connection_has_error() returned true Nov 24 19:12:22 volumio volumio[2739]: xcb_connection_has_error() returned true Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 24 19:12:22 volumio volumio[2739]: xcb_connection_has_error() returned true Nov 24 19:12:22 volumio volumio[2739]: info: Setting default mixerSoftMaster for Softvolume device Nov 24 19:12:22 volumio vtcs[3182]: [2024-11-24 19:12:22.322] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Nov 24 19:12:22 volumio vtcs[3182]: [2024-11-24 19:12:22.323] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 24 19:12:22 volumio sudo[13639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 24 19:12:22 volumio sudo[13639]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 19:12:22 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Nov 24 19:12:22 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 24 19:12:22 volumio systemd[1]: vtcs.service: Succeeded. Nov 24 19:12:22 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Nov 24 19:12:22 volumio sudo[13639]: pam_unix(sudo:session): session closed for user root Nov 24 19:12:22 volumio volumio[2739]: xcb_connection_has_error() returned true Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 24 19:12:22 volumio volumio[2739]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 24 19:12:22 volumio volumio[2739]: info: Disabling external Volume Control Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 19:12:22 volumio volumio[2739]: info: Enable softmixer device for audio device undefined Nov 24 19:12:22 volumio sudo[13652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 24 19:12:22 volumio sudo[13652]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 19:12:22 volumio volumio[2739]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 19:12:22 volumio volumio[2739]: info: CoreStateMachine::pushState Nov 24 19:12:22 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::volumioPushState Nov 24 19:12:22 volumio sudo[13652]: pam_unix(sudo:session): session closed for user root Nov 24 19:12:22 volumio volumio[2739]: info: [ASDebug] CurState: pause PrevState: pause Nov 24 19:12:22 volumio volumio[2739]: info: Software Volume ALSA configuration written Nov 24 19:12:22 volumio volumio[2739]: info: Preparing to generate the ALSA configuration file Nov 24 19:12:22 volumio volumio[2739]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 24 19:12:22 volumio volumio[2739]: info: Reading ALSA contributions from plugins. Nov 24 19:12:22 volumio volumio[2739]: xcb_connection_has_error() returned true Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 24 19:12:22 volumio volumio[2739]: xcb_connection_has_error() returned true Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 24 19:12:22 volumio volumio[2739]: info: Asound.conf file unchanged, so no further update is needed Nov 24 19:12:22 volumio volumio[2739]: info: Output device has changed, restarting MPD Nov 24 19:12:22 volumio sudo[13674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 19:12:22 volumio sudo[13674]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 19:12:22 volumio sudo[13674]: pam_unix(sudo:session): session closed for user root Nov 24 19:12:22 volumio volumio[2739]: info: Output device has changed, restarting Shairport Sync Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 19:12:22 volumio sudo[13677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 19:12:22 volumio sudo[13677]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 19:12:22 volumio systemd[1]: Stopping Music Player Daemon... Nov 24 19:12:22 volumio volumio[2739]: xcb_connection_has_error() returned true Nov 24 19:12:22 volumio volumio[2739]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 19:12:22 volumio volumio[2739]: xcb_connection_has_error() returned true Nov 24 19:12:22 volumio volumio[2739]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 24 19:12:22 volumio systemd[1]: mpd.service: Succeeded. Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 19:12:22 volumio systemd[1]: Stopped Music Player Daemon. Nov 24 19:12:22 volumio sudo[13686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 24 19:12:22 volumio sudo[13686]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 19:12:22 volumio systemd[1]: Starting Music Player Daemon... Nov 24 19:12:22 volumio volumio[2739]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 19:12:22 volumio volumio[2739]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 24 19:12:22 volumio volumio[2739]: info: MPD Permissions set Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 19:12:22 volumio sudo[13689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 24 19:12:22 volumio sudo[13689]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 19:12:22 volumio volumio[2739]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 19:12:22 volumio volumio[2739]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 24 19:12:22 volumio volumio[2739]: info: Starting Shairport Sync Nov 24 19:12:22 volumio sudo[13686]: pam_unix(sudo:session): session closed for user root Nov 24 19:12:22 volumio sudo[13689]: pam_unix(sudo:session): session closed for user root Nov 24 19:12:22 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Nov 24 19:12:22 volumio sudo[13682]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 24 19:12:22 volumio sudo[13699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 19:12:22 volumio sudo[13682]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 19:12:22 volumio sudo[13699]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 19:12:22 volumio sudo[13682]: pam_unix(sudo:session): session closed for user root Nov 24 19:12:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 24 19:12:22 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 24 19:12:22 volumio volumio[2739]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 19:12:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 24 19:12:22 volumio volumio[2739]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 24 19:12:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 24 19:12:22 volumio sudo[13699]: pam_unix(sudo:session): session closed for user root Nov 24 19:12:23 volumio volumio[2739]: info: Shairport-Sync Started Nov 24 19:12:23 volumio mpd[13702]: Nov 24 19:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 24 19:12:23 volumio systemd[1]: Started Music Player Daemon. Nov 24 19:12:23 volumio sudo[13677]: pam_unix(sudo:session): session closed for user root Nov 24 19:12:23 volumio volumio[2739]: error: updateQueue error: null Nov 24 19:12:25 volumio volumio[2739]: info: TidalConnect service stoped! Nov 24 19:12:25 volumio volumio[2739]: info: TidalConnect service stoped! Nov 24 19:12:25 volumio volumio[2739]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 24 19:12:25 volumio volumio[2739]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 24 19:12:25 volumio sudo[13717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 24 19:12:25 volumio sudo[13717]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 19:12:25 volumio systemd[1]: Started Volumio Tidal Connect Service. Nov 24 19:12:25 volumio sudo[13717]: pam_unix(sudo:session): session closed for user root Nov 24 19:12:25 volumio volumio[2739]: info: Executing endpoint tc_getconfig Nov 24 19:12:25 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 24 19:12:25 volumio vtcs[13719]: STARTING TidalConnect services, version: 1.4.0.34 Nov 24 19:12:25 volumio vtcs[13719]: STARTED TidalConnect services. Nov 24 19:12:25 volumio volumio[2739]: info: Executing endpoint tc_connect Nov 24 19:12:25 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 24 19:12:25 volumio volumio[2739]: info: Connecting to TidalConnect Nov 24 19:12:25 volumio volumio[2739]: info: CoreCommandRouter::servicePushState Nov 24 19:12:25 volumio volumio[2739]: info: CoreStateMachine::pushState Nov 24 19:12:25 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:25 volumio volumio[2739]: info: CoreCommandRouter::volumioPushState Nov 24 19:12:25 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:25 volumio volumio[2739]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 24 19:12:25 volumio volumio[2739]: info: CoreCommandRouter::servicePushState Nov 24 19:12:25 volumio volumio[2739]: info: CoreStateMachine::pushState Nov 24 19:12:25 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:25 volumio volumio[2739]: info: CoreCommandRouter::volumioPushState Nov 24 19:12:25 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:25 volumio volumio[2739]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 24 19:12:25 volumio volumio[2739]: info: [ASDebug] CurState: pause PrevState: pause Nov 24 19:12:25 volumio volumio[2739]: info: [ASDebug] CurState: pause PrevState: pause Nov 24 19:12:25 volumio volumio[2739]: info: TidalConnect service stoped! Nov 24 19:12:25 volumio volumio[2739]: info: TidalConnect service stoped! Nov 24 19:12:25 volumio volumio[2739]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 24 19:12:25 volumio volumio[2739]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 24 19:12:26 volumio sudo[13747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 24 19:12:26 volumio sudo[13747]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 19:12:26 volumio sudo[13747]: pam_unix(sudo:session): session closed for user root Nov 24 19:12:26 volumio volumio[2739]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 24 19:12:26 volumio volumio[2739]: info: CoreStateMachine::getcurrentVolume Nov 24 19:12:26 volumio volumio[2739]: info: CoreCommandRouter::volumioRetrievevolume Nov 24 19:12:26 volumio volumio[2739]: info: CoreStateMachine::pushState Nov 24 19:12:26 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:26 volumio volumio[2739]: info: CoreCommandRouter::volumioPushState Nov 24 19:12:26 volumio volumio[2739]: info: CoreStateMachine::updateTrackBlock Nov 24 19:12:26 volumio volumio[2739]: info: CorePlayQueue::getTrackBlock Nov 24 19:12:26 volumio volumio[2739]: info: CoreCommandRouter::volumioRetrievevolume Nov 24 19:12:26 volumio volumio[2739]: info: [ASDebug] CurState: pause PrevState: pause Nov 24 19:12:26 volumio volumio[2739]: info: CoreStateMachine::pushState Nov 24 19:12:26 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:26 volumio volumio[2739]: info: CoreCommandRouter::volumioPushState Nov 24 19:12:26 volumio volumio[2739]: info: [ASDebug] CurState: pause PrevState: pause Nov 24 19:12:28 volumio volumio[2739]: info: TidalConnect service started! Nov 24 19:12:29 volumio volumio[2739]: info: TidalConnect service started! Nov 24 19:12:31 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 19:12:31 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 19:12:31 volumio volumio[2739]: info: Discovery: Getting this device information Nov 24 19:12:31 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:31 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:31 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 19:12:31 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 19:12:31 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 19:12:31 volumio volumio[2739]: info: Discovery: Getting this device information Nov 24 19:12:31 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:31 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:31 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 19:12:31 volumio volumio[2739]: verbose: New Socket.io Connection to 192.168.68.112:3000 from 192.168.68.106 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Nov 24 19:12:31 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:31 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:31 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 24 19:12:31 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 24 19:12:31 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:31 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:35 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 19:12:35 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 19:12:35 volumio volumio[2739]: info: Discovery: Getting this device information Nov 24 19:12:35 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:35 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:35 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 19:12:35 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 19:12:35 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 19:12:35 volumio volumio[2739]: info: Discovery: Getting this device information Nov 24 19:12:35 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:35 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:35 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 19:12:38 volumio sudo[13863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 19:12:38 volumio sudo[13863]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 19:12:38 volumio sudo[13863]: pam_unix(sudo:session): session closed for user root Nov 24 19:12:38 volumio sudo[13866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 19:12:38 volumio sudo[13866]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 19:12:38 volumio sudo[13866]: pam_unix(sudo:session): session closed for user root Nov 24 19:12:38 volumio volumio[2739]: verbose: New Socket.io Connection to 192.168.68.112 from 192.168.68.106 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 24 19:12:38 volumio sudo[13869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 19:12:38 volumio sudo[13869]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 19:12:38 volumio sudo[13869]: pam_unix(sudo:session): session closed for user root Nov 24 19:12:38 volumio sudo[13872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 19:12:38 volumio sudo[13872]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 19:12:38 volumio sudo[13872]: pam_unix(sudo:session): session closed for user root Nov 24 19:12:38 volumio volumio[2739]: verbose: New Socket.io Connection to 192.168.68.112 from 192.168.68.106 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::volumioGetVisibleSources Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:38 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::volumioGetQueue Nov 24 19:12:38 volumio volumio[2739]: info: CoreStateMachine::getQueue Nov 24 19:12:38 volumio volumio[2739]: info: CorePlayQueue::getQueue Nov 24 19:12:38 volumio volumio[2739]: info: Listing playlists Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 24 19:12:38 volumio volumio[2739]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 24 19:12:38 volumio volumio[2739]: info: Received Get System Info Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 19:12:38 volumio volumio[2739]: info: Discovery: Getting this device information Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:38 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:38 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 24 19:12:38 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 24 19:12:40 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 24 19:12:40 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 19:12:40 volumio volumio[2739]: info: Received Get System Info Nov 24 19:12:40 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 19:12:40 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 19:12:40 volumio volumio[2739]: info: Discovery: Getting this device information Nov 24 19:12:40 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:40 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:40 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 19:12:41 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 19:12:41 volumio volumio[2739]: info: Received Get System Info Nov 24 19:12:41 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 19:12:41 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 19:12:41 volumio volumio[2739]: info: Discovery: Getting this device information Nov 24 19:12:41 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:41 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:41 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 19:12:41 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:41 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:41 volumio volumio[2739]: info: Listing playlists Nov 24 19:12:41 volumio volumio[2739]: info: Listing playlists Nov 24 19:12:48 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 24 19:12:51 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:51 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:57 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 24 19:12:57 volumio volumio[2739]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 24 19:12:57 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 24 19:12:57 volumio volumio[2739]: info: Received Get System Version Nov 24 19:12:57 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 24 19:12:57 volumio volumio[2739]: info: Received Get System Info Nov 24 19:12:57 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 19:12:57 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 19:12:57 volumio volumio[2739]: info: Discovery: Getting this device information Nov 24 19:12:57 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:12:57 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:12:57 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 19:13:00 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 19:13:00 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 19:13:00 volumio volumio[2739]: info: Discovery: Getting this device information Nov 24 19:13:00 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:13:00 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:13:00 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 19:13:01 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:13:01 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:13:01 volumio volumio[2739]: info: Listing playlists Nov 24 19:13:01 volumio volumio[2739]: info: Listing playlists Nov 24 19:13:11 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:13:11 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:13:12 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 24 19:13:12 volumio volumio[2739]: info: CURURI: artists://Steven%20Wilson Nov 24 19:13:12 volumio volumio[2739]: info: Preload queue cleared Nov 24 19:13:12 volumio volumio[2739]: info: Preload queue cleared Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/01 First Regret.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/02 3 Years Older.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/03 Hand Cannot Erase.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/04 Perfect Life.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/05 Routine.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/06 Home Invasion.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/07 Regret #9.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/08 Transience.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/09 Ancestral.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/10 Happy Returns.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/11 Ascendant Here On....flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/01 - Luminol.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/02 - Drive Home.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/03 - The Holy Drinker.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/04 - The Pin Drop.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/05 - The Watchmaker.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/06 - The Raven That Refused to Sing.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/01. To The Bone.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/02. Nowhere Now.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/03. Pariah (feat. Ninet Tayeb).flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/04. The Same Asylum As Before.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/05. Refuge.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/06. Permanating.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/07. Blank Tapes (feat. Ninet Tayeb).flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/08. People Who Eat Darkness.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/09. Song Of I (feat. Sophie Hunger).flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/10. Detonation.flac Nov 24 19:13:12 volumio volumio[2739]: info: Preloading song: music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/11. Song Of Unborn.flac Nov 24 19:13:12 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/01 First Regret.flac in service mpd Nov 24 19:13:12 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/Hand.%20Cannot.%20Erase./extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FHand.%20Cannot.%20Erase_%2F01%20First%20Regret.flac&metadata=false Nov 24 19:13:12 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/01 First Regret.flac Nov 24 19:13:12 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/02 3 Years Older.flac in service mpd Nov 24 19:13:12 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/03 Hand Cannot Erase.flac in service mpd Nov 24 19:13:12 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/04 Perfect Life.flac in service mpd Nov 24 19:13:12 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/Hand.%20Cannot.%20Erase./extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FHand.%20Cannot.%20Erase_%2F02%203%20Years%20Older.flac&metadata=false Nov 24 19:13:12 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/02 3 Years Older.flac Nov 24 19:13:12 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/Hand.%20Cannot.%20Erase./extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FHand.%20Cannot.%20Erase_%2F03%20Hand%20Cannot%20Erase.flac&metadata=false Nov 24 19:13:12 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/03 Hand Cannot Erase.flac Nov 24 19:13:12 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/05 Routine.flac in service mpd Nov 24 19:13:12 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/Hand.%20Cannot.%20Erase./extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FHand.%20Cannot.%20Erase_%2F04%20Perfect%20Life.flac&metadata=false Nov 24 19:13:12 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/04 Perfect Life.flac Nov 24 19:13:12 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/Hand.%20Cannot.%20Erase./extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FHand.%20Cannot.%20Erase_%2F05%20Routine.flac&metadata=false Nov 24 19:13:12 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/05 Routine.flac Nov 24 19:13:12 volumio volumio[2739]: info: Executing endpoint getSimilarArtists Nov 24 19:13:12 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Nov 24 19:13:12 volumio volumio[2739]: info: Executing endpoint metavolumio Nov 24 19:13:12 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 24 19:13:12 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/06 Home Invasion.flac in service mpd Nov 24 19:13:12 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/Hand.%20Cannot.%20Erase./extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FHand.%20Cannot.%20Erase_%2F06%20Home%20Invasion.flac&metadata=false Nov 24 19:13:12 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/06 Home Invasion.flac Nov 24 19:13:12 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/07 Regret #9.flac in service mpd Nov 24 19:13:12 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/Hand.%20Cannot.%20Erase./extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FHand.%20Cannot.%20Erase_%2F07%20Regret%20%239.flac&metadata=false Nov 24 19:13:12 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/07 Regret #9.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/08 Transience.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/Hand.%20Cannot.%20Erase./extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FHand.%20Cannot.%20Erase_%2F08%20Transience.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/08 Transience.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/09 Ancestral.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/Hand.%20Cannot.%20Erase./extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FHand.%20Cannot.%20Erase_%2F09%20Ancestral.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/09 Ancestral.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/10 Happy Returns.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/Hand.%20Cannot.%20Erase./extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FHand.%20Cannot.%20Erase_%2F10%20Happy%20Returns.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/10 Happy Returns.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/11 Ascendant Here On....flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/Hand.%20Cannot.%20Erase./extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FHand.%20Cannot.%20Erase_%2F11%20Ascendant%20Here%20On....flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/11 Ascendant Here On....flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/01 - Luminol.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/The%20Raven%20That%20Refused%20to%20Sing%20(and%20Other%20Stories)/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20The%20Raven%20That%20Refused%20to%20Sing%2F01%20-%20Luminol.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/01 - Luminol.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/02 - Drive Home.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/The%20Raven%20That%20Refused%20to%20Sing%20(and%20Other%20Stories)/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20The%20Raven%20That%20Refused%20to%20Sing%2F02%20-%20Drive%20Home.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/02 - Drive Home.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/03 - The Holy Drinker.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/The%20Raven%20That%20Refused%20to%20Sing%20(and%20Other%20Stories)/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20The%20Raven%20That%20Refused%20to%20Sing%2F03%20-%20The%20Holy%20Drinker.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/03 - The Holy Drinker.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/04 - The Pin Drop.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/The%20Raven%20That%20Refused%20to%20Sing%20(and%20Other%20Stories)/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20The%20Raven%20That%20Refused%20to%20Sing%2F04%20-%20The%20Pin%20Drop.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/04 - The Pin Drop.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/05 - The Watchmaker.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/The%20Raven%20That%20Refused%20to%20Sing%20(and%20Other%20Stories)/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20The%20Raven%20That%20Refused%20to%20Sing%2F05%20-%20The%20Watchmaker.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/05 - The Watchmaker.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/06 - The Raven That Refused to Sing.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/The%20Raven%20That%20Refused%20to%20Sing%20(and%20Other%20Stories)/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20The%20Raven%20That%20Refused%20to%20Sing%2F06%20-%20The%20Raven%20That%20Refused%20to%20Sing.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - The Raven That Refused to Sing/06 - The Raven That Refused to Sing.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/01. To The Bone.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/To%20The%20Bone/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20To%20the%20Bone%2F01.%20To%20The%20Bone.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/01. To The Bone.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/02. Nowhere Now.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/To%20The%20Bone/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20To%20the%20Bone%2F02.%20Nowhere%20Now.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/02. Nowhere Now.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/03. Pariah (feat. Ninet Tayeb).flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/To%20The%20Bone/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20To%20the%20Bone%2F03.%20Pariah%20(feat.%20Ninet%20Tayeb).flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/03. Pariah (feat. Ninet Tayeb).flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/04. The Same Asylum As Before.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/To%20The%20Bone/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20To%20the%20Bone%2F04.%20The%20Same%20Asylum%20As%20Before.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/04. The Same Asylum As Before.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/05. Refuge.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/To%20The%20Bone/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20To%20the%20Bone%2F05.%20Refuge.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/05. Refuge.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/06. Permanating.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/To%20The%20Bone/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20To%20the%20Bone%2F06.%20Permanating.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/06. Permanating.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/07. Blank Tapes (feat. Ninet Tayeb).flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/To%20The%20Bone/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20To%20the%20Bone%2F07.%20Blank%20Tapes%20(feat.%20Ninet%20Tayeb).flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/07. Blank Tapes (feat. Ninet Tayeb).flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/08. People Who Eat Darkness.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/To%20The%20Bone/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20To%20the%20Bone%2F08.%20People%20Who%20Eat%20Darkness.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/08. People Who Eat Darkness.flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/09. Song Of I (feat. Sophie Hunger).flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/To%20The%20Bone/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20To%20the%20Bone%2F09.%20Song%20Of%20I%20(feat.%20Sophie%20Hunger).flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/09. Song Of I (feat. Sophie Hunger).flac Nov 24 19:13:13 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/10. Detonation.flac in service mpd Nov 24 19:13:13 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/To%20The%20Bone/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20To%20the%20Bone%2F10.%20Detonation.flac&metadata=false Nov 24 19:13:13 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/10. Detonation.flac Nov 24 19:13:14 volumio volumio[2739]: info: Exploding uri music-library/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/11. Song Of Unborn.flac in service mpd Nov 24 19:13:14 volumio volumio[2739]: info: ALBUMART /albumart?cacheid=471&web=Steven%20Wilson/To%20The%20Bone/extralarge&path=%2Fmnt%2FNAS%2FMusic_HR%2FSteven%20Wilson%2FSteven%20Wilson%20-%20To%20the%20Bone%2F11.%20Song%20Of%20Unborn.flac&metadata=false Nov 24 19:13:14 volumio volumio[2739]: info: URI /mnt/NAS/Music_HR/Steven Wilson/Steven Wilson - To the Bone/11. Song Of Unborn.flac Nov 24 19:13:16 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 24 19:13:16 volumio volumio[2739]: info: CURURI: artists://Steven%20Wilson/Hand.%20Cannot.%20Erase. Nov 24 19:13:16 volumio volumio[2739]: info: Preload queue cleared Nov 24 19:13:16 volumio volumio[2739]: info: Executing endpoint getSimilarAlbums Nov 24 19:13:16 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Nov 24 19:13:16 volumio volumio[2739]: info: Executing endpoint metavolumio Nov 24 19:13:16 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 24 19:13:16 volumio volumio[2739]: info: Executing endpoint metavolumio Nov 24 19:13:16 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 24 19:13:21 volumio volumio[2739]: info: CoreCommandRouter::volumioGetState Nov 24 19:13:21 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:13:21 volumio volumio[2739]: info: Listing playlists Nov 24 19:13:21 volumio volumio[2739]: info: Listing playlists Nov 24 19:13:26 volumio volumio[2739]: info: Preload queue cleared Nov 24 19:13:26 volumio volumio[2739]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::ClearQueue Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::stop Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::updateTrackBlock Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::getTrackBlock Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::stPlaybackTimer Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::pushState Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:13:26 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 19:13:26 volumio volumio[2739]: info: CoreCommandRouter::volumioPushState Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::serviceStop Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::getTrack 2 Nov 24 19:13:26 volumio volumio[2739]: info: CoreCommandRouter::serviceStop Nov 24 19:13:26 volumio volumio[2739]: info: ControllerMpd::stop Nov 24 19:13:26 volumio volumio[2739]: verbose: ControllerMpd::sendMpdCommand stop Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::clearPlayQueue Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::saveQueue Nov 24 19:13:26 volumio volumio[2739]: info: CoreCommandRouter::volumioPushQueue Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::addQueueItems Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::addQueueItems Nov 24 19:13:26 volumio volumio[2739]: info: Preload queue cleared Nov 24 19:13:26 volumio volumio[2739]: info: Adding Item to queue: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/01 First Regret.flac Nov 24 19:13:26 volumio volumio[2739]: info: Using cached record of: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/01 First Regret.flac Nov 24 19:13:26 volumio volumio[2739]: info: Adding Item to queue: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/02 3 Years Older.flac Nov 24 19:13:26 volumio volumio[2739]: info: Using cached record of: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/02 3 Years Older.flac Nov 24 19:13:26 volumio volumio[2739]: info: Adding Item to queue: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/03 Hand Cannot Erase.flac Nov 24 19:13:26 volumio volumio[2739]: info: Using cached record of: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/03 Hand Cannot Erase.flac Nov 24 19:13:26 volumio volumio[2739]: info: Adding Item to queue: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/04 Perfect Life.flac Nov 24 19:13:26 volumio volumio[2739]: info: Using cached record of: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/04 Perfect Life.flac Nov 24 19:13:26 volumio volumio[2739]: info: Adding Item to queue: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/05 Routine.flac Nov 24 19:13:26 volumio volumio[2739]: info: Using cached record of: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/05 Routine.flac Nov 24 19:13:26 volumio volumio[2739]: info: Adding Item to queue: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/06 Home Invasion.flac Nov 24 19:13:26 volumio volumio[2739]: info: Using cached record of: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/06 Home Invasion.flac Nov 24 19:13:26 volumio volumio[2739]: info: Adding Item to queue: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/07 Regret #9.flac Nov 24 19:13:26 volumio volumio[2739]: info: Using cached record of: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/07 Regret #9.flac Nov 24 19:13:26 volumio volumio[2739]: info: Adding Item to queue: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/08 Transience.flac Nov 24 19:13:26 volumio volumio[2739]: info: Using cached record of: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/08 Transience.flac Nov 24 19:13:26 volumio volumio[2739]: info: Adding Item to queue: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/09 Ancestral.flac Nov 24 19:13:26 volumio volumio[2739]: info: Using cached record of: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/09 Ancestral.flac Nov 24 19:13:26 volumio volumio[2739]: info: CoreCommandRouter::volumioPushQueue Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::saveQueue Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::updateTrackBlock Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::getTrackBlock Nov 24 19:13:26 volumio volumio[2739]: info: CoreCommandRouter::volumioPlay Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::play index 8 Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::addQueueItems Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::addQueueItems Nov 24 19:13:26 volumio volumio[2739]: info: Preload queue cleared Nov 24 19:13:26 volumio volumio[2739]: info: Adding Item to queue: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/10 Happy Returns.flac Nov 24 19:13:26 volumio volumio[2739]: info: Using cached record of: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/10 Happy Returns.flac Nov 24 19:13:26 volumio volumio[2739]: info: Adding Item to queue: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/11 Ascendant Here On....flac Nov 24 19:13:26 volumio volumio[2739]: info: Using cached record of: music-library/NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/11 Ascendant Here On....flac Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::stop Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 19:13:26 volumio volumio[2739]: info: CoreCommandRouter::volumioPushQueue Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::saveQueue Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::play index undefined Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::updateTrackBlock Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::getTrackBlock Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::getTrack 8 Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::startPlaybackTimer Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::getTrack 8 Nov 24 19:13:26 volumio volumio[2739]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/09 Ancestral.flac Nov 24 19:13:26 volumio volumio[2739]: verbose: ControllerMpd::sendMpdCommand stop Nov 24 19:13:26 volumio volumio[2739]: info: [ASDebug] CurState: stop PrevState: pause Nov 24 19:13:26 volumio volumio[2739]: info: sendMpdCommand stop took 22 milliseconds Nov 24 19:13:26 volumio volumio[2739]: info: sendMpdCommand stop took 6 milliseconds Nov 24 19:13:26 volumio volumio[2739]: verbose: ControllerMpd::sendMpdCommand clear Nov 24 19:13:26 volumio volumio[2739]: info: Nov 24 19:13:26 volumio volumio[2739]: ---------------------------- MPD announces system playlist update Nov 24 19:13:26 volumio volumio[2739]: info: Ignoring MPD Status Update Nov 24 19:13:26 volumio volumio[2739]: info: sendMpdCommand clear took 1 milliseconds Nov 24 19:13:26 volumio volumio[2739]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/09 Ancestral.flac" Nov 24 19:13:26 volumio volumio[2739]: error: updateQueue error: null Nov 24 19:13:26 volumio volumio[2739]: info: Nov 24 19:13:26 volumio volumio[2739]: ---------------------------- MPD announces system playlist update Nov 24 19:13:26 volumio volumio[2739]: info: Ignoring MPD Status Update Nov 24 19:13:26 volumio volumio[2739]: info: ------------------------------ 2ms Nov 24 19:13:26 volumio volumio[2739]: info: sendMpdCommand add "NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/09 Ancestral.flac" took 3 milliseconds Nov 24 19:13:26 volumio volumio[2739]: verbose: ControllerMpd::sendMpdCommand play Nov 24 19:13:26 volumio volumio[2739]: info: ------------------------------ 4ms Nov 24 19:13:26 volumio volumio[2739]: info: sendMpdCommand play took 1 milliseconds Nov 24 19:13:26 volumio volumio[2739]: info: Nov 24 19:13:26 volumio volumio[2739]: ---------------------------- MPD announces state update: player Nov 24 19:13:26 volumio volumio[2739]: info: ControllerMpd::getState Nov 24 19:13:26 volumio volumio[2739]: verbose: ControllerMpd::sendMpdCommand status Nov 24 19:13:26 volumio volumio[2739]: info: Nov 24 19:13:26 volumio volumio[2739]: ---------------------------- MPD announces state update: player Nov 24 19:13:26 volumio volumio[2739]: info: sendMpdCommand status took 55 milliseconds Nov 24 19:13:26 volumio volumio[2739]: info: ControllerMpd::getState Nov 24 19:13:26 volumio volumio[2739]: verbose: ControllerMpd::sendMpdCommand status Nov 24 19:13:26 volumio volumio[2739]: verbose: ControllerMpd::parseState Nov 24 19:13:26 volumio volumio[2739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 19:13:26 volumio volumio[2739]: info: sendMpdCommand status took 3 milliseconds Nov 24 19:13:26 volumio volumio[2739]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 24 19:13:26 volumio volumio[2739]: verbose: ControllerMpd::parseState Nov 24 19:13:26 volumio volumio[2739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 19:13:26 volumio volumio[2739]: verbose: ControllerMpd::parseTrackInfo Nov 24 19:13:26 volumio volumio[2739]: info: ControllerMpd::pushState Nov 24 19:13:26 volumio volumio[2739]: info: CoreCommandRouter::servicePushState Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::getTrack 8 Nov 24 19:13:26 volumio volumio[2739]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":813,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"264 Kbps","isStreaming":false,"title":"Ancestral","artist":"Steven Wilson","album":"Hand. Cannot. Erase.","uri":"NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/09 Ancestral.flac","trackType":"flac"} Nov 24 19:13:26 volumio volumio[2739]: verbose: CURRENT POSITION 8 Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::syncState stateService play Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::syncState currentStatus stop Nov 24 19:13:26 volumio volumio[2739]: info: ------------------------------ 63ms Nov 24 19:13:26 volumio volumio[2739]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 24 19:13:26 volumio volumio[2739]: verbose: ControllerMpd::parseTrackInfo Nov 24 19:13:26 volumio volumio[2739]: info: ControllerMpd::pushState Nov 24 19:13:26 volumio volumio[2739]: info: CoreCommandRouter::servicePushState Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::getTrack 8 Nov 24 19:13:26 volumio volumio[2739]: verbose: STATE SERVICE {"status":"play","position":0,"seek":740,"duration":813,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"748 Kbps","isStreaming":false,"title":"Ancestral","artist":"Steven Wilson","album":"Hand. Cannot. Erase.","uri":"NAS/Music_HR/Steven Wilson/Hand. Cannot. Erase_/09 Ancestral.flac","trackType":"flac"} Nov 24 19:13:26 volumio volumio[2739]: verbose: CURRENT POSITION 8 Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::syncState stateService play Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::syncState currentStatus play Nov 24 19:13:26 volumio volumio[2739]: info: Received an update from plugin. extracting info from payload Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::pushState Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::getTrack 8 Nov 24 19:13:26 volumio volumio[2739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 19:13:26 volumio volumio[2739]: info: CoreCommandRouter::volumioPushState Nov 24 19:13:26 volumio volumio[2739]: info: CoreStateMachine::pushState Nov 24 19:13:26 volumio volumio[2739]: info: CorePlayQueue::getTrack 8 Nov 24 19:13:26 volumio volumio[2739]: info: CoreCommandRouter::volumioPushState Nov 24 19:13:26 volumio volumio[2739]: info: ------------------------------ 16ms Nov 24 19:13:26 volumio volumio[2739]: info: [ASDebug] CurState: play PrevState: pause Nov 24 19:13:26 volumio volumio[2739]: info: [ASDebug] Togle GPIO: ON Nov 24 19:13:26 volumio volumio[2739]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 19:13:26 volumio volumio[2739]: Error: EBADF: bad file descriptor, write Nov 24 19:13:26 volumio volumio[2739]: at Object.writeSync (fs.js:711:3) Nov 24 19:13:26 volumio volumio[2739]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Nov 24 19:13:26 volumio volumio[2739]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Nov 24 19:13:26 volumio volumio[2739]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Nov 24 19:13:26 volumio volumio[2739]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 24 19:13:26 volumio volumio[2739]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Nov 24 19:13:26 volumio volumio[2739]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Nov 24 19:13:26 volumio volumio[2739]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 24 19:13:26 volumio volumio[2739]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 24 19:13:26 volumio volumio[2739]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Nov 24 19:13:26 volumio volumio[2739]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 24 19:13:26 volumio volumio[2739]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 24 19:13:26 volumio volumio[2739]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Nov 24 19:13:26 volumio volumio[2739]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Nov 24 19:13:26 volumio volumio[2739]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 24 19:13:26 volumio volumio[2739]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Nov 24 19:13:26 volumio volumio[2739]: errno: -9, Nov 24 19:13:26 volumio volumio[2739]: syscall: 'write', Nov 24 19:13:26 volumio volumio[2739]: code: 'EBADF' Nov 24 19:13:26 volumio volumio[2739]: } Nov 24 19:13:26 volumio volumio[2739]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 19:13:27 volumio sudo[14034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 19:12 Nov 24 19:13:27 volumio sudo[14034]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"