-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2024-05-06 18:15:57 CEST. --
May 06 18:15:51 volumio systemd[1]: Starting Daily apt download activities...
May 06 18:15:51 volumio systemd[1]: apt-daily.service: Succeeded.
May 06 18:15:51 volumio systemd[1]: Started Daily apt download activities.
May 06 18:15:51 volumio systemd[1]: Starting Daily apt upgrade and clean activities...
May 06 18:15:51 volumio volumio[890]: info: Discovery: A device disappeared from network
May 06 18:15:51 volumio volumio[890]: info: Discovery: Device volumio disappeared from network
May 06 18:15:51 volumio systemd[1]: apt-daily-upgrade.service: Succeeded.
May 06 18:15:51 volumio systemd[1]: Started Daily apt upgrade and clean activities.
May 06 18:15:51 volumio volumio[890]: info: Discovery: adding ea79852b-be92-440a-a90f-0bf9e43c7f1f
May 06 18:15:51 volumio volumio[890]: info: Discovery: Found device Volumio
May 06 18:15:51 volumio volumio[890]: info: CoreCommandRouter::volumioGetState
May 06 18:15:51 volumio volumio[890]: info: CorePlayQueue::getTrack 0
May 06 18:15:52 volumio systemd[1]: systemd-fsckd.service: Succeeded.
May 06 18:15:53 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
May 06 18:15:56 volumio volumio[890]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
May 06 18:15:57 volumio volumio[890]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion
May 06 18:15:57 volumio volumio[890]: info: Preparing to generate the ALSA configuration file
May 06 18:15:57 volumio volumio[890]: info: Asound.conf file unchanged, so no further update is needed
May 06 18:15:57 volumio volumio[890]: info: Output device has changed, restarting MPD
May 06 18:15:57 volumio sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 06 18:15:57 volumio volumio[890]: info: Output device has changed, restarting Shairport Sync
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 06 18:15:57 volumio sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 06 18:15:57 volumio sudo[1179]: pam_unix(sudo:session): session closed for user root
May 06 18:15:57 volumio sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 06 18:15:57 volumio sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 06 18:15:57 volumio volumio[890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 06 18:15:57 volumio volumio[890]: info: ___________ START PLUGINS ___________
May 06 18:15:57 volumio volumio[890]: info: ControllerMpd::onStart: Initializing MPD
May 06 18:15:57 volumio volumio[890]: info: Creating MPD Configuration file
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 06 18:15:57 volumio sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 06 18:15:57 volumio sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 06 18:15:57 volumio volumio[890]: info: [1715012157315] CoreMusicLibrary::Adding element Medienserver
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 06 18:15:57 volumio systemd[1]: Listening on mpd.socket.
May 06 18:15:57 volumio sudo[1189]: pam_unix(sudo:session): session closed for user root
May 06 18:15:57 volumio systemd[1]: Starting Music Player Daemon...
May 06 18:15:57 volumio sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 06 18:15:57 volumio sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 06 18:15:57 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
May 06 18:15:57 volumio systemd[1]: mpd.service: Succeeded.
May 06 18:15:57 volumio systemd[1]: Stopped Music Player Daemon.
May 06 18:15:57 volumio systemd[1]: Starting Music Player Daemon...
May 06 18:15:57 volumio volumio[890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 06 18:15:57 volumio volumio[890]: info: [1715012157365] CoreMusicLibrary::Adding element Last_100
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 06 18:15:57 volumio volumio[890]: info: [1715012157366] CoreMusicLibrary::Adding element Webradio
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 06 18:15:57 volumio volumio[890]: info: AutoStart - onStart
May 06 18:15:57 volumio volumio[890]: info: Volumio Calling Home
May 06 18:15:57 volumio volumio[890]: info: AutoStart - Plugin is starting
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::volumioGetQueue
May 06 18:15:57 volumio volumio[890]: info: CoreStateMachine::getQueue
May 06 18:15:57 volumio volumio[890]: info: CorePlayQueue::getQueue
May 06 18:15:57 volumio volumio[890]: info: AutoStart - start playing
May 06 18:15:57 volumio volumio[890]: info: AutoStart - start playing with no specific position
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::volumioPlay
May 06 18:15:57 volumio volumio[890]: info: CoreStateMachine::play index 0
May 06 18:15:57 volumio volumio[890]: info: CoreStateMachine::setConsumeUpdateService undefined
May 06 18:15:57 volumio volumio[890]: info: CoreStateMachine::stop
May 06 18:15:57 volumio volumio[890]: info: CoreStateMachine::setConsumeUpdateService undefined
May 06 18:15:57 volumio volumio[890]: info: CoreStateMachine::play index undefined
May 06 18:15:57 volumio volumio[890]: info: CoreStateMachine::setConsumeUpdateService undefined
May 06 18:15:57 volumio volumio[890]: info: CorePlayQueue::getTrack 0
May 06 18:15:57 volumio volumio[890]: info: CoreStateMachine::startPlaybackTimer
May 06 18:15:57 volumio volumio[890]: info: CorePlayQueue::getTrack 0
May 06 18:15:57 volumio volumio[890]: verbose: ControllerMpd::clearAddPlayTracks USB/Musik/FLAC/10cc/Super Stars Best Collection/001 - 00 - Volumio-Intro.mp3
May 06 18:15:57 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand stop
May 06 18:15:57 volumio volumio[890]: info: MPD Permissions set
May 06 18:15:57 volumio volumio[890]: info: MPD Permissions set
May 06 18:15:57 volumio volumio[890]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 06 18:15:57 volumio volumio[890]: info: Volumio called home
May 06 18:15:57 volumio volumio[890]: info: Starting Shairport Sync
May 06 18:15:57 volumio volumio[890]: info: Starting Shairport Sync
May 06 18:15:57 volumio volumio[890]: info: Starting Shairport Sync
May 06 18:15:57 volumio sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 06 18:15:57 volumio sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 06 18:15:57 volumio sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 06 18:15:57 volumio sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 06 18:15:57 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 06 18:15:57 volumio systemd[1]: shairport-sync.service: Succeeded.
May 06 18:15:57 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 06 18:15:57 volumio sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 06 18:15:57 volumio sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 06 18:15:57 volumio volumio[890]: info: CoreCommandRouter::volumioGetState
May 06 18:15:57 volumio volumio[890]: info: CorePlayQueue::getTrack 0
May 06 18:15:57 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 06 18:15:57 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 06 18:15:57 volumio sudo[1214]: pam_unix(sudo:session): session closed for user root
May 06 18:15:57 volumio systemd[1]: shairport-sync.service: Succeeded.
May 06 18:15:57 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 06 18:15:57 volumio volumio[890]: info: Shairport-Sync Started
May 06 18:15:57 volumio volumio[890]: Error adding Membership: Error: addMembership EINVAL
May 06 18:15:57 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 06 18:15:57 volumio sudo[1216]: pam_unix(sudo:session): session closed for user root
May 06 18:15:57 volumio sudo[1220]: pam_unix(sudo:session): session closed for user root
May 06 18:15:57 volumio volumio[890]: info: Shairport-Sync Started
May 06 18:15:57 volumio volumio[890]: info: Shairport-Sync Started
May 06 18:15:57 volumio volumio[890]: info: CorePlayQueue::getTrack 0
May 06 18:15:57 volumio volumio[890]: info: CorePlayQueue::getTrack 1597
May 06 18:15:57 volumio volumio[890]: info: Prefetching next song
May 06 18:15:57 volumio volumio[890]: info: DOING PREFETCH IN MPD
May 06 18:15:57 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand add "USB/Musik/FLAC/Hitsville USA/Hitsville USA; The Motown Singles Collection 1959-1971, Disc 3/Four Tops, the - Hitsville USA The Motown Singles Collection 1959-1971, Disc 3 - 03 - Standing in the Shadows of Love.flac"
May 06 18:15:57 volumio volumio[890]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 06 18:15:57 volumio volumio[890]: TypeError: Cannot read property 'then' of undefined
May 06 18:15:57 volumio volumio[890]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6)
May 06 18:15:57 volumio volumio[890]: at ControllerMpd.prefetch (/volumio/app/plugins/music_service/mpd/index.js:3737:15)
May 06 18:15:57 volumio volumio[890]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:453:18)
May 06 18:15:57 volumio volumio[890]: at listOnTimeout (internal/timers.js:554:17)
May 06 18:15:57 volumio volumio[890]: at processTimers (internal/timers.js:497:7)
May 06 18:15:57 volumio volumio[890]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 06 18:15:57 volumio sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-06 18:14
May 06 18:15:57 volumio sudo[1236]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET"
VOLUMIO_VERSION="3.631"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"