-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2024-03-31 23:12:24 CEST. --
Mar 31 23:12:19 volumio ntpd[1077]: receive: Unexpected origin timestamp 0xe9b001d8.f87654b5 does not match aorg 0000000000.00000000 from server@82.197.164.46 xmt 0xe9b45033.6f0e8d65
Mar 31 23:12:19 volumio ntpd[1077]: receive: Unexpected origin timestamp 0xe9b001d8.f8734607 does not match aorg 0000000000.00000000 from server@193.33.30.39 xmt 0xe9b45033.6b7b2a4c
Mar 31 23:12:19 volumio ntpd[1077]: receive: Unexpected origin timestamp 0xe9b001d8.f8781019 does not match aorg 0000000000.00000000 from server@83.150.25.25 xmt 0xe9b45033.6f28bd3d
Mar 31 23:12:19 volumio ntpd[1077]: receive: Unexpected origin timestamp 0xe9b001d8.f8746c2b does not match aorg 0000000000.00000000 from server@81.94.123.17 xmt 0xe9b45033.6f7bfd38
Mar 31 23:12:19 volumio ntpd[1077]: receive: Unexpected origin timestamp 0xe9b001d8.f8754334 does not match aorg 0000000000.00000000 from server@84.16.73.33 xmt 0xe9b45033.6f6f21c3
Mar 31 23:12:19 volumio ntpd[1077]: receive: Unexpected origin timestamp 0xe9b001d8.f878f87f does not match aorg 0000000000.00000000 from server@192.33.214.57 xmt 0xe9b45033.6f700bee
Mar 31 23:12:19 volumio ntpd[1077]: receive: Unexpected origin timestamp 0xe9b001d8.f87728ff does not match aorg 0000000000.00000000 from server@109.233.182.116 xmt 0xe9b45033.70545fb7
Mar 31 23:12:19 volumio systemd[1]: Starting Daily apt download activities...
Mar 31 23:12:19 volumio systemd[1]: apt-daily.service: Succeeded.
Mar 31 23:12:19 volumio systemd[1]: Started Daily apt download activities.
Mar 31 23:12:19 volumio systemd[1]: Starting Daily apt upgrade and clean activities...
Mar 31 23:12:20 volumio systemd[1]: apt-daily-upgrade.service: Succeeded.
Mar 31 23:12:20 volumio systemd[1]: Started Daily apt upgrade and clean activities.
Mar 31 23:12:20 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
Mar 31 23:12:20 volumio volumio[907]: info: Discovery: A device disappeared from network
Mar 31 23:12:20 volumio volumio[907]: info: Discovery: Device volumio disappeared from network
Mar 31 23:12:20 volumio volumio[907]: info: Discovery: adding ea79852b-be92-440a-a90f-0bf9e43c7f1f
Mar 31 23:12:20 volumio volumio[907]: info: Discovery: Found device Volumio
Mar 31 23:12:20 volumio volumio[907]: info: CoreCommandRouter::volumioGetState
Mar 31 23:12:20 volumio volumio[907]: info: CorePlayQueue::getTrack 0
Mar 31 23:12:23 volumio volumio[907]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Mar 31 23:12:24 volumio volumio[907]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion
Mar 31 23:12:24 volumio volumio[907]: info: Preparing to generate the ALSA configuration file
Mar 31 23:12:24 volumio volumio[907]: info: Asound.conf file unchanged, so no further update is needed
Mar 31 23:12:24 volumio volumio[907]: info: Output device has changed, restarting MPD
Mar 31 23:12:24 volumio volumio[907]: info: Output device has changed, restarting Shairport Sync
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 31 23:12:24 volumio sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 31 23:12:24 volumio sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 31 23:12:24 volumio sudo[1195]: pam_unix(sudo:session): session closed for user root
Mar 31 23:12:24 volumio sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 31 23:12:24 volumio sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 31 23:12:24 volumio volumio[907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 31 23:12:24 volumio volumio[907]: info: ___________ START PLUGINS ___________
Mar 31 23:12:24 volumio volumio[907]: info: ControllerMpd::onStart: Initializing MPD
Mar 31 23:12:24 volumio volumio[907]: info: Creating MPD Configuration file
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 31 23:12:24 volumio sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 31 23:12:24 volumio sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 31 23:12:24 volumio volumio[907]: info: [1711919544295] CoreMusicLibrary::Adding element Medienserver
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 31 23:12:24 volumio sudo[1205]: pam_unix(sudo:session): session closed for user root
Mar 31 23:12:24 volumio sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 31 23:12:24 volumio sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 31 23:12:24 volumio systemd[1]: Listening on mpd.socket.
Mar 31 23:12:24 volumio systemd[1]: Starting Music Player Daemon...
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 31 23:12:24 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Mar 31 23:12:24 volumio systemd[1]: mpd.service: Succeeded.
Mar 31 23:12:24 volumio systemd[1]: Stopped Music Player Daemon.
Mar 31 23:12:24 volumio systemd[1]: Starting Music Player Daemon...
Mar 31 23:12:24 volumio volumio[907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 31 23:12:24 volumio volumio[907]: info: [1711919544342] CoreMusicLibrary::Adding element Last_100
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 31 23:12:24 volumio volumio[907]: info: [1711919544343] CoreMusicLibrary::Adding element Webradio
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 31 23:12:24 volumio volumio[907]: info: AutoStart - onStart
Mar 31 23:12:24 volumio volumio[907]: info: Volumio Calling Home
Mar 31 23:12:24 volumio volumio[907]: info: AutoStart - Plugin is starting
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::volumioGetQueue
Mar 31 23:12:24 volumio volumio[907]: info: CoreStateMachine::getQueue
Mar 31 23:12:24 volumio volumio[907]: info: CorePlayQueue::getQueue
Mar 31 23:12:24 volumio volumio[907]: info: AutoStart - start playing
Mar 31 23:12:24 volumio volumio[907]: info: AutoStart - start playing with no specific position
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::volumioPlay
Mar 31 23:12:24 volumio volumio[907]: info: CoreStateMachine::play index 0
Mar 31 23:12:24 volumio volumio[907]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 31 23:12:24 volumio volumio[907]: info: CoreStateMachine::stop
Mar 31 23:12:24 volumio volumio[907]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 31 23:12:24 volumio volumio[907]: info: CoreStateMachine::play index undefined
Mar 31 23:12:24 volumio volumio[907]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 31 23:12:24 volumio volumio[907]: info: CorePlayQueue::getTrack 0
Mar 31 23:12:24 volumio volumio[907]: info: CoreStateMachine::startPlaybackTimer
Mar 31 23:12:24 volumio volumio[907]: info: CorePlayQueue::getTrack 0
Mar 31 23:12:24 volumio volumio[907]: verbose: ControllerMpd::clearAddPlayTracks USB/Musik/FLAC/10cc/Super Stars Best Collection/001 - 00 - Volumio-Intro.mp3
Mar 31 23:12:24 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand stop
Mar 31 23:12:24 volumio volumio[907]: info: MPD Permissions set
Mar 31 23:12:24 volumio volumio[907]: info: MPD Permissions set
Mar 31 23:12:24 volumio volumio[907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 31 23:12:24 volumio volumio[907]: info: Volumio called home
Mar 31 23:12:24 volumio volumio[907]: info: Starting Shairport Sync
Mar 31 23:12:24 volumio volumio[907]: info: Starting Shairport Sync
Mar 31 23:12:24 volumio volumio[907]: info: Starting Shairport Sync
Mar 31 23:12:24 volumio sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 31 23:12:24 volumio sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 31 23:12:24 volumio sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 31 23:12:24 volumio sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 31 23:12:24 volumio sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 31 23:12:24 volumio sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 31 23:12:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Mar 31 23:12:24 volumio volumio[907]: info: CoreCommandRouter::volumioGetState
Mar 31 23:12:24 volumio volumio[907]: info: CorePlayQueue::getTrack 0
Mar 31 23:12:24 volumio systemd[1]: shairport-sync.service: Succeeded.
Mar 31 23:12:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Mar 31 23:12:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Mar 31 23:12:24 volumio sudo[1232]: pam_unix(sudo:session): session closed for user root
Mar 31 23:12:24 volumio sudo[1230]: pam_unix(sudo:session): session closed for user root
Mar 31 23:12:24 volumio sudo[1235]: pam_unix(sudo:session): session closed for user root
Mar 31 23:12:24 volumio volumio[907]: info: Shairport-Sync Started
Mar 31 23:12:24 volumio volumio[907]: Error adding Membership: Error: addMembership EINVAL
Mar 31 23:12:24 volumio volumio[907]: info: Shairport-Sync Started
Mar 31 23:12:24 volumio volumio[907]: info: Shairport-Sync Started
Mar 31 23:12:24 volumio volumio[907]: info: CorePlayQueue::getTrack 0
Mar 31 23:12:24 volumio volumio[907]: info: CorePlayQueue::getTrack 1531
Mar 31 23:12:24 volumio volumio[907]: info: Prefetching next song
Mar 31 23:12:24 volumio volumio[907]: info: DOING PREFETCH IN MPD
Mar 31 23:12:24 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand add "USB/Musik/FLAC/Hitsville USA/Hitsville USA, Vol. 2; The Motown Singles Collection 1972-1992, Disc 4/Smokey Robinson - Hitsville USA, Vol. 2 The Motown Singles Collection 1972-1992, Disc 4 - 07 - One Heartbeat.flac"
Mar 31 23:12:24 volumio volumio[907]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 31 23:12:24 volumio volumio[907]: TypeError: Cannot read property 'then' of undefined
Mar 31 23:12:24 volumio volumio[907]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6)
Mar 31 23:12:24 volumio volumio[907]: at ControllerMpd.prefetch (/volumio/app/plugins/music_service/mpd/index.js:3737:15)
Mar 31 23:12:24 volumio volumio[907]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:453:18)
Mar 31 23:12:24 volumio volumio[907]: at listOnTimeout (internal/timers.js:554:17)
Mar 31 23:12:24 volumio volumio[907]: at processTimers (internal/timers.js:497:7)
Mar 31 23:12:24 volumio volumio[907]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 31 23:12:24 volumio sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-31 23:11
Mar 31 23:12:24 volumio sudo[1250]: 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"