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