-- 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"