-- Logs begin at Thu 2024-04-11 13:23:06 IST, end at Thu 2024-04-11 13:31:56 IST. -- Apr 11 13:30:29 meetha volumio[943]: info: CoreCommandRouter::volumioGetState Apr 11 13:30:29 meetha volumio[943]: info: CorePlayQueue::getTrack 0 Apr 11 13:30:59 meetha volumio[943]: info: CoreCommandRouter::volumioGetState Apr 11 13:30:59 meetha volumio[943]: info: CorePlayQueue::getTrack 0 Apr 11 13:31:29 meetha volumio[943]: info: CoreCommandRouter::volumioGetState Apr 11 13:31:29 meetha volumio[943]: info: CorePlayQueue::getTrack 0 Apr 11 13:31:43 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 13:31:43 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 13:31:43 meetha volumio[943]: info: Discovery: Getting this device information Apr 11 13:31:43 meetha volumio[943]: info: CoreCommandRouter::volumioGetState Apr 11 13:31:43 meetha volumio[943]: info: CorePlayQueue::getTrack 0 Apr 11 13:31:43 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 13:31:51 meetha volumio[943]: info: Enabling plugin mpd_oled Apr 11 13:31:51 meetha volumio[943]: info: Loading plugin "mpd_oled"... Apr 11 13:31:51 meetha volumio[943]: info: Applying required configuration parameters for plugin mpd_oled Apr 11 13:31:51 meetha volumio[943]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Apr 11 13:31:51 meetha volumio[943]: info: Preparing to generate the ALSA configuration file Apr 11 13:31:51 meetha volumio[943]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 11 13:31:51 meetha volumio[943]: info: Reading ALSA contributions from plugins. Apr 11 13:31:51 meetha volumio[943]: info: Asound.conf file written Apr 11 13:31:51 meetha sudo[1290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 11 13:31:51 meetha sudo[1290]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 13:31:51 meetha sudo[1290]: pam_unix(sudo:session): session closed for user root Apr 11 13:31:51 meetha volumio[943]: info: Output device has changed, restarting MPD Apr 11 13:31:51 meetha sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 13:31:51 meetha sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 13:31:51 meetha volumio[943]: info: Output device has changed, restarting Shairport Sync Apr 11 13:31:51 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:51 meetha sudo[1295]: pam_unix(sudo:session): session closed for user root Apr 11 13:31:51 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 13:31:51 meetha sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 13:31:51 meetha sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 13:31:51 meetha systemd[1]: Stopping Music Player Daemon... Apr 11 13:31:51 meetha volumio[943]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 13:31:51 meetha volumio[943]: [SpotifyConnect] Creating VLS config file Apr 11 13:31:51 meetha systemd[1]: mpd.service: Succeeded. Apr 11 13:31:51 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:51 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:51 meetha systemd[1]: Stopped Music Player Daemon. Apr 11 13:31:51 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:51 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:51 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:51 meetha volumio[943]: info: PLUGIN START: mpd_oled Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Not starting mpd_oled service because oled type is not configured yet Apr 11 13:31:52 meetha volumio[943]: info: Preparing to generate the ALSA configuration file Apr 11 13:31:52 meetha volumio[943]: info: Done. Apr 11 13:31:52 meetha volumio[943]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 11 13:31:52 meetha systemd[1]: Starting Music Player Daemon... Apr 11 13:31:52 meetha volumio[943]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 11 13:31:52 meetha volumio[943]: info: Reading ALSA contributions from plugins. Apr 11 13:31:52 meetha volumio[943]: info: MPD Permissions set Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha sudo[1307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 11 13:31:52 meetha sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha systemd[1]: Started Volspotconnect2 Daemon. Apr 11 13:31:52 meetha volumio[943]: info: Starting Shairport Sync Apr 11 13:31:52 meetha sudo[1307]: pam_unix(sudo:session): session closed for user root Apr 11 13:31:52 meetha volumio[1313]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 11 13:31:52 meetha volumio[1313]: Reading Config from "/tmp/volspotify.toml" Apr 11 13:31:52 meetha volumio[1313]: [Vollibrespot] : Using Alsa backend with device: volumio Apr 11 13:31:52 meetha volumio[943]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 11 13:31:52 meetha volumio[943]: info: Asound.conf file unchanged, so no further update is needed Apr 11 13:31:52 meetha volumio[943]: info: Output device has changed, restarting MPD Apr 11 13:31:52 meetha sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 13:31:52 meetha sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 13:31:52 meetha sudo[1319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 13:31:52 meetha sudo[1319]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 13:31:52 meetha volumio[1313]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Apr 11 13:31:52 meetha systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 13:31:52 meetha systemd[1]: shairport-sync.service: Succeeded. Apr 11 13:31:52 meetha systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 13:31:52 meetha sudo[1319]: pam_unix(sudo:session): session closed for user root Apr 11 13:31:52 meetha systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 13:31:52 meetha volumio[943]: info: Output device has changed, restarting Shairport Sync Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 13:31:52 meetha sudo[1317]: pam_unix(sudo:session): session closed for user root Apr 11 13:31:52 meetha sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 13:31:52 meetha sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 13:31:52 meetha systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 11 13:31:52 meetha systemd[1]: mpd.service: Succeeded. Apr 11 13:31:52 meetha systemd[1]: Stopped Music Player Daemon. Apr 11 13:31:52 meetha systemd[1]: Starting Music Player Daemon... Apr 11 13:31:52 meetha volumio[943]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 13:31:52 meetha volumio[943]: [SpotifyConnect] Creating VLS config file Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 11 13:31:52 meetha volumio[943]: info: MPD Permissions set Apr 11 13:31:52 meetha volumio[943]: info: Shairport-Sync Started Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 11 13:31:52 meetha sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 13:31:52 meetha systemd[1]: Stopping Volspotconnect2 Daemon... Apr 11 13:31:52 meetha systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Apr 11 13:31:52 meetha systemd[1]: volspotconnect.service: Succeeded. Apr 11 13:31:52 meetha systemd[1]: Stopped Volspotconnect2 Daemon. Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 13:31:52 meetha systemd[1]: Started Volspotconnect2 Daemon. Apr 11 13:31:52 meetha sudo[1334]: pam_unix(sudo:session): session closed for user root Apr 11 13:31:52 meetha volumio[943]: info: Starting Shairport Sync Apr 11 13:31:52 meetha volumio[1341]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 11 13:31:52 meetha volumio[1341]: Reading Config from "/tmp/volspotify.toml" Apr 11 13:31:52 meetha volumio[1341]: [Vollibrespot] : Using Alsa backend with device: volumio Apr 11 13:31:52 meetha volumio[943]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 11 13:31:52 meetha sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 13:31:52 meetha sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 13:31:52 meetha volumio[1341]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Apr 11 13:31:52 meetha systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 13:31:52 meetha systemd[1]: shairport-sync.service: Succeeded. Apr 11 13:31:52 meetha systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 13:31:52 meetha systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 13:31:52 meetha sudo[1344]: pam_unix(sudo:session): session closed for user root Apr 11 13:31:52 meetha volumio[943]: info: Shairport-Sync Started Apr 11 13:31:52 meetha volumio[943]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: oledType: 0 - Select... Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: numberOfBars: 16 - 16 (default) Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: gapBetweenBars: 1 - 1 (default) Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: frameRate: 50 - 50 (default) Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: scrollRate: 8 - 8 (default) Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: scrollDelay: 5 - 5 (default) Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: clockFormat: 0 - 24h leading 0 (default) Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: pauseScreenType: p - Play (default) Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: invertDisplayPeriod: 0 - Disabled (default) Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: i2cAddress: - Default for display Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: i2cBus: 1 - /dev/i2c-1 (default) Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: spiResetGPIONumber: 25 - 25 (default) Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: spiDCGPIONumber: 24 - 24 (default) Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: spiCS: 0 - 0 (default) Apr 11 13:31:52 meetha volumio[943]: info: [MPD_OLED Plugin] Populating: dateFormat: 0 - DD-MM-YYYY (default) Apr 11 13:31:52 meetha mpd[1346]: Apr 11 13:31 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 13:31:52 meetha mpd[1346]: Apr 11 13:31 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 11 13:31:52 meetha mpd[1346]: Apr 11 13:31 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 11 13:31:52 meetha systemd[1]: Started Music Player Daemon. Apr 11 13:31:52 meetha sudo[1325]: pam_unix(sudo:session): session closed for user root Apr 11 13:31:52 meetha sudo[1298]: pam_unix(sudo:session): session closed for user root Apr 11 13:31:52 meetha volumio[943]: error: MPD error: The expression evaluated to a falsy value: Apr 11 13:31:52 meetha volumio[943]: assert.ok(self.idling) Apr 11 13:31:52 meetha volumio[943]: error: The expression evaluated to a falsy value: Apr 11 13:31:52 meetha volumio[943]: assert.ok(self.idling) Apr 11 13:31:52 meetha volumio[943]: error: updateQueue error: null Apr 11 13:31:56 meetha volumio[943]: info: Checking Spotify Web API Apr 11 13:31:56 meetha volumio[943]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 13:31:56 meetha volumio[943]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Apr 11 13:31:56 meetha volumio[943]: at healthCheck (dgram.js:897:11) Apr 11 13:31:56 meetha volumio[943]: at Socket.send (dgram.js:622:3) Apr 11 13:31:56 meetha volumio[943]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Apr 11 13:31:56 meetha volumio[943]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2212:23) Apr 11 13:31:56 meetha volumio[943]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2501:18) Apr 11 13:31:56 meetha volumio[943]: at listOnTimeout (internal/timers.js:554:17) Apr 11 13:31:56 meetha volumio[943]: at processTimers (internal/timers.js:497:7) { Apr 11 13:31:56 meetha volumio[943]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Apr 11 13:31:56 meetha volumio[943]: } Apr 11 13:31:56 meetha volumio[943]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 13:31:56 meetha sudo[1361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 13:30 Apr 11 13:31:56 meetha sudo[1361]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"