-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Thu 2024-08-29 11:26:02 CST. -- Aug 29 11:25:43 volumio systemd-timesyncd[2122]: Initial synchronization to time server 185.125.190.57:123 (ntp.ubuntu.com). Aug 29 11:25:43 volumio systemd[1]: Starting Daily apt download activities... Aug 29 11:25:43 volumio systemd[1]: Starting Online ext4 Metadata Check for All Filesystems... Aug 29 11:25:44 volumio systemd[1]: Starting Discard unused blocks on filesystems from /etc/fstab... Aug 29 11:25:44 volumio systemd[1]: Starting Message of the Day... Aug 29 11:25:44 volumio systemd[1]: Starting Rotate log files... Aug 29 11:25:44 volumio systemd[1]: e2scrub_all.service: Succeeded. Aug 29 11:25:44 volumio systemd[1]: Finished Online ext4 Metadata Check for All Filesystems. Aug 29 11:25:44 volumio systemd[1]: motd-news.service: Succeeded. Aug 29 11:25:44 volumio systemd[1]: Finished Message of the Day. Aug 29 11:25:46 volumio systemd[1]: logrotate.service: Succeeded. Aug 29 11:25:46 volumio systemd[1]: Finished Rotate log files. Aug 29 11:25:46 volumio fstrim[2625]: /: 1.5 GiB (1580736512 bytes) trimmed on /dev/mmcblk0p5 Aug 29 11:25:46 volumio fstrim[2625]: /mnt/UDISK: 3 GiB (3169333248 bytes) trimmed on /dev/mmcblk0p9 Aug 29 11:25:46 volumio systemd[1]: fstrim.service: Succeeded. Aug 29 11:25:46 volumio systemd[1]: Finished Discard unused blocks on filesystems from /etc/fstab. Aug 29 11:25:47 volumio volumio[2266]: [SpotifyConnect2] Creating VLS config file Aug 29 11:25:47 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:47 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:47 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:47 volumio volumio[2266]: [SpotifyConnect2] <> or Mixer found, using softvol Aug 29 11:25:47 volumio volumio[2266]: info: Loading plugin "updater_comm"... Aug 29 11:25:48 volumio volumio[2266]: info: Plugin mpdemulation is not enabled Aug 29 11:25:48 volumio volumio[2266]: info: Loading plugin "rest_api"... Aug 29 11:25:48 volumio volumio[2266]: info: Loading plugin "websocket"... Aug 29 11:25:48 volumio nmbd[2231]: [2024/08/29 11:25:48.837709, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 29 11:25:48 volumio nmbd[2231]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.23.170 for name WORKGROUP<1d>. Aug 29 11:25:48 volumio nmbd[2231]: This response was from IP 192.168.23.81, reporting an IP address of 192.168.23.81. Aug 29 11:25:48 volumio volumio[2266]: info: ___________ START PLUGINS ___________ Aug 29 11:25:48 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:48 volumio volumio[2266]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 11:25:48 volumio volumio[2266]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 11:25:48 volumio volumio[2266]: info: [1724901948957] CoreMusicLibrary::Adding element Last_100 Aug 29 11:25:48 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 11:25:48 volumio volumio[2266]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 11:25:48 volumio volumio[2266]: info: [1724901948982] CoreMusicLibrary::Adding element Pandora Aug 29 11:25:48 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 11:25:48 volumio volumio[2266]: info: [1724901948992] ControllerPandora::initializeMQTT Aug 29 11:25:48 volumio volumio[2266]: info: [1724901948994] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 11:25:49 volumio volumio[2266]: info: [1724901949063] CoreMusicLibrary::Adding element Podcast Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 11:25:49 volumio volumio[2266]: info: [1724901949073] CoreMusicLibrary::Adding element Qobuz Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 11:25:49 volumio volumio[2266]: info: [1724901949097] ControllerTidalPlugin::onStart Aug 29 11:25:49 volumio volumio[2266]: info: [1724901949109] ControllerTidalPlugin::addToBrowseSources Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 11:25:49 volumio volumio[2266]: info: [1724901949111] CoreMusicLibrary::Adding element Tidal Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 11:25:49 volumio volumio[2266]: Loading session from /data/configuration/music_service/tidal/tidal-session-oauth.json... Aug 29 11:25:49 volumio volumio[2266]: Could not load session from /data/configuration/music_service/tidal/tidal-session-oauth.json: Error: ENOENT: no such file or directory, open '/data/configuration/music_service/tidal/tidal-session-oauth.json' Aug 29 11:25:49 volumio volumio[2266]: [SpotifyConnect2] Creating VLS config file Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:49 volumio volumio[2266]: [SpotifyConnect2] <> or Mixer found, using softvol Aug 29 11:25:49 volumio volumio[2266]: [SpotifyConnect2] Starting metadata listener Aug 29 11:25:49 volumio volumio[2266]: info: Loading i18n strings for locale en Aug 29 11:25:49 volumio volumio[2266]: Updating browse sources language Aug 29 11:25:49 volumio volumio[2266]: Cannot find translation for sourcePodcast Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 11:25:49 volumio sudo[2672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Aug 29 11:25:49 volumio sudo[2672]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 11:25:49 volumio systemd[1]: Configuration file /etc/systemd/system/volspotconnect2.service is marked executable. Please remove executable permission bits. Proceeding anyway. Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::initPlayerControls Aug 29 11:25:49 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 29 11:25:49 volumio sudo[2672]: pam_unix(sudo:session): session closed for user root Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 11:25:49 volumio volumio[2266]: info: BOOT COMPLETED Aug 29 11:25:49 volumio volumio[2266]: [Metrics] CommandRouter: 29s 410.60ms Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 11:25:49 volumio volumio[2266]: ALSA lib pcm.c:2642:(snd_pcm_open_noupdate) Unknown PCM softvolume Aug 29 11:25:49 volumio volumio[2266]: aplay: main:852: audio open error: No such file or directory Aug 29 11:25:49 volumio volumio[2266]: Cannot play startup sound Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::Close All Modals sent Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::Close All Modals sent Aug 29 11:25:49 volumio volumio[2266]: Express server listening on port 3000 Aug 29 11:25:49 volumio volumio[2266]: [Metrics] WebUI: 32s 25.59ms Aug 29 11:25:49 volumio volumio[2266]: info: [1724901949720] ControllerPandora::validateAndSetAccountOptions Aug 29 11:25:49 volumio volumio[2266]: error: [1724901949724] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Aug 29 11:25:49 volumio volumio[2266]: info: CoreStateMachine::resetVolumioState Aug 29 11:25:49 volumio volumio[2266]: info: CoreStateMachine::getcurrentVolume Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 11:25:49 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 11:25:49 volumio volumio[2682]: vollibrespot v0.2.4 61563b7 2021-07-22 (librespot 08d8bcc 2020-10-07) -- Built On 2021-07-22 Aug 29 11:25:49 volumio volumio[2682]: Reading Config from "volspotify.toml" Aug 29 11:25:49 volumio volumio[2682]: [Vollibrespot] : Using Alsa backend with device: hw:1 Aug 29 11:25:50 volumio sudo[2697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 11:25:50 volumio volumio[2266]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 29 11:25:50 volumio sudo[2697]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:25:50 volumio volumio[2266]: info: Cannot read play queue from file Aug 29 11:25:50 volumio sudo[2701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 11:25:50 volumio sudo[2697]: pam_unix(sudo:session): session closed for user root Aug 29 11:25:50 volumio sudo[2701]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:25:50 volumio sudo[2701]: pam_unix(sudo:session): session closed for user root Aug 29 11:25:50 volumio volumio[2266]: info: CoreStateMachine::pushState Aug 29 11:25:50 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 11:25:50 volumio volumio[2266]: info: CoreStateMachine::updateTrackBlock Aug 29 11:25:50 volumio volumio[2266]: info: CorePlayQueue::getTrackBlock Aug 29 11:25:50 volumio volumio[2266]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 11:25:50 volumio volumio[2266]: [SpotifyConnect2] Vollibrespot Daemon service started! Aug 29 11:25:50 volumio volumio[2266]: [Metrics] SpotifyConnect: 1s 439.74ms Aug 29 11:25:50 volumio volumio[2266]: info: MPD Permissions set Aug 29 11:25:50 volumio volumio[2266]: info: Permissions for /etc/hostapd/hostapd.conf Aug 29 11:25:50 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 29 11:25:50 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 11:25:50 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 29 11:25:51 volumio sudo[2738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Aug 29 11:25:51 volumio sudo[2738]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:25:51 volumio systemd[1]: Starting Wireless Services... Aug 29 11:25:51 volumio sudo[2746]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Aug 29 11:25:51 volumio sudo[2746]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:25:51 volumio volumio[2266]: info: CoreStateMachine::pushState Aug 29 11:25:51 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 11:25:51 volumio wifistart.sh[2751]: command failed: No such device (-19) Aug 29 11:25:51 volumio sudo[2746]: pam_unix(sudo:session): session closed for user root Aug 29 11:25:52 volumio socketio[2273]: command_len 9 Aug 29 11:25:52 volumio socketio[2273]: getQueue Aug 29 11:25:52 volumio socketio[2273]: Aug 29 11:25:52 volumio volumio[2266]: info: Aug 29 11:25:52 volumio volumio[2266]: ---------------------------- Client requests Volume 100 Aug 29 11:25:52 volumio volumio[2266]: info: VolumeController::SetAlsaVolume100 Aug 29 11:25:52 volumio volumio[2266]: info: CoreStateMachine::pushState Aug 29 11:25:52 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 11:25:52 volumio volumio[2266]: sendCommand: Command { name: 'playlistinfo', args: [] } Aug 29 11:25:52 volumio volumio[2266]: sendCommand: Command { name: 'lsinfo "USB"', args: [] } Aug 29 11:25:52 volumio volumio[2266]: sendCommand: Command { name: 'search album ""', args: [] } Aug 29 11:25:53 volumio volumio[2266]: info: MPD running with PID2611 Aug 29 11:25:53 volumio volumio[2266]: ,establishing connection Aug 29 11:25:53 volumio volumio[2266]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Aug 29 11:25:53 volumio volumio[2266]: sudo: /usr/sbin/i2cdetect: command not found Aug 29 11:25:53 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:53 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:53 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:53 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:53 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:53 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:53 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:53 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:53 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 11:25:53 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 29 11:25:53 volumio volumio[2266]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 11:25:53 volumio volumio[2266]: error: updateQueue error: null Aug 29 11:25:53 volumio volumio[2266]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 29 11:25:53 volumio volumio[2266]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Aug 29 11:25:53 volumio volumio[2266]: info: Setting volume on startup at 100 Aug 29 11:25:53 volumio volumio[2266]: sendCommand: Command { name: 'playlistinfo', args: [] } Aug 29 11:25:53 volumio volumio[2266]: error: updateQueue error: null Aug 29 11:25:54 volumio volumio[2266]: info: Starting Shairport Sync Aug 29 11:25:54 volumio sudo[2775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 11:25:54 volumio sudo[2775]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:25:54 volumio wireless.js[2758]: WIRELESS: No wireless interface, exiting Aug 29 11:25:54 volumio sudo[2779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 29 11:25:54 volumio systemd[1]: wireless.service: Control process exited, code=exited, status=1/FAILURE Aug 29 11:25:54 volumio systemd[1]: wireless.service: Failed with result 'exit-code'. Aug 29 11:25:54 volumio systemd[1]: Failed to start Wireless Services. Aug 29 11:25:54 volumio sudo[2738]: pam_unix(sudo:session): session closed for user root Aug 29 11:25:54 volumio sudo[2779]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 11:25:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 11:25:54 volumio sudo[2775]: pam_unix(sudo:session): session closed for user root Aug 29 11:25:54 volumio volumio[2725]: Forking 1 albumart workers Aug 29 11:25:55 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 29 11:25:55 volumio sudo[2779]: pam_unix(sudo:session): session closed for user root Aug 29 11:25:55 volumio volumio[2266]: info: Shairport-Sync Started Aug 29 11:25:55 volumio volumio[2266]: Error adding Membership: Error: addMembership EINVAL Aug 29 11:25:55 volumio volumio[2266]: info: Upmpdcli Daemon Started Aug 29 11:25:55 volumio volumio[2266]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 11:25:55 volumio volumio[2266]: TypeError: f.split(...)[0].capitalize is not a function Aug 29 11:25:55 volumio volumio[2266]: at /volumio/app/plugins/miscellanea/appearance/index.js:198:56 Aug 29 11:25:55 volumio volumio[2266]: at Array.forEach () Aug 29 11:25:55 volumio volumio[2266]: at /volumio/app/plugins/miscellanea/appearance/index.js:196:11 Aug 29 11:25:55 volumio volumio[2266]: at go$readdir$cb (/volumio/node_modules/graceful-fs/graceful-fs.js:195:14) Aug 29 11:25:55 volumio volumio[2266]: at FSReqWrap.args [as oncomplete] (fs.js:140:20) Aug 29 11:25:55 volumio volumio[2266]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 11:25:56 volumio volumio[2835]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 29 11:25:58 volumio volumio[2813]: Starting albumart workers Aug 29 11:26:01 volumio volumio[2835]: ........................++++ Aug 29 11:26:01 volumio socketio[2273]: [2024-08-29 11:26:01] [connect] Successful connection Aug 29 11:26:02 volumio systemd[1]: apt-daily.service: Succeeded. Aug 29 11:26:02 volumio systemd[1]: Finished Daily apt download activities. Aug 29 11:26:02 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Aug 29 11:26:02 volumio sudo[2885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 11:25 Aug 29 11:26:02 volumio sudo[2885]: 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"