-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2024-10-18 19:49:40 CEST. -- Oct 18 19:48:00 margules-magenta volumio[1039]: info: Loading i18n strings for locale es Oct 18 19:48:00 margules-magenta volumio[1039]: Updating browse sources language Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::initPlayerControls Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: Express server listening on port 3000 Oct 18 19:48:00 margules-magenta volumio[1039]: [Metrics] WebUI: 12s 208.25ms Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreStateMachine::resetVolumioState Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreStateMachine::getcurrentVolume Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioRetrievevolume Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:48:00 margules-magenta kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1855: inode #20020227: comm amixer: iget: checksum invalid Oct 18 19:48:00 margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 18 19:48:00 margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Oct 18 19:48:00 margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 18 19:48:00 margules-magenta volumio[1039]: info: MRS: Removed streaming files Oct 18 19:48:00 margules-magenta volumio[1039]: info: MRS: volumioStreaming STOPPED Oct 18 19:48:00 margules-magenta volumio[1039]: info: MRS: SNAPSERVER STOPPED Oct 18 19:48:00 margules-magenta volumio[1039]: info: MRS: SNAPCLIENT STOPPED Oct 18 19:48:00 margules-magenta volumio[1039]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 18 19:48:00 margules-magenta volumio[1039]: wlan0 Interface doesn't support scanning : Resource temporarily unavailable Oct 18 19:48:00 margules-magenta volumio[1039]: info: Cannot use regular scanning, forcing with ap-force Oct 18 19:48:00 margules-magenta sudo[1217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 18 19:48:00 margules-magenta sudo[1217]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:00 margules-magenta sudo[1217]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:00 margules-magenta volumio[1039]: command failed: Resource temporarily unavailable (-11) Oct 18 19:48:00 margules-magenta volumio[1039]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 18 19:48:00 margules-magenta volumio[1039]: command failed: Resource temporarily unavailable (-11) Oct 18 19:48:00 margules-magenta kernel: ieee80211 phy0: brcmf_cfg80211_scan: Connecting: status (3) Oct 18 19:48:00 margules-magenta volumio[1039]: verbose: New Socket.io Connection to 192.168.1.101:3000 from 192.168.1.51 UA: node-XMLHttpRequest Total Clients: 1 Oct 18 19:48:00 margules-magenta volumio[1039]: verbose: New Socket.io Connection to 192.168.1.101:3000 from 192.168.1.67 UA: node-XMLHttpRequest Total Clients: 2 Oct 18 19:48:00 margules-magenta volumio[1039]: verbose: New Socket.io Connection to 192.168.1.101:3000 from 192.168.1.51 UA: node-XMLHttpRequest Total Clients: 3 Oct 18 19:48:00 margules-magenta volumio[1039]: verbose: New Socket.io Connection to 192.168.1.101:3000 from 192.168.1.51 UA: node-XMLHttpRequest Total Clients: 4 Oct 18 19:48:00 margules-magenta volumio[1039]: verbose: New Socket.io Connection to 192.168.1.101:3000 from 192.168.1.67 UA: node-XMLHttpRequest Total Clients: 5 Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:48:00 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreStateMachine::updateTrackBlock Oct 18 19:48:00 margules-magenta volumio[1039]: info: CorePlayQueue::getTrackBlock Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioRetrievevolume Oct 18 19:48:00 margules-magenta kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1855: inode #20020227: comm amixer: iget: checksum invalid Oct 18 19:48:00 margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 18 19:48:00 margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Oct 18 19:48:00 margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreStateMachine::setRepeat false single undefined Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:48:00 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreStateMachine::setRandom false Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:48:00 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:48:00 margules-magenta volumio[1039]: info: Setting Device type: Raspberry PI Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:48:00 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:48:00 margules-magenta volumio[1039]: info: Completed loading Core Plugins Oct 18 19:48:00 margules-magenta volumio[1039]: info: Preparing to generate the ALSA configuration file Oct 18 19:48:00 margules-magenta volumio[1039]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 18 19:48:00 margules-magenta volumio[1039]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 18 19:48:00 margules-magenta volumio[1039]: info: Reading ALSA contributions from plugins. Oct 18 19:48:00 margules-magenta sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 19:48:00 margules-magenta sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:00 margules-magenta sudo[1233]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:00 margules-magenta volumio[1039]: info: Discovery: adding d1fafd40-3b50-46d2-b780-3ddc1b0aa982 Oct 18 19:48:00 margules-magenta volumio[1039]: info: Discovery: Found device Margules Magenta II Oct 18 19:48:00 margules-magenta sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 19:48:00 margules-magenta sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:00 margules-magenta sudo[1236]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:00 margules-magenta volumio[1039]: info: Discovery: Connecting to remote: 192.168.1.52 Oct 18 19:48:00 margules-magenta volumio[1039]: info: Discovery: adding 0d2d32bb-403e-459f-8094-d748929d3ba8 Oct 18 19:48:00 margules-magenta volumio[1039]: info: Discovery: Found device Margules Magenta Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetState Oct 18 19:48:00 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:00 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output for this device Oct 18 19:48:00 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:00 margules-magenta volumio[1039]: info: Adding audio output: Oct 18 19:48:00 margules-magenta volumio[1039]: info: Adding audio output: Oct 18 19:48:00 margules-magenta volumio[1039]: info: Asound.conf file unchanged, so no further update is needed Oct 18 19:48:00 margules-magenta volumio[1039]: info: Output device has changed, restarting MPD Oct 18 19:48:00 margules-magenta sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 18 19:48:00 margules-magenta sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:00 margules-magenta volumio[1039]: info: Output device has changed, restarting Shairport Sync Oct 18 19:48:00 margules-magenta sudo[1239]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:48:00 margules-magenta sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 18 19:48:00 margules-magenta sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:00 margules-magenta kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1855: inode #20020227: comm aplay: iget: checksum invalid Oct 18 19:48:00 margules-magenta volumio[1039]: Failed to stat runtime directory /home/volumio/.config/pulse/a34096547c2268309628208d660159ea-runtime: Bad message Oct 18 19:48:00 margules-magenta volumio[1039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 18 19:48:00 margules-magenta volumio[1039]: Failed to stat runtime directory /home/volumio/.config/pulse/a34096547c2268309628208d660159ea-runtime: Bad message Oct 18 19:48:00 margules-magenta volumio[1039]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: info: ___________ START PLUGINS ___________ Oct 18 19:48:00 margules-magenta volumio[1039]: info: ControllerMpd::onStart: Initializing MPD Oct 18 19:48:00 margules-magenta volumio[1039]: info: Creating MPD Configuration file Oct 18 19:48:00 margules-magenta systemd[1]: Listening on mpd.socket. Oct 18 19:48:00 margules-magenta systemd[1]: Starting Music Player Daemon... Oct 18 19:48:00 margules-magenta kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1855: inode #20020227: comm alsacap: iget: checksum invalid Oct 18 19:48:00 margules-magenta sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 18 19:48:00 margules-magenta sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 18 19:48:00 margules-magenta volumio[1039]: info: [1729273680887] CoreMusicLibrary::Adding element Media Servers Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 19:48:00 margules-magenta sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 18 19:48:00 margules-magenta sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:00 margules-magenta sudo[1252]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:00 margules-magenta systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 18 19:48:00 margules-magenta systemd[1]: mpd.service: Succeeded. Oct 18 19:48:00 margules-magenta systemd[1]: Stopped Music Player Daemon. Oct 18 19:48:00 margules-magenta volumio[1039]: info: Adding Manifest REST API Endpoints Oct 18 19:48:00 margules-magenta systemd[1]: Starting Music Player Daemon... Oct 18 19:48:00 margules-magenta volumio[1039]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Oct 18 19:48:00 margules-magenta volumio[1039]: info: Adding METAVOLUMIO REST API Endpoints Oct 18 19:48:00 margules-magenta volumio[1039]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 18 19:48:00 margules-magenta volumio[1039]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 18 19:48:00 margules-magenta volumio[1039]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 18 19:48:00 margules-magenta volumio[1039]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:48:00 margules-magenta sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Margules Magenta Oct 18 19:48:00 margules-magenta sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:00 margules-magenta sudo[1258]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:00 margules-magenta volumio[1039]: Failed to stat runtime directory /home/volumio/.config/pulse/a34096547c2268309628208d660159ea-runtime: Bad message Oct 18 19:48:00 margules-magenta volumio[1039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 18 19:48:00 margules-magenta volumio[1039]: Failed to stat runtime directory /home/volumio/.config/pulse/a34096547c2268309628208d660159ea-runtime: Bad message Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:48:00 margules-magenta kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1855: inode #20020227: comm aplay: iget: checksum invalid Oct 18 19:48:01 margules-magenta volumio[1039]: Failed to stat runtime directory /home/volumio/.config/pulse/a34096547c2268309628208d660159ea-runtime: Bad message Oct 18 19:48:01 margules-magenta kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1855: inode #20020227: comm aplay: iget: checksum invalid Oct 18 19:48:01 margules-magenta volumio[1039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 18 19:48:01 margules-magenta volumio[1039]: Failed to stat runtime directory /home/volumio/.config/pulse/a34096547c2268309628208d660159ea-runtime: Bad message Oct 18 19:48:01 margules-magenta volumio[1039]: info: Preparing CD Folders Oct 18 19:48:01 margules-magenta volumio[1039]: info: Adding CD REST API Endpoints Oct 18 19:48:01 margules-magenta volumio[1039]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 18 19:48:01 margules-magenta volumio[1039]: info: Starting UDEV Watcher for CD Oct 18 19:48:01 margules-magenta volumio[1039]: info: Detecting CD presence with UDEV Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 18 19:48:01 margules-magenta volumio[1039]: info: [1729273681049] CoreMusicLibrary::Adding element Last_100 Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 19:48:01 margules-magenta volumio[1039]: info: Streaming services startup Oct 18 19:48:01 margules-magenta volumio[1039]: info: Starting Streaming Daemon Oct 18 19:48:01 margules-magenta sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 18 19:48:01 margules-magenta sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:01 margules-magenta sudo[1268]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 18 19:48:01 margules-magenta volumio[1039]: info: [1729273681138] CoreMusicLibrary::Adding element Webradio Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 18 19:48:01 margules-magenta volumio[1039]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta sudo[1275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 18 19:48:01 margules-magenta sudo[1275]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:01 margules-magenta volumio[1039]: error: Hi Res Audio Failed Login: Missing Login Data Oct 18 19:48:01 margules-magenta volumio[1039]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 18 19:48:01 margules-magenta volumio[1039]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 18 19:48:01 margules-magenta volumio[1039]: info: Refreshing QOBUZ token Oct 18 19:48:01 margules-magenta kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1855: inode #20020227: comm alsacap: iget: checksum invalid Oct 18 19:48:01 margules-magenta volumio[1039]: info: Adding inputs REST Endpoints Oct 18 19:48:01 margules-magenta volumio[1039]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 18 19:48:01 margules-magenta volumio[1039]: info: Scanning Audio Inputs Oct 18 19:48:01 margules-magenta sudo[1275]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:01 margules-magenta kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1855: inode #20020227: comm arecord: iget: checksum invalid Oct 18 19:48:01 margules-magenta volumio[1039]: Failed to stat runtime directory /home/volumio/.config/pulse/a34096547c2268309628208d660159ea-runtime: Bad message Oct 18 19:48:01 margules-magenta volumio[1039]: info: Checking against Known Cards name Oct 18 19:48:01 margules-magenta volumio[1039]: info: Adding Server instance for streaming Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: Volumio Calling Home Oct 18 19:48:01 margules-magenta volumio[1039]: info: Stopping AccessToken refresher cron Oct 18 19:48:01 margules-magenta volumio[1039]: info: AccessToken refresher cron started Oct 18 19:48:01 margules-magenta volumio[1039]: info: Adding TIDAL REST API Endpoints Oct 18 19:48:01 margules-magenta volumio[1039]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 18 19:48:01 margules-magenta sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 18 19:48:01 margules-magenta sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:01 margules-magenta volumio[1039]: error: Cannot start Volumio Streaming Daemon Oct 18 19:48:01 margules-magenta volumio[1039]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 18 19:48:01 margules-magenta volumio[1039]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 18 19:48:01 margules-magenta volumio[1039]: info: Bluetooth name changed to Margules Magenta Oct 18 19:48:01 margules-magenta volumio[1039]: info: MPD Permissions set Oct 18 19:48:01 margules-magenta volumio[1039]: info: MPD Permissions set Oct 18 19:48:01 margules-magenta volumio[1039]: info: MRS: Pushing audio output on request Oct 18 19:48:01 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:01 margules-magenta volumio[1039]: info: Discovery: Connected to remote: 192.168.1.52 Oct 18 19:48:01 margules-magenta systemd[1]: Started UPnP Renderer front-end to MPD. Oct 18 19:48:01 margules-magenta sudo[1297]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:01 margules-magenta volumio[1039]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Oct 18 19:48:01 margules-magenta volumio[1039]: info: Upmpdcli Daemon Started Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:01 margules-magenta volumio[1039]: verbose: New Socket.io Connection to 192.168.1.101:3000 from 192.168.1.51 UA: node-XMLHttpRequest Total Clients: 7 Oct 18 19:48:01 margules-magenta volumio[1039]: info: Starting Shairport Sync Oct 18 19:48:01 margules-magenta volumio[1039]: info: Starting Shairport Sync Oct 18 19:48:01 margules-magenta sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 18 19:48:01 margules-magenta sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:01 margules-magenta volumio[1039]: info: Starting Shairport Sync Oct 18 19:48:01 margules-magenta systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 18 19:48:01 margules-magenta sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 18 19:48:01 margules-magenta systemd[1]: shairport-sync.service: Succeeded. Oct 18 19:48:01 margules-magenta sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:01 margules-magenta systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 18 19:48:01 margules-magenta sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 18 19:48:01 margules-magenta sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:01 margules-magenta systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 18 19:48:01 margules-magenta volumio[1039]: verbose: New Socket.io Connection to 192.168.1.101:3000 from 192.168.1.67 UA: node-XMLHttpRequest Total Clients: 8 Oct 18 19:48:01 margules-magenta volumio[1039]: info: Discovery: adding 66da74f3-7079-4e95-8a5f-98378f4d3042 Oct 18 19:48:01 margules-magenta volumio[1039]: info: Discovery: Found device Volumio Oct 18 19:48:01 margules-magenta volumio[1039]: info: Discovery: Connecting to remote: 192.168.1.67 Oct 18 19:48:01 margules-magenta volumio[1039]: info: MRS: Adding multiroomSync output Oct 18 19:48:01 margules-magenta volumio[1039]: info: Adding audio output: Oct 18 19:48:01 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:01 margules-magenta sudo[1310]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:01 margules-magenta volumio[1039]: info: Volumio called home Oct 18 19:48:01 margules-magenta volumio[1039]: info: MRS: Pushing audio output on request Oct 18 19:48:01 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:01 margules-magenta volumio[1039]: info: Discovery: Connected to remote: 192.168.1.67 Oct 18 19:48:01 margules-magenta systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 18 19:48:01 margules-magenta systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 18 19:48:01 margules-magenta systemd[1]: shairport-sync.service: Succeeded. Oct 18 19:48:01 margules-magenta systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 18 19:48:01 margules-magenta systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 18 19:48:01 margules-magenta sudo[1313]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:01 margules-magenta sudo[1317]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 18 19:48:01 margules-magenta volumio[1039]: info: Shairport-Sync Started Oct 18 19:48:01 margules-magenta volumio[1039]: Error adding Membership: Error: addMembership EINVAL Oct 18 19:48:01 margules-magenta volumio[1039]: info: MRS: Found cast device: Bose-Soundbar-700-a1e1879fd153f3710e1ac9bbf7850b5b Oct 18 19:48:01 margules-magenta volumio[1039]: info: Adding audio output: Oct 18 19:48:01 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetState Oct 18 19:48:01 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:01 margules-magenta volumio[1039]: info: Shairport-Sync Started Oct 18 19:48:01 margules-magenta volumio[1039]: info: Shairport-Sync Started Oct 18 19:48:02 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:02 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 18 19:48:02 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetState Oct 18 19:48:02 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:02 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetState Oct 18 19:48:02 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:02 margules-magenta volumio[1039]: info: Access Token successfully retrieved Oct 18 19:48:02 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 18 19:48:02 margules-magenta volumio[1039]: info: [1729273682391] CoreMusicLibrary::Adding element QOBUZ Oct 18 19:48:02 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 19:48:02 margules-magenta volumio[1039]: Cannot find translation for source QOBUZ Oct 18 19:48:02 margules-magenta volumio[1039]: info: Stopping AccessToken refresher cron for QOBUZ Oct 18 19:48:02 margules-magenta volumio[1039]: info: AccessToken refresher cron started for QOBUZ Oct 18 19:48:02 margules-magenta volumio[1039]: info: Adding QOBUZ REST API Endpoints Oct 18 19:48:02 margules-magenta volumio[1039]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 18 19:48:02 margules-magenta mpd[1259]: Oct 18 19:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 18 19:48:02 margules-magenta systemd[1]: Started Music Player Daemon. Oct 18 19:48:02 margules-magenta sudo[1242]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:02 margules-magenta sudo[1255]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:02 margules-magenta volumio[1039]: error: MPD error: The expression evaluated to a falsy value: Oct 18 19:48:02 margules-magenta volumio[1039]: assert.ok(self.idling) Oct 18 19:48:02 margules-magenta volumio[1039]: error: The expression evaluated to a falsy value: Oct 18 19:48:02 margules-magenta volumio[1039]: assert.ok(self.idling) Oct 18 19:48:02 margules-magenta volumio[1299]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 18 19:48:02 margules-magenta volumio[1039]: error: updateQueue error: null Oct 18 19:48:02 margules-magenta volumio[1039]: info: MPD running with PID1259 Oct 18 19:48:02 margules-magenta volumio[1039]: ,establishing connection Oct 18 19:48:02 margules-magenta volumio[1039]: error: updateQueue error: null Oct 18 19:48:03 margules-magenta volumio[1039]: info: MRS: Pushing audio output on request Oct 18 19:48:03 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:03 margules-magenta volumio[1039]: info: Discovery: Connected to remote: 192.168.1.67 Oct 18 19:48:03 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:03 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:03 margules-magenta volumio[1299]: ...++++ Oct 18 19:48:03 margules-magenta volumio[1039]: info: MRS: Pushing audio output on request Oct 18 19:48:03 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:03 margules-magenta volumio[1039]: info: Discovery: Connected to remote: 192.168.1.67 Oct 18 19:48:03 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:03 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:03 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:03 margules-magenta sudo[1342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 18 19:48:03 margules-magenta sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:04 margules-magenta systemd[1]: Started Volumio Bluetooth Module. Oct 18 19:48:04 margules-magenta sudo[1342]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:04 margules-magenta volumio[1039]: info: Volumio BT Module successfully started Oct 18 19:48:04 margules-magenta sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 18 19:48:04 margules-magenta sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:04 margules-magenta volumiobt[1344]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Oct 18 19:48:04 margules-magenta sudo[1345]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:04 margules-magenta volumio[1039]: info: TidalConnect service stoped! Oct 18 19:48:04 margules-magenta volumio[1039]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 18 19:48:04 margules-magenta volumio[1039]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 18 19:48:04 margules-magenta sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 18 19:48:04 margules-magenta sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:04 margules-magenta systemd[1]: Started Volumio Tidal Connect Service. Oct 18 19:48:04 margules-magenta sudo[1357]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:04 margules-magenta volumio[1039]: info: Executing endpoint tc_getconfig Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 18 19:48:04 margules-magenta vtcs[1359]: STARTING TidalConnect services, version: 1.3.0.19 Oct 18 19:48:04 margules-magenta vtcs[1359]: Failed to stat runtime directory /home/volumio/.config/pulse/a34096547c2268309628208d660159ea-runtime: Bad message Oct 18 19:48:04 margules-magenta kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1855: inode #20020227: comm vtcs: iget: checksum invalid Oct 18 19:48:04 margules-magenta vtcs[1359]: Failed to stat runtime directory /home/volumio/.config/pulse/a34096547c2268309628208d660159ea-runtime: Bad message Oct 18 19:48:04 margules-magenta kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1855: inode #20020227: comm vtcs: iget: checksum invalid Oct 18 19:48:04 margules-magenta vtcs[1359]: STARTED TidalConnect services. Oct 18 19:48:04 margules-magenta volumio[1039]: info: Executing endpoint tc_connect Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 18 19:48:04 margules-magenta volumio[1039]: info: Connecting to TidalConnect Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreCommandRouter::servicePushState Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:48:04 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:48:04 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:48:04 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:04 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:04 margules-magenta volumio[1039]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 18 19:48:04 margules-magenta volumio[1039]: verbose: CURRENT POSITION 0 Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreStateMachine::syncState stateService stop Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:48:04 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:48:04 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:48:04 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:04 margules-magenta volumio[1039]: info: No code Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:48:04 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:48:04 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:48:04 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreCommandRouter::servicePushState Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:48:04 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:48:04 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:48:04 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:04 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:04 margules-magenta volumio[1039]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 18 19:48:04 margules-magenta volumio[1039]: verbose: CURRENT POSITION 0 Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreStateMachine::syncState stateService stop Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:48:04 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:48:04 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:48:04 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:04 margules-magenta volumio[1039]: info: No code Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:48:04 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:04 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:48:04 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:48:04 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:05 margules-magenta sudo[1377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 18 19:48:05 margules-magenta sudo[1377]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:48:05 margules-magenta volumiobt[1344]: pulseaudio: no process found Oct 18 19:48:05 margules-magenta sudo[1377]: pam_unix(sudo:session): session closed for user root Oct 18 19:48:05 margules-magenta dbus-daemon[514]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=1381 comm="/usr/bin/pulseaudio --high-priority=true ") Oct 18 19:48:05 margules-magenta systemd[1]: Starting RealtimeKit Scheduling Policy Service... Oct 18 19:48:05 margules-magenta volumio[1039]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 18 19:48:05 margules-magenta dbus-daemon[514]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Oct 18 19:48:05 margules-magenta rtkit-daemon[1382]: Successfully called chroot. Oct 18 19:48:05 margules-magenta rtkit-daemon[1382]: Successfully dropped privileges. Oct 18 19:48:05 margules-magenta systemd[1]: Started RealtimeKit Scheduling Policy Service. Oct 18 19:48:05 margules-magenta rtkit-daemon[1382]: Successfully limited resources. Oct 18 19:48:05 margules-magenta rtkit-daemon[1382]: Running. Oct 18 19:48:05 margules-magenta rtkit-daemon[1382]: Watchdog thread running. Oct 18 19:48:05 margules-magenta rtkit-daemon[1382]: Canary thread running. Oct 18 19:48:05 margules-magenta dbus-daemon[514]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.22' (uid=0 pid=1382 comm="/usr/lib/rtkit/rtkit-daemon ") Oct 18 19:48:05 margules-magenta systemd[1]: Starting Authorization Manager... Oct 18 19:48:05 margules-magenta volumio[1039]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 18 19:48:05 margules-magenta polkitd[1385]: started daemon version 0.105 using authority implementation `local' version `0.105' Oct 18 19:48:05 margules-magenta dbus-daemon[514]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 18 19:48:05 margules-magenta systemd[1]: Started Authorization Manager. Oct 18 19:48:05 margules-magenta pulseaudio[1381]: Failed to stat runtime directory /home/volumio/.config/pulse/a34096547c2268309628208d660159ea-runtime: Bad message Oct 18 19:48:05 margules-magenta volumiobt[1344]: E: [pulseaudio] main.c: Daemon startup failed. Oct 18 19:48:05 margules-magenta kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1855: inode #20020227: comm pulseaudio: iget: checksum invalid Oct 18 19:48:05 margules-magenta volumiobt[1344]: Applying permissions Oct 18 19:48:05 margules-magenta volumiobt[1344]: Setting BT discoverable and pairable Oct 18 19:48:05 margules-magenta volumiobt[1344]: Agent registered Oct 18 19:48:05 margules-magenta volumiobt[1344]: [bluetooth]# -e power on Oct 18 19:48:05 margules-magenta volumiobt[1344]: Invalid command in menu main: -e Oct 18 19:48:05 margules-magenta volumiobt[1344]: Oct 18 19:48:05 margules-magenta volumiobt[1344]: Use "help" for a list of available commands in a menu. Oct 18 19:48:05 margules-magenta volumiobt[1344]: Use "menu " if you want to enter any submenu. Oct 18 19:48:05 margules-magenta volumiobt[1344]: Use "back" if you want to return to menu main. Oct 18 19:48:05 margules-magenta volumiobt[1344]: [bluetooth]# agent on Oct 18 19:48:05 margules-magenta volumiobt[1344]: Agent is already registered Oct 18 19:48:05 margules-magenta volumiobt[1344]: [bluetooth]# discoverable on Oct 18 19:48:05 margules-magenta volumio[1039]: info: MRS: Pushing audio output on request Oct 18 19:48:05 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:05 margules-magenta volumiobt[1344]: [bluetooth]# pairable on Oct 18 19:48:05 margules-magenta volumiobt[1344]: [bluetooth]# agent NoInputNoOutput Oct 18 19:48:05 margules-magenta volumiobt[1344]: Agent is already registered Oct 18 19:48:05 margules-magenta volumiobt[1344]: [bluetooth]# default-agent Oct 18 19:48:05 margules-magenta volumiobt[1344]: [bluetooth]# quit Oct 18 19:48:05 margules-magenta volumio[1039]: info: Discovery: Connected to remote: 192.168.1.67 Oct 18 19:48:05 margules-magenta volumiobt[1344]: [59B blob data] Oct 18 19:48:05 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:05 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:05 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:05 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:05 margules-magenta wpa_supplicant[762]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=1c:61:b4:79:34:83 status_code=16 Oct 18 19:48:06 margules-magenta volumio[1039]: info: MRS: Getting audio outputs on start Oct 18 19:48:06 margules-magenta volumio[1039]: info: MRS: Requesting all other devices output Oct 18 19:48:06 margules-magenta volumio[1039]: info: MRS: Pushing audio output on request Oct 18 19:48:06 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:06 margules-magenta volumio[1039]: info: Discovery: Connected to remote: 192.168.1.67 Oct 18 19:48:06 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:06 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:06 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:06 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:06 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:07 margules-magenta volumio[1039]: info: TidalConnect service started! Oct 18 19:48:07 margules-magenta volumio[1039]: info: Completed starting Core Plugins Oct 18 19:48:07 margules-magenta volumio[1039]: info: ------------------------------------------- Oct 18 19:48:07 margules-magenta volumio[1039]: info: ----- MyVolumio plugins startup ---- Oct 18 19:48:07 margules-magenta volumio[1039]: info: ------------------------------------------- Oct 18 19:48:07 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 18 19:48:07 margules-magenta volumio[1039]: info: MRS: Pushing audio output on request Oct 18 19:48:07 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:07 margules-magenta volumio[1039]: info: Discovery: Connected to remote: 192.168.1.67 Oct 18 19:48:07 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:07 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:07 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:07 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:07 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:07 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:08 margules-magenta volumio[1039]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 18 19:48:08 margules-magenta volumio-remote-updater[521]: [2024-10-18 19:48:08] [connect] Successful connection Oct 18 19:48:08 margules-magenta volumio-remote-updater[521]: [2024-10-18 19:48:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729273688 101 Oct 18 19:48:08 margules-magenta volumio[1039]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 9 Oct 18 19:48:09 margules-magenta volumio[1039]: info: MRS: Pushing audio output on request Oct 18 19:48:09 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:09 margules-magenta volumio[1039]: info: Discovery: Connected to remote: 192.168.1.67 Oct 18 19:48:09 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:09 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:09 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:09 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:09 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:09 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:09 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:10 margules-magenta wpa_supplicant[762]: wlan0: Trying to associate with SSID 'DECO50-4' Oct 18 19:48:10 margules-magenta volumio[1299]: ................................................................................++++ Oct 18 19:48:10 margules-magenta volumio[1299]: e is 65537 (0x010001) Oct 18 19:48:10 margules-magenta volumio[1299]: writing RSA key Oct 18 19:48:11 margules-magenta volumio[1039]: info: MRS: Pushing audio output on request Oct 18 19:48:11 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Connected to remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: MRS: Pushing audio output on request Oct 18 19:48:11 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Connected to remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:11 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:12 margules-magenta volumio[1039]: info: MRS: Pushing audio output on request Oct 18 19:48:12 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:12 margules-magenta volumio[1039]: info: Discovery: Connected to remote: 192.168.1.67 Oct 18 19:48:12 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:12 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:12 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:12 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:12 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:12 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:12 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:12 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:12 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:12 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:13 margules-magenta volumio[1039]: info: MRS: Pushing audio output on request Oct 18 19:48:13 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:13 margules-magenta volumio[1039]: info: Discovery: Connected to remote: 192.168.1.67 Oct 18 19:48:13 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:13 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:13 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:13 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:13 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:13 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:13 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:13 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:13 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:13 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:13 margules-magenta volumio[1039]: info: Discovery: Disconnected from remote: 192.168.1.67 Oct 18 19:48:14 margules-magenta volumio[1039]: info: MRS: Pushing audio output on request Oct 18 19:48:14 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:14 margules-magenta volumio[1039]: info: Discovery: Connected to remote: 192.168.1.67 Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 18 19:48:15 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 18 19:48:16 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 18 19:48:16 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 18 19:48:16 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:48:16 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:48:16 margules-magenta volumio[1039]: info: Starting MyVolumio Remote Streaming Endpoints Oct 18 19:48:16 margules-magenta volumio[1039]: info: MyVolumio login type: Token Oct 18 19:48:16 margules-magenta volumio[1039]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 18 19:48:16 margules-magenta volumio[1039]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Oct 18 19:48:16 margules-magenta volumio[1039]: error: MyVolumio Custom Token format not valid, refreshing it Oct 18 19:48:17 margules-magenta wpa_supplicant[762]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=1c:61:b4:79:34:82 status_code=16 Oct 18 19:48:17 margules-magenta volumio[1039]: info: MyVolumio login type: Token Oct 18 19:48:18 margules-magenta volumio[1039]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 18 19:48:18 margules-magenta volumio[1039]: info: MyVolumio token set successfully Oct 18 19:48:18 margules-magenta volumio[1039]: info: MYVOLUMIO: Adding device Oct 18 19:48:18 margules-magenta volumio[1039]: info: MYVOLUMIO: Evaluating Server Oct 18 19:48:21 margules-magenta wpa_supplicant[762]: wlan0: Trying to associate with SSID 'DECO50-4' Oct 18 19:48:27 margules-magenta volumio[1039]: error: MyVolumio Plugin failed to start in a timely fashion Oct 18 19:48:27 margules-magenta volumio[1039]: info: BOOT COMPLETED Oct 18 19:48:27 margules-magenta volumio[1039]: [Metrics] CommandRouter: 38s 814.86ms Oct 18 19:48:27 margules-magenta volumio[1039]: info: CoreCommandRouter::volumiosetStartupVolume Oct 18 19:48:27 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:27 margules-magenta volumio[1039]: info: VolumeController:: Setting startup Volume 80 Oct 18 19:48:27 margules-magenta volumio[1039]: info: VolumeController::SetAlsaVolume80 Oct 18 19:48:27 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:48:27 margules-magenta volumio[1039]: info: CoreCommandRouter::Close All Modals sent Oct 18 19:48:27 margules-magenta kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1855: inode #20020227: comm amixer: iget: checksum invalid Oct 18 19:48:27 margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 18 19:48:27 margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Oct 18 19:48:27 margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 18 19:48:27 margules-magenta volumio[1039]: info: CoreCommandRouter::Close All Modals sent Oct 18 19:48:27 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:48:27 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:27 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 19:48:27 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:48:27 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:48:27 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:48:27 margules-magenta volumio[1039]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Oct 18 19:48:27 margules-magenta volumio[1039]: Failed to stat runtime directory /home/volumio/.config/pulse/a34096547c2268309628208d660159ea-runtime: Bad message Oct 18 19:48:27 margules-magenta kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1855: inode #20020227: comm aplay: iget: checksum invalid Oct 18 19:48:27 margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 18 19:48:27 margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 18 19:48:28 margules-magenta wpa_supplicant[762]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=1c:61:b4:79:34:83 status_code=16 Oct 18 19:48:28 margules-magenta wpa_supplicant[762]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="DECO50-4" auth_failures=1 duration=10 reason=CONN_FAILED Oct 18 19:48:28 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 18 19:48:28 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 19:48:28 margules-magenta kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1855: inode #20020227: comm aplay: iget: checksum invalid Oct 18 19:48:28 margules-magenta volumio[1039]: xcb_connection_has_error() returned true Oct 18 19:48:28 margules-magenta volumio[1039]: Failed to stat runtime directory /home/volumio/.config/pulse/a34096547c2268309628208d660159ea-runtime: Bad message Oct 18 19:48:28 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 18 19:48:31 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 19:48:31 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:48:31 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 18 19:48:31 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 19:48:31 margules-magenta volumio[1039]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Oct 18 19:48:31 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetState Oct 18 19:48:31 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:31 margules-magenta dhcpcd[881]: wlan0: carrier acquired Oct 18 19:48:32 margules-magenta dhcpcd[881]: wlan0: IAID dd:49:75:06 Oct 18 19:48:32 margules-magenta dhcpcd[881]: wlan0: adding address fe80::9755:889:6909:23dc Oct 18 19:48:32 margules-magenta dhcpcd[881]: ipv6_addaddr1: Permission denied Oct 18 19:48:32 margules-magenta kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 18 19:48:32 margules-magenta dhcpcd[881]: wlan0: soliciting an IPv6 router Oct 18 19:48:32 margules-magenta dhcpcd[881]: wlan0: soliciting a DHCP lease Oct 18 19:48:36 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:36 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 19:48:36 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 18 19:48:36 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 18 19:48:36 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 18 19:48:36 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetBrowseSources Oct 18 19:48:36 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 19:48:36 margules-magenta wpa_supplicant[762]: wlan0: Failed to initiate sched scan Oct 18 19:48:37 margules-magenta dhcpcd[881]: wlan0: probing for an IPv4LL address Oct 18 19:48:42 margules-magenta dhcpcd[881]: wlan0: using IPv4LL address 169.254.190.136 Oct 18 19:48:42 margules-magenta avahi-daemon[527]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.190.136. Oct 18 19:48:42 margules-magenta avahi-daemon[527]: New relevant interface wlan0.IPv4 for mDNS. Oct 18 19:48:42 margules-magenta avahi-daemon[527]: Registering new address record for 169.254.190.136 on wlan0.IPv4. Oct 18 19:48:42 margules-magenta dhcpcd[881]: wlan0: adding route to 169.254.0.0/16 Oct 18 19:48:43 margules-magenta ntpd[822]: Listen normally on 4 wlan0 169.254.190.136:123 Oct 18 19:48:43 margules-magenta ntpd[822]: new interface(s) found: waking up resolver Oct 18 19:48:45 margules-magenta volumio[1039]: info: Discovery: this is already registered, 0d2d32bb-403e-459f-8094-d748929d3ba8 Oct 18 19:48:45 margules-magenta volumio[1039]: info: Discovery: Found device Margules Magenta Oct 18 19:48:45 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetState Oct 18 19:48:45 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:48:45 margules-magenta wpa_supplicant[762]: wlan0: CTRL-EVENT-SSID-REENABLED id=1 ssid="DECO50-4" Oct 18 19:48:45 margules-magenta wpa_supplicant[762]: wlan0: Trying to associate with SSID 'DECO50-4' Oct 18 19:48:45 margules-magenta dhcpcd[881]: wlan0: carrier lost Oct 18 19:48:45 margules-magenta avahi-daemon[527]: Withdrawing address record for 169.254.190.136 on wlan0. Oct 18 19:48:45 margules-magenta avahi-daemon[527]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.190.136. Oct 18 19:48:45 margules-magenta avahi-daemon[527]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 18 19:48:45 margules-magenta dhcpcd[881]: wlan0: deleting route to 169.254.0.0/16 Oct 18 19:48:45 margules-magenta volumio[1039]: info: Discovery: A device disappeared from network Oct 18 19:48:46 margules-magenta ntpd[822]: Deleting interface #4 wlan0, 169.254.190.136#123, interface stats: received=0, sent=0, dropped=0, active_time=3 secs Oct 18 19:48:52 margules-magenta wpa_supplicant[762]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=1c:61:b4:79:34:83 status_code=16 Oct 18 19:48:52 margules-magenta wpa_supplicant[762]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="DECO50-4" auth_failures=2 duration=23 reason=CONN_FAILED Oct 18 19:49:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 19:49:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 19:49:00 margules-magenta volumio[1039]: info: Discovery: Getting this device information Oct 18 19:49:00 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetState Oct 18 19:49:00 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 19:49:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 19:49:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 19:49:00 margules-magenta volumio[1039]: info: Discovery: Getting this device information Oct 18 19:49:00 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetState Oct 18 19:49:00 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:00 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 19:49:01 margules-magenta volumio[1039]: verbose: New Socket.io Connection to 192.168.1.101:3000 from 192.168.1.120 UA: Dart/3.4 (dart:io) Total Clients: 11 Oct 18 19:49:02 margules-magenta volumio[1039]: verbose: New Socket.io Connection to 192.168.1.101 from 192.168.1.120 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 12 Oct 18 19:49:02 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetState Oct 18 19:49:02 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:02 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 18 19:49:02 margules-magenta volumio[1039]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 18 19:49:02 margules-magenta volumio[1039]: info: Listing playlists Oct 18 19:49:03 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 19:49:05 margules-magenta wpa_supplicant[762]: wlan0: Failed to initiate sched scan Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:49:05 margules-magenta volumio[1039]: info: Retrieving Cloud Streaming UI Oct 18 19:49:05 margules-magenta volumio[1039]: info: Getting Tidal Cloud Configuration Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:49:05 margules-magenta volumio[1039]: info: Getting Qobuz Cloud Configuration Oct 18 19:49:05 margules-magenta volumio[1039]: info: Asking plugin for UI Config Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:49:05 margules-magenta volumio[1039]: info: Getting Spotify Cloud Configuration Oct 18 19:49:05 margules-magenta volumio[1039]: info: Asking plugin for UI Config Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:49:05 margules-magenta volumio[1039]: info: Saving Spotify Acccount Oct 18 19:49:05 margules-magenta volumio[1039]: info: Got it Oct 18 19:49:05 margules-magenta volumio[1039]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 18 19:49:05 margules-magenta volumio[1039]: info: Got Tidal Cloud Configuration Oct 18 19:49:05 margules-magenta volumio[1039]: info: Got it Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetBrowseSources Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetBrowseSources Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetBrowseSources Oct 18 19:49:05 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:49:06 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: ControllerMpd::sendMpdCommand rescan Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:49:10 margules-magenta volumio[1039]: info: Oct 18 19:49:10 margules-magenta volumio[1039]: ---------------------------- MPD announces state update: update Oct 18 19:49:10 margules-magenta volumio[1039]: info: sendMpdCommand rescan took 5 milliseconds Oct 18 19:49:10 margules-magenta volumio[1039]: info: ControllerMpd::getState Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:49:10 margules-magenta volumio[1039]: info: Oct 18 19:49:10 margules-magenta volumio[1039]: ---------------------------- MPD announces state update: update Oct 18 19:49:10 margules-magenta volumio[1039]: info: ControllerMpd::getState Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:49:10 margules-magenta volumio[1039]: info: Oct 18 19:49:10 margules-magenta volumio[1039]: ---------------------------- MPD announces state update: update Oct 18 19:49:10 margules-magenta volumio[1039]: info: ControllerMpd::getState Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:49:10 margules-magenta volumio[1039]: info: sendMpdCommand status took 13 milliseconds Oct 18 19:49:10 margules-magenta volumio[1039]: info: sendMpdCommand status took 10 milliseconds Oct 18 19:49:10 margules-magenta volumio[1039]: info: sendMpdCommand status took 9 milliseconds Oct 18 19:49:10 margules-magenta volumio[1039]: info: sendMpdCommand status took 9 milliseconds Oct 18 19:49:10 margules-magenta volumio[1039]: info: sendMpdCommand status took 8 milliseconds Oct 18 19:49:10 margules-magenta volumio[1039]: info: sendMpdCommand status took 7 milliseconds Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: ControllerMpd::parseState Oct 18 19:49:10 margules-magenta volumio[1039]: info: Command Router : Notfying DB Updatetrue Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: ControllerMpd::parseState Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: ControllerMpd::parseState Oct 18 19:49:10 margules-magenta volumio[1039]: info: Command Router : Notfying DB Updatetrue Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::Close All Modals sent Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: ControllerMpd::parseState Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: ControllerMpd::parseState Oct 18 19:49:10 margules-magenta volumio[1039]: info: Command Router : Notfying DB Updatetrue Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::Close All Modals sent Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: ControllerMpd::parseState Oct 18 19:49:10 margules-magenta volumio[1039]: info: ControllerMpd::pushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::servicePushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:49:10 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: CURRENT POSITION 0 Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::syncState stateService stop Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:49:10 margules-magenta volumio[1039]: info: No code Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:49:10 margules-magenta volumio[1039]: info: ControllerMpd::pushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::servicePushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:49:10 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: CURRENT POSITION 0 Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::syncState stateService stop Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:49:10 margules-magenta volumio[1039]: info: No code Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:49:10 margules-magenta volumio[1039]: info: ControllerMpd::pushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::servicePushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:49:10 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 18 19:49:10 margules-magenta volumio[1039]: verbose: CURRENT POSITION 0 Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::syncState stateService stop Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:49:10 margules-magenta volumio[1039]: info: No code Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreStateMachine::pushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:10 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioPushState Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:49:10 margules-magenta volumio[1039]: info: MRS: Pushing multiroomSync output Oct 18 19:49:10 margules-magenta volumio[1039]: info: ------------------------------ 172ms Oct 18 19:49:10 margules-magenta volumio[1039]: info: ------------------------------ 168ms Oct 18 19:49:10 margules-magenta volumio[1039]: info: ------------------------------ 167ms Oct 18 19:49:14 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:49:14 margules-magenta wpa_supplicant[762]: wlan0: Failed to initiate sched scan Oct 18 19:49:17 margules-magenta volumio[1039]: info: MyVolumio status changed Oct 18 19:49:17 margules-magenta volumio[1039]: info: Streaming services startup Oct 18 19:49:17 margules-magenta volumio[1039]: info: Starting Streaming Daemon Oct 18 19:49:17 margules-magenta volumio[1039]: info: Removing browser output: myVolumio user plan is not superstar Oct 18 19:49:17 margules-magenta volumio[1039]: info: Removing audio output: Oct 18 19:49:17 margules-magenta volumio[1039]: info: Stoppping Tunnel 1 Oct 18 19:49:17 margules-magenta sudo[1621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 18 19:49:17 margules-magenta sudo[1621]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:49:17 margules-magenta sudo[1621]: pam_unix(sudo:session): session closed for user root Oct 18 19:49:17 margules-magenta volumio-remote-updater[521]: No test mode Oct 18 19:49:17 margules-magenta volumio-remote-updater[521]: No alpha test mode Oct 18 19:49:17 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 18 19:49:17 margules-magenta sudo[1624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 18 19:49:17 margules-magenta sudo[1624]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:49:17 margules-magenta volumio[1039]: error: Cannot start Volumio Streaming Daemon Oct 18 19:49:17 margules-magenta volumio[1039]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 18 19:49:17 margules-magenta volumio[1039]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 18 19:49:17 margules-magenta sudo[1624]: pam_unix(sudo:session): session closed for user root Oct 18 19:49:17 margules-magenta volumio[1039]: info: Remote SSH Stopped Oct 18 19:49:17 margules-magenta volumio[1039]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 18 19:49:17 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 18 19:49:18 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:49:18 margules-magenta volumio[1039]: info: Setting Geolocation for MyVolumio to eu7 Oct 18 19:49:18 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:49:18 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:49:18 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:49:18 margules-magenta volumio[1039]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 18 19:49:19 margules-magenta volumio[1039]: info: Updating MyVolumio device info Oct 18 19:49:19 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:49:19 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetVisibleSources Oct 18 19:49:19 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 19:49:19 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetQueue Oct 18 19:49:19 margules-magenta volumio[1039]: info: CoreStateMachine::getQueue Oct 18 19:49:19 margules-magenta volumio[1039]: info: CorePlayQueue::getQueue Oct 18 19:49:19 margules-magenta volumio[1039]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 18 19:49:22 margules-magenta volumio[1039]: info: MYVOLUMIO: Adding device Oct 18 19:49:22 margules-magenta volumio[1039]: info: MYVOLUMIO: Evaluating Server Oct 18 19:49:22 margules-magenta wpa_supplicant[762]: wlan0: CTRL-EVENT-SSID-REENABLED id=1 ssid="DECO50-4" Oct 18 19:49:22 margules-magenta wpa_supplicant[762]: wlan0: Trying to associate with SSID 'DECO50-4' Oct 18 19:49:23 margules-magenta volumio[1039]: info: Setting Geolocation for MyVolumio to eu7 Oct 18 19:49:23 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:49:23 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 19:49:23 margules-magenta volumio[1039]: info: Preload queue cleared Oct 18 19:49:24 margules-magenta volumio[1039]: info: Updating MyVolumio device info Oct 18 19:49:24 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 19:49:24 margules-magenta volumio[1039]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 18 19:49:24 margules-magenta volumio[1039]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 18 19:49:24 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 19:49:25 margules-magenta volumio[1039]: info: Preload queue cleared Oct 18 19:49:28 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 19:49:28 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 19:49:28 margules-magenta volumio[1039]: info: Discovery: Getting this device information Oct 18 19:49:28 margules-magenta volumio[1039]: info: CoreCommandRouter::volumioGetState Oct 18 19:49:28 margules-magenta volumio[1039]: info: CorePlayQueue::getTrack 0 Oct 18 19:49:28 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 19:49:29 margules-magenta wpa_supplicant[762]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=1c:61:b4:79:34:83 status_code=16 Oct 18 19:49:29 margules-magenta wpa_supplicant[762]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="DECO50-4" auth_failures=3 duration=46 reason=CONN_FAILED Oct 18 19:49:33 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 19:49:33 margules-magenta volumio[1039]: info: Preload queue cleared Oct 18 19:49:37 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 19:49:38 margules-magenta volumio[1039]: info: Preload queue cleared Oct 18 19:49:40 margules-magenta volumio[1039]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 18 19:49:40 margules-magenta volumio[1039]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 19:49:40 margules-magenta volumio[1039]: TypeError: Cannot read property 'length' of undefined Oct 18 19:49:40 margules-magenta volumio[1039]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 18 19:49:40 margules-magenta volumio[1039]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 18 19:49:40 margules-magenta volumio[1039]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 18 19:49:40 margules-magenta volumio[1039]: at Parser.emit (events.js:315:20) Oct 18 19:49:40 margules-magenta volumio[1039]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 18 19:49:40 margules-magenta volumio[1039]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 18 19:49:40 margules-magenta volumio[1039]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 18 19:49:40 margules-magenta volumio[1039]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 18 19:49:40 margules-magenta volumio[1039]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 18 19:49:40 margules-magenta volumio[1039]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 18 19:49:40 margules-magenta volumio[1039]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 18 19:49:40 margules-magenta volumio[1039]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 18 19:49:40 margules-magenta volumio[1039]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 18 19:49:40 margules-magenta volumio[1039]: at IncomingMessage.emit (events.js:327:22) Oct 18 19:49:40 margules-magenta volumio[1039]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 18 19:49:40 margules-magenta volumio[1039]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 18 19:49:40 margules-magenta volumio[1039]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 19:49:40 margules-magenta sudo[1697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-18 19:48 Oct 18 19:49:40 margules-magenta sudo[1697]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="margules" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 25 Mar 2024 12:05:08 PM CET" VOLUMIO_VERSION="3.609" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_VENDOR_MODEL="Margules Magenta" VOLUMIO_VENDOR="Margules" VOLUMIO_MODEL="Margules Magenta" VOLUMIO_HASH="1fe05d36c107355d439846423b3dc483"