-- Logs begin at Thu 2019-02-14 20:12:00 AEST, end at Sat 2024-08-17 19:15:37 AEST. -- Aug 17 19:14:00 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 17 19:14:00 musicberry volumio[894]: info: Received Get System Info Aug 17 19:14:00 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:14:00 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:14:00 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:14:00 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:14:00 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:00 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:14:02 musicberry volumio[894]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 17 19:14:02 musicberry volumio[894]: info: Completed starting MyVolumio Plugin Aug 17 19:14:02 musicberry volumio[894]: info: BOOT COMPLETED Aug 17 19:14:02 musicberry volumio[894]: [Metrics] CommandRouter: 80s 831.88ms Aug 17 19:14:02 musicberry volumio[894]: info: CoreCommandRouter::volumiosetStartupVolume Aug 17 19:14:02 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 19:14:02 musicberry volumio[894]: info: VolumeController:: Setting startup Volume 15 Aug 17 19:14:02 musicberry volumio[894]: info: VolumeController::SetAlsaVolume15 Aug 17 19:14:02 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 19:14:02 musicberry volumio[894]: info: CoreCommandRouter::Close All Modals sent Aug 17 19:14:02 musicberry volumio[894]: info: CoreCommandRouter::Close All Modals sent Aug 17 19:14:02 musicberry volumio[894]: info: CoreStateMachine::pushState Aug 17 19:14:02 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:02 musicberry volumio[894]: info: CoreCommandRouter::volumioPushState Aug 17 19:14:02 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 17 19:14:03 musicberry kernel: usb 1-1: USB disconnect, device number 2 Aug 17 19:14:03 musicberry volumio[894]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Aug 17 19:14:03 musicberry volumio[894]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 17 19:14:03 musicberry volumio[894]: aplay: pcm_write:2053: write error: Input/output error Aug 17 19:14:03 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 17 19:14:03 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 17 19:14:04 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 17 19:14:04 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 17 19:14:04 musicberry kernel: Indeed it is in host mode hprt0 = 00021501 Aug 17 19:14:04 musicberry kernel: usb 1-1: new high-speed USB device number 3 using dwc_otg Aug 17 19:14:04 musicberry kernel: Indeed it is in host mode hprt0 = 00001101 Aug 17 19:14:04 musicberry kernel: usb 1-1: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.05 Aug 17 19:14:04 musicberry kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Aug 17 19:14:04 musicberry kernel: usb 1-1: Product: E50 Aug 17 19:14:04 musicberry kernel: usb 1-1: Manufacturer: Topping Aug 17 19:14:04 musicberry kernel: usb 1-1: 1:3 : unsupported format bits 0x100000000 Aug 17 19:14:04 musicberry kernel: hid-generic 0003:152A:8750.0002: hiddev96,hidraw0: USB HID v1.10 Device [Topping E50] on usb-3f980000.usb-1/input3 Aug 17 19:14:05 musicberry volumio[894]: info: Aug 17 19:14:05 musicberry volumio[894]: ---------------------------- USB Audio Device Detached Aug 17 19:14:05 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Aug 17 19:14:05 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 17 19:14:08 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 17 19:14:09 musicberry volumio[894]: info: Aug 17 19:14:09 musicberry volumio[894]: ---------------------------- USB Audio Device Attached Aug 17 19:14:09 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Aug 17 19:14:09 musicberry volumio[894]: info: CoreCommandRouter::Close All Modals sent Aug 17 19:14:09 musicberry volumio[894]: info: Preparing to save Alsa Options, stopping services first Aug 17 19:14:09 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:14:09 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:09 musicberry volumio[894]: info: CoreCommandRouter::volumioPause Aug 17 19:14:09 musicberry volumio[894]: info: CoreStateMachine::pause Aug 17 19:14:09 musicberry volumio[894]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"E50","alsacard":"E50"},"i2s":false} Aug 17 19:14:09 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 17 19:14:09 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 17 19:14:09 musicberry volumio[894]: info: Device 5 does not have any Mixer Control Available Aug 17 19:14:09 musicberry volumio[894]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 17 19:14:09 musicberry volumio[894]: info: Updating Volume Controller Parameters: Device: 5 Name: E50 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Aug 17 19:14:09 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 17 19:14:09 musicberry volumio[894]: info: Disabling external Volume Control Aug 17 19:14:09 musicberry volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 19:14:09 musicberry volumio[894]: info: CoreStateMachine::pushState Aug 17 19:14:09 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:09 musicberry volumio[894]: info: CoreCommandRouter::volumioPushState Aug 17 19:14:10 musicberry volumio[894]: info: Preparing to generate the ALSA configuration file Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 17 19:14:10 musicberry volumio[894]: info: Asound.conf file unchanged, so no further update is needed Aug 17 19:14:10 musicberry volumio[894]: info: Output device has changed, restarting MPD Aug 17 19:14:10 musicberry volumio[894]: info: Output device has changed, restarting Shairport Sync Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 19:14:10 musicberry volumio[894]: info: Starting Shairport Sync Aug 17 19:14:10 musicberry sudo[1709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 17 19:14:10 musicberry sudo[1707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 17 19:14:10 musicberry volumio[894]: info: MPD Permissions set Aug 17 19:14:10 musicberry sudo[1707]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 19:14:10 musicberry sudo[1709]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 19:14:10 musicberry sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 17 19:14:10 musicberry sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 19:14:10 musicberry sudo[1707]: pam_unix(sudo:session): session closed for user root Aug 17 19:14:11 musicberry systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 17 19:14:11 musicberry systemd[1]: Stopping Music Player Daemon... Aug 17 19:14:11 musicberry systemd[1]: shairport-sync.service: Succeeded. Aug 17 19:14:11 musicberry systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 17 19:14:11 musicberry systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 17 19:14:11 musicberry sudo[1718]: pam_unix(sudo:session): session closed for user root Aug 17 19:14:11 musicberry volumio[894]: info: Shairport-Sync Started Aug 17 19:14:12 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 17 19:14:12 musicberry volumio[894]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 17 19:14:12 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 17 19:14:12 musicberry volumio[894]: info: Received Get System Version Aug 17 19:14:12 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 17 19:14:12 musicberry volumio[894]: info: Received Get System Info Aug 17 19:14:12 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:14:12 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:14:12 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:14:12 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:14:12 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:12 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:14:13 musicberry volumio[894]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 17 19:14:13 musicberry volumio[894]: info: CoreStateMachine::getcurrentVolume Aug 17 19:14:13 musicberry volumio[894]: info: CoreCommandRouter::volumioRetrievevolume Aug 17 19:14:13 musicberry volumio[894]: info: CoreStateMachine::pushState Aug 17 19:14:15 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:15 musicberry volumio[894]: info: CoreCommandRouter::volumioPushState Aug 17 19:14:16 musicberry systemd[1]: mpd.service: Succeeded. Aug 17 19:14:16 musicberry systemd[1]: Stopped Music Player Daemon. Aug 17 19:14:16 musicberry systemd[1]: Starting Music Player Daemon... Aug 17 19:14:16 musicberry sudo[1725]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 17 19:14:16 musicberry sudo[1725]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 19:14:16 musicberry sudo[1725]: pam_unix(sudo:session): session closed for user root Aug 17 19:14:19 musicberry mpd[1727]: Aug 17 19:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 17 19:14:19 musicberry systemd[1]: Started Music Player Daemon. Aug 17 19:14:19 musicberry sudo[1709]: pam_unix(sudo:session): session closed for user root Aug 17 19:14:19 musicberry volumio[894]: error: updateQueue error: null Aug 17 19:14:37 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:14:37 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:14:37 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:14:37 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:14:37 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:37 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:14:37 musicberry volumio[894]: verbose: New Socket.io Connection to 192.168.1.166:3000 from 192.168.1.29 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Aug 17 19:14:38 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:14:38 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:38 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 17 19:14:38 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 17 19:14:44 musicberry volumio[894]: error: error Aug 17 19:14:45 musicberry sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 17 19:14:45 musicberry sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 17 19:14:45 musicberry sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 19:14:45 musicberry sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 19:14:45 musicberry sudo[1779]: pam_unix(sudo:session): session closed for user root Aug 17 19:14:47 musicberry sudo[1777]: pam_unix(sudo:session): session closed for user root Aug 17 19:14:48 musicberry volumio[894]: verbose: New Socket.io Connection to 192.168.1.166 from 192.168.1.29 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 8 Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 17 19:14:48 musicberry sudo[1785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 17 19:14:48 musicberry sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 17 19:14:48 musicberry sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 19:14:48 musicberry sudo[1785]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 19:14:48 musicberry sudo[1787]: pam_unix(sudo:session): session closed for user root Aug 17 19:14:48 musicberry sudo[1785]: pam_unix(sudo:session): session closed for user root Aug 17 19:14:48 musicberry volumio[894]: verbose: New Socket.io Connection to 192.168.1.166 from 192.168.1.29 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 9 Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::volumioGetVisibleSources Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:14:48 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 17 19:14:48 musicberry volumio[894]: info: Received Get System Info Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:14:48 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:14:48 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:14:48 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:48 musicberry volumio[894]: info: Listing playlists Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 17 19:14:48 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 17 19:14:49 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:14:49 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:50 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 17 19:14:50 musicberry volumio[894]: info: Received Get System Info Aug 17 19:14:50 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:14:50 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:14:50 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:14:50 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:14:50 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:50 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:14:50 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 17 19:14:50 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 17 19:14:50 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:14:50 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:14:50 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:14:50 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:14:50 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:50 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:14:51 musicberry volumio[894]: info: CoreCommandRouter::volumioGetQueue Aug 17 19:14:51 musicberry volumio[894]: info: CoreStateMachine::getQueue Aug 17 19:14:51 musicberry volumio[894]: info: CorePlayQueue::getQueue Aug 17 19:14:52 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 17 19:14:52 musicberry volumio[894]: info: Received Get System Info Aug 17 19:14:52 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:14:52 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:14:52 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:14:52 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:14:52 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:52 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:14:54 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 17 19:14:54 musicberry volumio[894]: info: Received Get System Info Aug 17 19:14:54 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:14:54 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:14:54 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:14:54 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:14:54 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:14:54 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:14:55 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 17 19:14:56 musicberry volumio[894]: info: Preload queue cleared Aug 17 19:14:58 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 17 19:15:02 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:15:02 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:15:02 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:15:02 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:15:02 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:15:02 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:15:03 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 17 19:15:03 musicberry volumio[894]: info: Preload queue cleared Aug 17 19:15:05 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 17 19:15:05 musicberry volumio[894]: info: Preload queue cleared Aug 17 19:15:08 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 17 19:15:08 musicberry volumio[894]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 17 19:15:08 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 17 19:15:08 musicberry volumio[894]: info: Received Get System Version Aug 17 19:15:08 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 17 19:15:08 musicberry volumio[894]: info: Received Get System Info Aug 17 19:15:08 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:15:08 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:15:08 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:15:08 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:15:08 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:15:08 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:15:22 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:15:22 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:15:22 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:15:22 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:15:22 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:15:22 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:15:22 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:15:22 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:15:22 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:15:22 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:15:22 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:15:22 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:15:23 musicberry volumio[894]: verbose: New Socket.io Connection to 192.168.1.166:3000 from 192.168.1.29 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Aug 17 19:15:23 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:15:23 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:15:23 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 17 19:15:23 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 17 19:15:25 musicberry sudo[1840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 17 19:15:25 musicberry sudo[1840]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 19:15:25 musicberry sudo[1843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 17 19:15:25 musicberry sudo[1840]: pam_unix(sudo:session): session closed for user root Aug 17 19:15:25 musicberry sudo[1843]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 19:15:25 musicberry sudo[1843]: pam_unix(sudo:session): session closed for user root Aug 17 19:15:25 musicberry volumio[894]: verbose: New Socket.io Connection to 192.168.1.166 from 192.168.1.29 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 8 Aug 17 19:15:25 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 17 19:15:26 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 17 19:15:27 musicberry sudo[1848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 17 19:15:27 musicberry sudo[1848]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 19:15:27 musicberry sudo[1851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 17 19:15:27 musicberry sudo[1851]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 19:15:27 musicberry sudo[1848]: pam_unix(sudo:session): session closed for user root Aug 17 19:15:27 musicberry sudo[1851]: pam_unix(sudo:session): session closed for user root Aug 17 19:15:27 musicberry volumio[894]: verbose: New Socket.io Connection to 192.168.1.166 from 192.168.1.29 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 9 Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::volumioGetVisibleSources Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:15:27 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::volumioGetQueue Aug 17 19:15:27 musicberry volumio[894]: info: CoreStateMachine::getQueue Aug 17 19:15:27 musicberry volumio[894]: info: CorePlayQueue::getQueue Aug 17 19:15:27 musicberry volumio[894]: info: Listing playlists Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 17 19:15:27 musicberry volumio[894]: info: Received Get System Info Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:15:27 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:15:27 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:15:27 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 17 19:15:27 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 17 19:15:28 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 17 19:15:29 musicberry volumio[894]: info: Preload queue cleared Aug 17 19:15:29 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 17 19:15:29 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 17 19:15:29 musicberry volumio[894]: info: Received Get System Info Aug 17 19:15:29 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:15:29 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:15:29 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:15:29 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:15:29 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:15:29 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:15:30 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 17 19:15:30 musicberry volumio[894]: info: Received Get System Info Aug 17 19:15:30 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 19:15:30 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 19:15:30 musicberry volumio[894]: info: Discovery: Getting this device information Aug 17 19:15:30 musicberry volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 19:15:30 musicberry volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 19:15:30 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 19:15:34 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 17 19:15:34 musicberry volumio[894]: info: Preload queue cleared Aug 17 19:15:36 musicberry volumio[894]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 17 19:15:36 musicberry volumio[894]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 19:15:36 musicberry volumio[894]: TypeError: Cannot read property 'length' of undefined Aug 17 19:15:36 musicberry volumio[894]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 17 19:15:36 musicberry volumio[894]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 17 19:15:36 musicberry volumio[894]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 17 19:15:36 musicberry volumio[894]: at Parser.emit (events.js:315:20) Aug 17 19:15:36 musicberry volumio[894]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 17 19:15:36 musicberry volumio[894]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 17 19:15:36 musicberry volumio[894]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 17 19:15:36 musicberry volumio[894]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 17 19:15:36 musicberry volumio[894]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 17 19:15:36 musicberry volumio[894]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 17 19:15:36 musicberry volumio[894]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 17 19:15:36 musicberry volumio[894]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 17 19:15:36 musicberry volumio[894]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 17 19:15:36 musicberry volumio[894]: at IncomingMessage.emit (events.js:327:22) Aug 17 19:15:36 musicberry volumio[894]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 17 19:15:36 musicberry volumio[894]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 17 19:15:36 musicberry volumio[894]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 19:15:37 musicberry sudo[1870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-17 19:14 Aug 17 19:15:37 musicberry sudo[1870]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"