-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Mon 2024-07-22 19:28:33 CST. -- Jul 22 19:27:00 volumiodev volumio[4901]: info: Loading plugin "upnp_browser"... Jul 22 19:27:01 volumiodev volumio[4901]: info: Loading plugin "networkfs"... Jul 22 19:27:02 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 22 19:27:02 volumiodev volumio[4901]: info: Loading plugin "alarm-clock"... Jul 22 19:27:02 volumiodev volumio[4901]: info: Loading plugin "airplay_emulation"... Jul 22 19:27:02 volumiodev volumio[4901]: info: Starting Shairport Sync Jul 22 19:27:02 volumiodev volumio[4901]: info: Loading plugin "last_100"... Jul 22 19:27:02 volumiodev volumio[4901]: info: Loading plugin "webradio"... Jul 22 19:27:03 volumiodev volumio[4901]: info: Plugin youtube2 is not enabled Jul 22 19:27:03 volumiodev volumio[4901]: info: Loading plugin "i2s_dacs"... Jul 22 19:27:03 volumiodev volumio[4901]: info: I2S DAC not set, start Auto-detection Jul 22 19:27:03 volumiodev volumio[4901]: info: Loading plugin "volumiodiscovery"... Jul 22 19:27:03 volumiodev volumio[4901]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 22 19:27:03 volumiodev volumio[4901]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 22 19:27:03 volumiodev node[4901]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 22 19:27:03 volumiodev volumio[4901]: *** WARNING *** For more information see Jul 22 19:27:03 volumiodev volumio[4901]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 22 19:27:03 volumiodev volumio[4901]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 22 19:27:03 volumiodev volumio[4901]: *** WARNING *** For more information see Jul 22 19:27:03 volumiodev node[4901]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 22 19:27:03 volumiodev node[4901]: *** WARNING *** For more information see Jul 22 19:27:03 volumiodev node[4901]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 22 19:27:03 volumiodev node[4901]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 22 19:27:03 volumiodev node[4901]: *** WARNING *** For more information see Jul 22 19:27:03 volumiodev volumio[4901]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 22 19:27:03 volumiodev volumio[4901]: Discovery: StartAdv! undefined Jul 22 19:27:03 volumiodev volumio[4901]: Discovery: Started advertising... Volumiodev - undefined Jul 22 19:27:03 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 22 19:27:03 volumiodev volumio[4901]: info: Loading plugin "outputs"... Jul 22 19:27:03 volumiodev volumio[4901]: info: Loading plugin "albumart"... Jul 22 19:27:03 volumiodev volumio[4901]: info: Plugin example_plugin is not enabled Jul 22 19:27:03 volumiodev volumio[4901]: info: Loading plugin "inputs"... Jul 22 19:27:03 volumiodev volumio[4901]: info: Loading plugin "pandora"... Jul 22 19:27:04 volumiodev volumio[4901]: info: Applying required configuration parameters for plugin pandora Jul 22 19:27:04 volumiodev volumio[4901]: info: Loading plugin "qobuz"... Jul 22 19:27:04 volumiodev wpa_supplicant[4132]: wlan0: CTRL-EVENT-BEACON-LOSS Jul 22 19:27:04 volumiodev volumio[4901]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Jul 22 19:27:04 volumiodev volumio[4901]: info: Loading plugin "tidal"... Jul 22 19:27:04 volumiodev volumio[4901]: info: [1721647624513] ControllerTidalPlugin::constructor Jul 22 19:27:04 volumiodev volumio[4901]: info: [1721647624514] ControllerTidalPlugin::onVolumioStart Jul 22 19:27:04 volumiodev volumio[4901]: info: Loading plugin "volspotconnect2"... Jul 22 19:27:06 volumiodev volumio[4901]: [SpotifyConnect2] Creating VLS config file Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 19:27:06 volumiodev volumio[4901]: info: Loading plugin "updater_comm"... Jul 22 19:27:06 volumiodev volumio[4901]: info: Plugin mpdemulation is not enabled Jul 22 19:27:06 volumiodev volumio[4901]: info: Loading plugin "rest_api"... Jul 22 19:27:06 volumiodev volumio[4901]: info: Loading plugin "websocket"... Jul 22 19:27:06 volumiodev volumio[4901]: info: ___________ START PLUGINS ___________ Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 22 19:27:06 volumiodev volumio[4901]: info: [1721647626691] CoreMusicLibrary::Adding element Media Servers Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 19:27:06 volumiodev volumio[4901]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 22 19:27:06 volumiodev volumio[4901]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 22 19:27:06 volumiodev volumio[4901]: info: [1721647626849] CoreMusicLibrary::Adding element Last_100 Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 22 19:27:06 volumiodev volumio[4901]: info: [1721647626859] CoreMusicLibrary::Adding element Webradio Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 22 19:27:06 volumiodev volumio[4901]: info: [1721647626883] CoreMusicLibrary::Adding element Pandora Radio Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 19:27:06 volumiodev volumio[4901]: info: [1721647626888] ControllerPandora::initializeMQTT Jul 22 19:27:06 volumiodev volumio[4901]: info: [1721647626890] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 22 19:27:06 volumiodev volumio[4901]: info: [1721647626892] CoreMusicLibrary::Adding element Qobuz Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 19:27:06 volumiodev volumio[4901]: info: [1721647626903] ControllerTidalPlugin::onStart Jul 22 19:27:06 volumiodev volumio[4901]: info: [1721647626905] ControllerTidalPlugin::addToBrowseSources Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 22 19:27:06 volumiodev volumio[4901]: info: [1721647626907] CoreMusicLibrary::Adding element Tidal Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 19:27:06 volumiodev volumio[4901]: [SpotifyConnect2] Creating VLS config file Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 19:27:06 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 19:27:06 volumiodev volumio[4901]: [SpotifyConnect2] Starting metadata listener Jul 22 19:27:06 volumiodev volumio[4901]: info: Loading i18n strings for locale en Jul 22 19:27:07 volumiodev volumio[4901]: Updating browse sources language Jul 22 19:27:07 volumiodev volumio[4901]: Cannot find translation for sourceMedia Servers Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 19:27:07 volumiodev sudo[4981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 22 19:27:07 volumiodev sudo[4981]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::initPlayerControls Jul 22 19:27:07 volumiodev sudo[4981]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 19:27:07 volumiodev volumio[4901]: info: BOOT COMPLETED Jul 22 19:27:07 volumiodev volumio[4901]: [Metrics] CommandRouter: 17s 374.52ms Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 22 19:27:07 volumiodev kernel: [SNDCODEC][sunxi_card_hw_params][614]:invalid rate setting Jul 22 19:27:07 volumiodev kernel: sunxi-codec-machine 2030340.sound: ASoC: machine hw_params failed: -22 Jul 22 19:27:07 volumiodev volumio[4901]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jul 22 19:27:07 volumiodev volumio[4901]: aplay: set_params:1432: Unable to install hw params: Jul 22 19:27:07 volumiodev volumio[4901]: ACCESS: RW_INTERLEAVED Jul 22 19:27:07 volumiodev volumio[4901]: FORMAT: S16_LE Jul 22 19:27:07 volumiodev volumio[4901]: SUBFORMAT: STD Jul 22 19:27:07 volumiodev volumio[4901]: SAMPLE_BITS: 16 Jul 22 19:27:07 volumiodev volumio[4901]: FRAME_BITS: 32 Jul 22 19:27:07 volumiodev volumio[4901]: CHANNELS: 2 Jul 22 19:27:07 volumiodev volumio[4901]: RATE: 44100 Jul 22 19:27:07 volumiodev volumio[4901]: PERIOD_TIME: (92879 92880) Jul 22 19:27:07 volumiodev volumio[4901]: PERIOD_SIZE: (4095 4096] Jul 22 19:27:07 volumiodev volumio[4901]: PERIOD_BYTES: (16380 16384] Jul 22 19:27:07 volumiodev volumio[4901]: PERIODS: [4 5) Jul 22 19:27:07 volumiodev volumio[4901]: BUFFER_TIME: (371519 371520) Jul 22 19:27:07 volumiodev volumio[4901]: BUFFER_SIZE: 16384 Jul 22 19:27:07 volumiodev volumio[4901]: BUFFER_BYTES: 65536 Jul 22 19:27:07 volumiodev volumio[4901]: TICK_TIME: 0 Jul 22 19:27:07 volumiodev volumio[4901]: Cannot play startup sound Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::Close All Modals sent Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::Close All Modals sent Jul 22 19:27:07 volumiodev volumio[4901]: Express server listening on port 3000 Jul 22 19:27:07 volumiodev volumio[4901]: [Metrics] WebUI: 18s 689.90ms Jul 22 19:27:07 volumiodev volumio[4901]: info: [1721647627479] ControllerPandora::validateAndSetAccountOptions Jul 22 19:27:07 volumiodev volumio[4901]: error: [1721647627481] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreStateMachine::resetVolumioState Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreStateMachine::getcurrentVolume Jul 22 19:27:07 volumiodev volumio[4901]: info: CoreCommandRouter::volumioRetrievevolume Jul 22 19:27:07 volumiodev volumio[4901]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 22 19:27:07 volumiodev volumio[4901]: Unable to parse: Jul 22 19:27:07 volumiodev volumio[4901]: Simple mixer control 'Headphone',0 Jul 22 19:27:07 volumiodev volumio[4901]: Capabilities: pswitch pswitch-joined Jul 22 19:27:07 volumiodev volumio[4901]: Playback channels: Mono Jul 22 19:27:07 volumiodev volumio[4901]: Mono: Playback [on] Jul 22 19:27:07 volumiodev volumio[4901]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 22 19:27:07 volumiodev volumio[4901]: [SpotifyConnect2] Error starting SpotifyConnect Error: Command failed: /usr/bin/sudo /bin/systemctl start volspotconnect2.service Jul 22 19:27:07 volumiodev volumio[4901]: Failed to start volspotconnect2.service: Unit volspotconnect2.service not found. Jul 22 19:27:07 volumiodev volumio[4901]: at ChildProcess.exithandler (child_process.js:294:12) Jul 22 19:27:07 volumiodev volumio[4901]: at ChildProcess.emit (events.js:198:13) Jul 22 19:27:07 volumiodev volumio[4901]: at maybeClose (internal/child_process.js:982:16) Jul 22 19:27:07 volumiodev volumio[4901]: at Socket.stream.socket.on (internal/child_process.js:389:11) Jul 22 19:27:07 volumiodev volumio[4901]: at Socket.emit (events.js:198:13) Jul 22 19:27:07 volumiodev volumio[4901]: at Pipe._handle.close (net.js:607:12) Jul 22 19:27:07 volumiodev volumio[4901]: [Metrics] SpotifyConnect: 0s 967.42ms Jul 22 19:27:07 volumiodev volumio[4901]: info: MPD running with PID2335 Jul 22 19:27:07 volumiodev volumio[4901]: ,establishing connection Jul 22 19:27:08 volumiodev volumio[4901]: info: Permissions for /etc/hostapd/hostapd.conf Jul 22 19:27:08 volumiodev sudo[5009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jul 22 19:27:08 volumiodev sudo[5009]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:08 volumiodev volumio[4901]: Unable to parse: Jul 22 19:27:08 volumiodev volumio[4901]: Simple mixer control 'Headphone',0 Jul 22 19:27:08 volumiodev volumio[4901]: Capabilities: pswitch pswitch-joined Jul 22 19:27:08 volumiodev volumio[4901]: Playback channels: Mono Jul 22 19:27:08 volumiodev volumio[4901]: Mono: Playback [on] Jul 22 19:27:08 volumiodev volumio[4901]: info: VolumeController:: Volume=undefined Mute =false Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreStateMachine::pushState Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreCommandRouter::volumioPushState Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreStateMachine::updateTrackBlock Jul 22 19:27:08 volumiodev volumio[4901]: info: CorePlayQueue::getTrackBlock Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreCommandRouter::volumioRetrievevolume Jul 22 19:27:08 volumiodev systemd[1]: Stopping Wireless Services... Jul 22 19:27:08 volumiodev kernel: wlan0: deauthenticating from 00:90:4c:71:e1:6a by local choice (Reason: 3=DEAUTH_LEAVING) Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 22 19:27:08 volumiodev hostapd[2345]: wlan0: STA 00:90:4c:71:e1:6a IEEE 802.11: disassociated Jul 22 19:27:08 volumiodev wpa_supplicant[4132]: wlan0: CTRL-EVENT-DISCONNECTED bssid=00:90:4c:71:e1:6a reason=3 locally_generated=1 Jul 22 19:27:08 volumiodev systemd-networkd[2072]: wlan0: Lost carrier Jul 22 19:27:08 volumiodev systemd-networkd[2072]: wlan0: DHCP lease lost Jul 22 19:27:08 volumiodev systemd-timesyncd[2062]: No network connectivity, watching for changes. Jul 22 19:27:08 volumiodev avahi-daemon[2100]: Withdrawing address record for 192.168.100.106 on wlan0. Jul 22 19:27:08 volumiodev avahi-daemon[2100]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.106. Jul 22 19:27:08 volumiodev avahi-daemon[2100]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 22 19:27:08 volumiodev wpa_supplicant[4132]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 22 19:27:08 volumiodev volumio[4901]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Jul 22 19:27:08 volumiodev volumio[4901]: sudo: /usr/sbin/i2cdetect: command not found Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 22 19:27:08 volumiodev volumio[4901]: info: Reloading queue from file Jul 22 19:27:08 volumiodev volumio[4901]: error: updateQueue error: null Jul 22 19:27:08 volumiodev volumio[4901]: Unable to parse: Jul 22 19:27:08 volumiodev volumio[4901]: Simple mixer control 'Headphone',0 Jul 22 19:27:08 volumiodev volumio[4901]: Capabilities: pswitch pswitch-joined Jul 22 19:27:08 volumiodev volumio[4901]: Playback channels: Mono Jul 22 19:27:08 volumiodev volumio[4901]: Mono: Playback [on] Jul 22 19:27:08 volumiodev volumio[4901]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 22 19:27:08 volumiodev volumio[4999]: Forking 1 albumart workers Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreStateMachine::setRepeat true single undefined Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreStateMachine::pushState Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreCommandRouter::volumioPushState Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 19:27:08 volumiodev wpa_supplicant[4132]: wlan0: CTRL-EVENT-TERMINATING Jul 22 19:27:08 volumiodev avahi-daemon[2100]: Interface wlan0.IPv6 no longer relevant for mDNS. Jul 22 19:27:08 volumiodev avahi-daemon[2100]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::7edd:90ff:fe40:e38b. Jul 22 19:27:08 volumiodev avahi-daemon[2100]: Withdrawing address record for fe80::7edd:90ff:fe40:e38b on wlan0. Jul 22 19:27:08 volumiodev systemd-networkd[2072]: wlan0: Link DOWN Jul 22 19:27:08 volumiodev systemd[1]: wireless.service: Succeeded. Jul 22 19:27:08 volumiodev systemd[1]: Stopped Wireless Services. Jul 22 19:27:08 volumiodev systemd[1]: Starting Wireless Services... Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreStateMachine::setRandom null Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreStateMachine::pushState Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreCommandRouter::volumioPushState Jul 22 19:27:08 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 19:27:09 volumiodev sudo[5026]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Jul 22 19:27:09 volumiodev sudo[5026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:09 volumiodev volumio[4901]: Unable to parse: Jul 22 19:27:09 volumiodev volumio[4901]: Simple mixer control 'Headphone',0 Jul 22 19:27:09 volumiodev volumio[4901]: Capabilities: pswitch pswitch-joined Jul 22 19:27:09 volumiodev volumio[4901]: Playback channels: Mono Jul 22 19:27:09 volumiodev volumio[4901]: Mono: Playback [on] Jul 22 19:27:09 volumiodev volumio[4901]: info: VolumeController:: Volume=undefined Mute =false Jul 22 19:27:09 volumiodev volumio[4901]: info: CoreStateMachine::pushState Jul 22 19:27:09 volumiodev volumio[4901]: info: CoreCommandRouter::volumioPushState Jul 22 19:27:09 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 19:27:09 volumiodev sudo[5026]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:09 volumiodev volumio[4901]: info: Starting Shairport Sync Jul 22 19:27:09 volumiodev sudo[5042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 22 19:27:09 volumiodev volumio[4901]: info: Starting Shairport Sync Jul 22 19:27:09 volumiodev sudo[5045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 19:27:09 volumiodev volumio[4901]: Discovery: adding ec48237d-fca8-40ec-a8cf-208d6a6080a9 Jul 22 19:27:09 volumiodev volumio[4901]: info: mDNS: Found device Volumiodev Jul 22 19:27:09 volumiodev sudo[5042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:09 volumiodev sudo[5045]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:09 volumiodev volumio[4901]: error: Could not retrieve plugin audio_interface multiroom Jul 22 19:27:09 volumiodev sudo[5042]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:09 volumiodev sudo[5052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 22 19:27:09 volumiodev volumio[4901]: error: Could not retrieve plugin audio_interface multiroom Jul 22 19:27:09 volumiodev sudo[5045]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:09 volumiodev sudo[5052]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:09 volumiodev sudo[5061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 22 19:27:09 volumiodev sudo[5061]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:10 volumiodev systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 22 19:27:10 volumiodev systemd[1]: shairport-sync.service: Succeeded. Jul 22 19:27:10 volumiodev systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 22 19:27:10 volumiodev systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 22 19:27:10 volumiodev sudo[5052]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:10 volumiodev systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 22 19:27:10 volumiodev systemd[1]: shairport-sync.service: Succeeded. Jul 22 19:27:10 volumiodev systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 22 19:27:10 volumiodev volumio[4901]: Discovery: this is already registered, ec48237d-fca8-40ec-a8cf-208d6a6080a9 Jul 22 19:27:10 volumiodev volumio[4901]: info: mDNS: Found device Volumiodev Jul 22 19:27:10 volumiodev volumio[4901]: error: Could not retrieve plugin audio_interface multiroom Jul 22 19:27:10 volumiodev volumio[4901]: error: Could not retrieve plugin audio_interface multiroom Jul 22 19:27:10 volumiodev systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 22 19:27:10 volumiodev sudo[5061]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:10 volumiodev volumio[4901]: info: mDNS: A device disapperared from network Jul 22 19:27:10 volumiodev volumio[4901]: info: mDNS: Device volumiodev disapperared from network Jul 22 19:27:10 volumiodev volumio[4901]: error: Could not retrieve plugin audio_interface multiroom Jul 22 19:27:10 volumiodev volumio[4901]: info: Shairport-Sync Started Jul 22 19:27:10 volumiodev volumio[4901]: Error adding Membership: Error: addMembership EINVAL Jul 22 19:27:10 volumiodev volumio[4901]: info: mDNS: A device disapperared from network Jul 22 19:27:10 volumiodev volumio[4901]: error: Could not retrieve plugin audio_interface multiroom Jul 22 19:27:10 volumiodev volumio[4901]: info: Shairport-Sync Started Jul 22 19:27:11 volumiodev sudo[5101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 22 19:27:11 volumiodev sudo[5101]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:11 volumiodev systemd[1]: Started UPnP Renderer front-end to MPD. Jul 22 19:27:11 volumiodev sudo[5101]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:11 volumiodev systemd[5107]: upmpdcli.service: Failed to execute command: No such file or directory Jul 22 19:27:11 volumiodev systemd[5107]: upmpdcli.service: Failed at step EXEC spawning /usr/bin/upmpdcli: No such file or directory Jul 22 19:27:11 volumiodev systemd[1]: upmpdcli.service: Main process exited, code=exited, status=203/EXEC Jul 22 19:27:11 volumiodev systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jul 22 19:27:11 volumiodev volumio[4901]: info: Upmpdcli Daemon Started Jul 22 19:27:13 volumiodev wireless.js[5047]: Cleaning previous... Jul 22 19:27:13 volumiodev volumio[5024]: Starting albumart workers Jul 22 19:27:13 volumiodev sudo[5118]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jul 22 19:27:13 volumiodev sudo[5118]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:13 volumiodev sudo[5118]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:13 volumiodev sudo[5127]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jul 22 19:27:13 volumiodev sudo[5127]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:13 volumiodev sudo[5127]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:14 volumiodev volumio[4901]: info: mDNS Browse raised the following error Error: dns service error: unknown Jul 22 19:27:14 volumiodev wireless.js[5047]: Stopped aP Jul 22 19:27:14 volumiodev wireless.js[5047]: Start wireless flow Jul 22 19:27:14 volumiodev wireless.js[5047]: Stopped hotspot (if there).. Jul 22 19:27:14 volumiodev sudo[5141]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jul 22 19:27:14 volumiodev sudo[5141]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:14 volumiodev sudo[5141]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:14 volumiodev sudo[5150]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jul 22 19:27:14 volumiodev sudo[5150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:14 volumiodev sudo[5150]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:14 volumiodev wireless.js[5047]: DHCP IP Jul 22 19:27:14 volumiodev wireless.js[5047]: Start ap Jul 22 19:27:14 volumiodev wpa_supplicant[5159]: Successfully initialized wpa_supplicant Jul 22 19:27:15 volumiodev systemd-networkd[2072]: wlan0: Link UP Jul 22 19:27:15 volumiodev wireless.js[5047]: trying... Jul 22 19:27:15 volumiodev sudo[5173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 19:27:15 volumiodev sudo[5173]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:15 volumiodev sudo[5173]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:16 volumiodev wireless.js[5047]: trying... Jul 22 19:27:16 volumiodev sudo[5183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 19:27:16 volumiodev sudo[5183]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:16 volumiodev wpa_supplicant[5171]: wlan0: SME: Trying to authenticate with 00:90:4c:62:2e:36 (SSID='VeriderTech-OFC' freq=2437 MHz) Jul 22 19:27:16 volumiodev kernel: wlan0: authenticate with 00:90:4c:62:2e:36 Jul 22 19:27:16 volumiodev kernel: wlan0: send auth to 00:90:4c:62:2e:36 (try 1/3) Jul 22 19:27:16 volumiodev sudo[5183]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:16 volumiodev wpa_supplicant[5171]: wlan0: Trying to associate with 00:90:4c:62:2e:36 (SSID='VeriderTech-OFC' freq=2437 MHz) Jul 22 19:27:16 volumiodev kernel: wlan0: authenticated Jul 22 19:27:16 volumiodev kernel: wlan0: associate with 00:90:4c:62:2e:36 (try 1/3) Jul 22 19:27:16 volumiodev kernel: wlan0: RX AssocResp from 00:90:4c:62:2e:36 (capab=0x411 status=0 aid=5) Jul 22 19:27:16 volumiodev systemd-networkd[2072]: wlan0: Gained carrier Jul 22 19:27:16 volumiodev kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Jul 22 19:27:16 volumiodev wpa_supplicant[5171]: wlan0: Associated with 00:90:4c:62:2e:36 Jul 22 19:27:16 volumiodev kernel: wlan0: associated Jul 22 19:27:16 volumiodev wpa_supplicant[5171]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 22 19:27:16 volumiodev systemd-networkd[2072]: wlan0: Connected WiFi access point: VeriderTech-OFC (00:90:4c:62:2e:36) Jul 22 19:27:16 volumiodev systemd-networkd[2072]: wlan0: found matching network '/etc/systemd/network/11-wlan0.network', based on potentially unpredictable ifname Jul 22 19:27:16 volumiodev systemd-networkd[2072]: wlan0: Lost carrier Jul 22 19:27:16 volumiodev wpa_supplicant[5171]: wlan0: WPA: Key negotiation completed with 00:90:4c:62:2e:36 [PTK=CCMP GTK=CCMP] Jul 22 19:27:16 volumiodev wpa_supplicant[5171]: wlan0: CTRL-EVENT-CONNECTED - Connection to 00:90:4c:62:2e:36 completed [id=0 id_str=] Jul 22 19:27:16 volumiodev systemd-networkd[2072]: wlan0: Gained carrier Jul 22 19:27:16 volumiodev wpa_supplicant[5171]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Jul 22 19:27:16 volumiodev systemd-networkd[2072]: wlan0: DHCPv4 address 192.168.100.106/24 via 192.168.100.1 Jul 22 19:27:17 volumiodev avahi-daemon[2100]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.106. Jul 22 19:27:17 volumiodev avahi-daemon[2100]: New relevant interface wlan0.IPv4 for mDNS. Jul 22 19:27:17 volumiodev avahi-daemon[2100]: Registering new address record for 192.168.100.106 on wlan0.IPv4. Jul 22 19:27:17 volumiodev systemd-timesyncd[2062]: Network configuration changed, trying to establish connection. Jul 22 19:27:17 volumiodev volumio[4901]: Discovery: adding 2d0afa64-976b-49ba-b6b1-49909d54e571 Jul 22 19:27:17 volumiodev volumio[4901]: info: mDNS: Found device ft4386-0c6b Jul 22 19:27:17 volumiodev volumio[4901]: error: Could not retrieve plugin audio_interface multiroom Jul 22 19:27:17 volumiodev wireless.js[5047]: trying... Jul 22 19:27:17 volumiodev sudo[5200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 19:27:17 volumiodev volumio[4901]: error: Could not retrieve plugin audio_interface multiroom Jul 22 19:27:17 volumiodev sudo[5200]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:17 volumiodev sudo[5200]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:17 volumiodev wireless.js[5047]: Connected to: ----VeriderTech-OFC Jul 22 19:27:17 volumiodev wireless.js[5047]: ---- Jul 22 19:27:17 volumiodev sudo[5210]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 19:27:17 volumiodev sudo[5210]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:17 volumiodev sudo[5210]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:17 volumiodev volumio[4901]: Discovery: adding ec48237d-fca8-40ec-a8cf-208d6a6080a9 Jul 22 19:27:17 volumiodev volumio[4901]: info: mDNS: Found device Volumiodev Jul 22 19:27:17 volumiodev volumio[4901]: error: Could not retrieve plugin audio_interface multiroom Jul 22 19:27:17 volumiodev volumio[4901]: error: Could not retrieve plugin audio_interface multiroom Jul 22 19:27:17 volumiodev wireless.js[5047]: ... joined AP, wlan0 IPv4 is 192.168.100.106, ipV6 is fe80::7edd:90ff:fe40:e38b Jul 22 19:27:17 volumiodev wireless.js[5047]: It's done! AP Jul 22 19:27:18 volumiodev systemd[1]: Started Wireless Services. Jul 22 19:27:18 volumiodev sudo[5009]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:18 volumiodev systemd-networkd[2072]: wlan0: Gained IPv6LL Jul 22 19:27:18 volumiodev systemd-timesyncd[2062]: Network configuration changed, trying to establish connection. Jul 22 19:27:18 volumiodev avahi-daemon[2100]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::7edd:90ff:fe40:e38b. Jul 22 19:27:18 volumiodev avahi-daemon[2100]: New relevant interface wlan0.IPv6 for mDNS. Jul 22 19:27:18 volumiodev avahi-daemon[2100]: Registering new address record for fe80::7edd:90ff:fe40:e38b on wlan0.*. Jul 22 19:27:18 volumiodev systemd-resolved[2163]: Using degraded feature set (UDP) for DNS server 192.168.100.1. Jul 22 19:27:19 volumiodev volumio[4901]: Discovery: this is already registered, ec48237d-fca8-40ec-a8cf-208d6a6080a9 Jul 22 19:27:19 volumiodev volumio[4901]: info: mDNS: Found device Volumiodev Jul 22 19:27:19 volumiodev volumio[4901]: error: Could not retrieve plugin audio_interface multiroom Jul 22 19:27:19 volumiodev volumio[4901]: error: Could not retrieve plugin audio_interface multiroom Jul 22 19:27:19 volumiodev systemd-timesyncd[2062]: Initial synchronization to time server 185.125.190.58:123 (ntp.ubuntu.com). Jul 22 19:27:23 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jul 22 19:27:23 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jul 22 19:27:23 volumiodev sudo[5229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 19:27:23 volumiodev sudo[5229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:23 volumiodev sudo[5229]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:23 volumiodev sudo[5239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 22 19:27:23 volumiodev sudo[5239]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:23 volumiodev sudo[5239]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:28 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jul 22 19:27:28 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jul 22 19:27:28 volumiodev volumio[4901]: info: Refreshing Cached IP Addresses Jul 22 19:27:28 volumiodev sudo[5251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jul 22 19:27:28 volumiodev sudo[5251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:28 volumiodev sudo[5255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 22 19:27:28 volumiodev sudo[5255]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:28 volumiodev sudo[5257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 19:27:28 volumiodev sudo[5255]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:28 volumiodev sudo[5251]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:28 volumiodev sudo[5257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:28 volumiodev sudo[5257]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:28 volumiodev volumio[4901]: error: Cannot kill upmpdcli Error: Command failed: /usr/bin/sudo /usr/bin/killall upmpdcli Jul 22 19:27:28 volumiodev volumio[4901]: upmpdcli: no process found Jul 22 19:27:37 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 22 19:27:37 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 22 19:27:38 volumiodev sudo[5369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 22 19:27:38 volumiodev sudo[5372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 19:27:38 volumiodev sudo[5369]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:38 volumiodev sudo[5372]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:38 volumiodev sudo[5369]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:38 volumiodev sudo[5378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 22 19:27:38 volumiodev sudo[5372]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:38 volumiodev sudo[5378]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:27:38 volumiodev systemd[1]: Started UPnP Renderer front-end to MPD. Jul 22 19:27:38 volumiodev sudo[5378]: pam_unix(sudo:session): session closed for user root Jul 22 19:27:38 volumiodev systemd[5395]: upmpdcli.service: Failed to execute command: No such file or directory Jul 22 19:27:38 volumiodev systemd[5395]: upmpdcli.service: Failed at step EXEC spawning /usr/bin/upmpdcli: No such file or directory Jul 22 19:27:38 volumiodev systemd[1]: upmpdcli.service: Main process exited, code=exited, status=203/EXEC Jul 22 19:27:38 volumiodev systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jul 22 19:27:38 volumiodev volumio[4901]: info: Upmpdcli Daemon Started Jul 22 19:27:48 volumiodev sudo[5399]: volumio : TTY=ttyAS0 ; PWD=/volumio ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 19:27:48 volumiodev sudo[5399]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Jul 22 19:27:48 volumiodev sudo[5399]: pam_unix(sudo:session): session closed for user root Jul 22 19:28:09 volumiodev sudo[5409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 22 19:28:09 volumiodev sudo[5409]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:28:09 volumiodev sudo[5411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 19:28:09 volumiodev sudo[5409]: pam_unix(sudo:session): session closed for user root Jul 22 19:28:09 volumiodev sudo[5411]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:28:09 volumiodev sudo[5411]: pam_unix(sudo:session): session closed for user root Jul 22 19:28:10 volumiodev sudo[5429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 22 19:28:10 volumiodev sudo[5429]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:28:10 volumiodev sudo[5432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 19:28:10 volumiodev sudo[5429]: pam_unix(sudo:session): session closed for user root Jul 22 19:28:10 volumiodev sudo[5432]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 19:28:10 volumiodev sudo[5432]: pam_unix(sudo:session): session closed for user root Jul 22 19:28:12 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 22 19:28:12 volumiodev volumio[4901]: error: Could not retrieve plugin audio_interface multiroom Jul 22 19:28:12 volumiodev volumio[4901]: info: CoreCommandRouter::volumioGetVisibleSources Jul 22 19:28:12 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 19:28:12 volumiodev volumio[4901]: info: Listing playlists Jul 22 19:28:12 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 22 19:28:12 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 22 19:28:12 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 22 19:28:12 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 22 19:28:12 volumiodev volumio[4901]: info: VolumeController::SetAlsaVolume0 Jul 22 19:28:13 volumiodev volumio[4901]: info: CoreStateMachine::pushState Jul 22 19:28:13 volumiodev volumio[4901]: info: CoreCommandRouter::volumioPushState Jul 22 19:28:13 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 19:28:17 volumiodev sshd[5453]: rexec line 6: Deprecated option UsePrivilegeSeparation Jul 22 19:28:17 volumiodev sshd[5453]: rexec line 7: Deprecated option KeyRegenerationInterval Jul 22 19:28:17 volumiodev sshd[5453]: rexec line 8: Deprecated option ServerKeyBits Jul 22 19:28:17 volumiodev sshd[5453]: rexec line 14: Deprecated option RSAAuthentication Jul 22 19:28:17 volumiodev sshd[5453]: rexec line 17: Deprecated option RhostsRSAAuthentication Jul 22 19:28:18 volumiodev sshd[5453]: reprocess config line 14: Deprecated option RSAAuthentication Jul 22 19:28:18 volumiodev sshd[5453]: reprocess config line 17: Deprecated option RhostsRSAAuthentication Jul 22 19:28:18 volumiodev sshd[5453]: Accepted password for volumio from 192.168.100.200 port 58440 ssh2 Jul 22 19:28:18 volumiodev sshd[5453]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jul 22 19:28:18 volumiodev systemd-logind[2159]: New session c2 of user volumio. Jul 22 19:28:18 volumiodev systemd[1]: Started Session c2 of user volumio. Jul 22 19:28:20 volumiodev sshd[5468]: Received disconnect from 192.168.100.200 port 58440:11: Jul 22 19:28:20 volumiodev sshd[5468]: Disconnected from user volumio 192.168.100.200 port 58440 Jul 22 19:28:20 volumiodev sshd[5453]: pam_unix(sshd:session): session closed for user volumio Jul 22 19:28:20 volumiodev systemd-logind[2159]: Session c2 logged out. Waiting for processes to exit. Jul 22 19:28:20 volumiodev systemd[1]: session-c2.scope: Succeeded. Jul 22 19:28:20 volumiodev systemd-logind[2159]: Removed session c2. Jul 22 19:28:20 volumiodev sshd[5474]: rexec line 6: Deprecated option UsePrivilegeSeparation Jul 22 19:28:20 volumiodev sshd[5474]: rexec line 7: Deprecated option KeyRegenerationInterval Jul 22 19:28:20 volumiodev sshd[5474]: rexec line 8: Deprecated option ServerKeyBits Jul 22 19:28:20 volumiodev sshd[5474]: rexec line 14: Deprecated option RSAAuthentication Jul 22 19:28:20 volumiodev sshd[5474]: rexec line 17: Deprecated option RhostsRSAAuthentication Jul 22 19:28:20 volumiodev sshd[5474]: reprocess config line 14: Deprecated option RSAAuthentication Jul 22 19:28:20 volumiodev sshd[5474]: reprocess config line 17: Deprecated option RhostsRSAAuthentication Jul 22 19:28:20 volumiodev sshd[5474]: Accepted password for volumio from 192.168.100.200 port 58458 ssh2 Jul 22 19:28:20 volumiodev sshd[5474]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jul 22 19:28:20 volumiodev systemd-logind[2159]: New session c3 of user volumio. Jul 22 19:28:20 volumiodev systemd[1]: Started Session c3 of user volumio. Jul 22 19:28:21 volumiodev sshd[5492]: rexec line 6: Deprecated option UsePrivilegeSeparation Jul 22 19:28:21 volumiodev sshd[5492]: rexec line 7: Deprecated option KeyRegenerationInterval Jul 22 19:28:21 volumiodev sshd[5492]: rexec line 8: Deprecated option ServerKeyBits Jul 22 19:28:21 volumiodev sshd[5492]: rexec line 14: Deprecated option RSAAuthentication Jul 22 19:28:21 volumiodev sshd[5492]: rexec line 17: Deprecated option RhostsRSAAuthentication Jul 22 19:28:21 volumiodev sshd[5492]: reprocess config line 14: Deprecated option RSAAuthentication Jul 22 19:28:21 volumiodev sshd[5492]: reprocess config line 17: Deprecated option RhostsRSAAuthentication Jul 22 19:28:31 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 22 19:28:31 volumiodev volumio[4901]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 22 19:28:31 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 22 19:28:31 volumiodev volumio[4901]: error: Could not retrieve plugin system_controller my_volumio Jul 22 19:28:31 volumiodev volumio[4901]: info: Received Get System Version Jul 22 19:28:31 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 22 19:28:31 volumiodev volumio[4901]: info: Received Get System Info Jul 22 19:28:31 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 22 19:28:31 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 22 19:28:31 volumiodev volumio[4901]: info: Discovery: Getting this device information Jul 22 19:28:31 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Jul 22 19:28:31 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 22 19:28:31 volumiodev volumio[4901]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 22 19:28:31 volumiodev volumio[4901]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 22 19:28:31 volumiodev volumio[4901]: TypeError: Cannot read property 'indexOf' of undefined Jul 22 19:28:31 volumiodev volumio[4901]: at ControllerVolumioDiscovery.getThisDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:434:34) Jul 22 19:28:31 volumiodev volumio[4901]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1052:32) Jul 22 19:28:31 volumiodev volumio[4901]: at ControllerSystem.getSystemInfo (/volumio/app/plugins/system_controller/system/index.js:425:45) Jul 22 19:28:31 volumiodev volumio[4901]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1052:32) Jul 22 19:28:31 volumiodev volumio[4901]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:895:49) Jul 22 19:28:31 volumiodev volumio[4901]: at Socket.emit (events.js:198:13) Jul 22 19:28:31 volumiodev volumio[4901]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jul 22 19:28:31 volumiodev volumio[4901]: at process._tickCallback (internal/process/next_tick.js:61:11) Jul 22 19:28:31 volumiodev volumio[4901]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 22 19:28:33 volumiodev sudo[5593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-22 19:27 Jul 22 19:28:33 volumiodev sudo[5593]: pam_unix(sudo:session): session opened for user root by (uid=0) NAME="Ubuntu" VERSION="20.04.5 LTS (Focal Fossa)" ID=ubuntu ID_LIKE=debian PRETTY_NAME="Ubuntu 20.04.5 LTS" VERSION_ID="20.04" HOME_URL="https://www.ubuntu.com/" SUPPORT_URL="https://help.ubuntu.com/" BUG_REPORT_URL="https://bugs.launchpad.net/ubuntu/" PRIVACY_POLICY_URL="https://www.ubuntu.com/legal/terms-and-policies/privacy-policy" VERSION_CODENAME=focal UBUNTU_CODENAME=focal VOLUMIO_BUILD_VERSION="" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7"