-- Logs begin at Thu 2019-02-14 10:12:07 UTC, end at Fri 2025-04-18 08:40:17 UTC. -- Apr 18 08:39:45 kiwibox ntpd[562]: receive: Unexpected origin timestamp 0xeb93c017.cffdb477 does not match aorg 0000000000.00000000 from server@78.47.56.71 xmt 0xebac8e51.2e37c6ec Apr 18 08:39:45 kiwibox ntpd[562]: receive: Unexpected origin timestamp 0xeb93c017.cfd5a88f does not match aorg 0000000000.00000000 from server@185.197.135.21 xmt 0xebac8e51.3b2b8812 Apr 18 08:39:45 kiwibox systemd[1]: Starting Daily apt download activities... Apr 18 08:39:45 kiwibox volumio[805]: info: Asound.conf file unchanged, so no further update is needed Apr 18 08:39:45 kiwibox volumio[805]: info: Output device has changed, restarting MPD Apr 18 08:39:45 kiwibox volumio[805]: info: ___________ START PLUGINS ___________ Apr 18 08:39:46 kiwibox ntpd[562]: Soliciting pool server 185.11.138.90 Apr 18 08:39:46 kiwibox volumio[805]: info: ControllerMpd::onStart: Initializing MPD Apr 18 08:39:46 kiwibox volumio[805]: info: Creating MPD Configuration file Apr 18 08:39:46 kiwibox sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 08:39:46 kiwibox sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 08:39:46 kiwibox sudo[937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 08:39:46 kiwibox sudo[937]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 08:39:46 kiwibox sudo[933]: pam_unix(sudo:session): session closed for user root Apr 18 08:39:46 kiwibox volumio[805]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 08:39:46 kiwibox volumio[805]: info: [1744965586915] CoreMusicLibrary::Adding element Last_100 Apr 18 08:39:46 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 08:39:47 kiwibox systemd[1]: Listening on mpd.socket. Apr 18 08:39:47 kiwibox systemd[1]: Starting Music Player Daemon... Apr 18 08:39:47 kiwibox sudo[947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 08:39:47 kiwibox sudo[947]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 08:39:47 kiwibox volumio[805]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 08:39:47 kiwibox volumio[805]: info: [1744965587493] CoreMusicLibrary::Adding element Webradio Apr 18 08:39:47 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 08:39:47 kiwibox sudo[955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 08:39:47 kiwibox sudo[947]: pam_unix(sudo:session): session closed for user root Apr 18 08:39:47 kiwibox sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 08:39:47 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 08:39:47 kiwibox volumio[805]: info: Initializing BBC Radios Apr 18 08:39:48 kiwibox systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 18 08:39:48 kiwibox systemd[1]: mpd.service: Succeeded. Apr 18 08:39:48 kiwibox systemd[1]: Stopped Music Player Daemon. Apr 18 08:39:48 kiwibox systemd[1]: Starting Music Player Daemon... Apr 18 08:39:48 kiwibox volumio[805]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Apr 18 08:39:48 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 08:39:48 kiwibox volumio[805]: Starting albumart workers Apr 18 08:39:49 kiwibox sudo[967]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 08:39:49 kiwibox sudo[967]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 08:39:49 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 08:39:49 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 08:39:49 kiwibox sudo[967]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 18 08:39:49 kiwibox sudo[967]: pam_unix(sudo:session): session closed for user root Apr 18 08:39:49 kiwibox volumio[805]: info: Creating Spotify config file Apr 18 08:39:49 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:39:51 kiwibox volumio[805]: info: Volumio Calling Home Apr 18 08:39:56 kiwibox volumio[805]: info: MPD Permissions set Apr 18 08:39:57 kiwibox volumio[805]: info: MPD Permissions set Apr 18 08:39:57 kiwibox volumio[805]: info: Discovery: Disconnected from remote: 192.168.10.12 Apr 18 08:39:58 kiwibox volumio[805]: info: Volumio called home Apr 18 08:39:58 kiwibox volumio[805]: info: Spotify config file written Apr 18 08:39:58 kiwibox volumio[805]: info: Apr 18 08:39:58 kiwibox volumio[805]: ---------------------------- Client requests Volumio Play Playlist frozen Apr 18 08:39:58 kiwibox volumio[805]: info: Play playlist frozen Apr 18 08:39:59 kiwibox sudo[986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 18 08:39:59 kiwibox sudo[986]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 08:39:59 kiwibox systemd[1]: Started go-librespot Daemon. Apr 18 08:39:59 kiwibox go-librespot[996]: Librespot-go daemon starting... Apr 18 08:39:59 kiwibox sudo[986]: pam_unix(sudo:session): session closed for user root Apr 18 08:40:00 kiwibox go-librespot[996]: time="2025-04-18T08:40:00Z" level=info msg="generated new device id: c99812e7f5ae32b152c4a2bba4b0b33e1c532e8c" Apr 18 08:40:00 kiwibox go-librespot[996]: time="2025-04-18T08:40:00Z" level=debug msg="stored credentials found for mmooppaa" Apr 18 08:40:01 kiwibox volumio[805]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 18 08:40:01 kiwibox volumio[805]: info: Discovery: Connecting to remote: 192.168.10.12 Apr 18 08:40:01 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:01 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:01 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:01 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:01 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:01 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:01 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:02 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:02 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 08:40:02 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:03 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:03 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:03 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:03 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:03 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:03 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:03 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:03 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:03 kiwibox systemd[1]: apt-daily.service: Succeeded. Apr 18 08:40:03 kiwibox systemd[1]: Started Daily apt download activities. Apr 18 08:40:03 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 08:40:03 kiwibox systemd[1]: Starting Daily apt upgrade and clean activities... Apr 18 08:40:03 kiwibox volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 08:40:06 kiwibox go-librespot[996]: time="2025-04-18T08:40:06Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 18 08:40:06 kiwibox go-librespot[996]: time="2025-04-18T08:40:06Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Apr 18 08:40:06 kiwibox go-librespot[996]: time="2025-04-18T08:40:06Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Apr 18 08:40:06 kiwibox go-librespot[996]: time="2025-04-18T08:40:06Z" level=debug msg="zeroconf server listening on port 33485" Apr 18 08:40:06 kiwibox volumio[805]: info: Discovery: A device disappeared from network Apr 18 08:40:06 kiwibox volumio[805]: info: Discovery: Device radio disappeared from network Apr 18 08:40:06 kiwibox go-librespot[996]: time="2025-04-18T08:40:06Z" level=debug msg="obtained new client token: AACK3r0fa5b8CAySKezT3WwXIHk3uImqTcnX9Zbsyv4ra5nBsmmFO6u75wrTWcovf1C8PX9DHNBPc5Bv0KoyxwZdfP0ALiVWYLyOCyjf4PUsGt8wZq4bEhytJtFK595bqV3G13DR9GFHehiYQlAlvIlAPioVZhaZDLNYFmTgVOO2dgkIDwB8qix8mTReyr4xL2iK3czw1HAO6PKJkGOd+VXRNaKaw2pjyVQKcC1v6EHQPG9jDLexzPcMPg==" Apr 18 08:40:07 kiwibox go-librespot[996]: time="2025-04-18T08:40:07Z" level=debug msg="completed keyexchange" Apr 18 08:40:07 kiwibox volumio[805]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 18 08:40:07 kiwibox volumio[805]: SPOTIFY: BQBwCgES0ZcWZ1uPOjgBVg41Qorz2WgFa4fFm9__0oHnsDipB9bPARnHxd3p534gulzHBq3sMjcEg2rxVnFT8en2oD_eFiV2t8jlTzLCdXHf9eC8dz_5MO_UXeTc5xG4ih1Nv99sBEkczrHHs4M9YlVu4MypcJ15u4D79QhL6KfndALJfkYNehrVJNYM9ALw6OOpiUoGO0JEubopyJGhl_BMgstqwiPlXOZ5FP3yzoGbwJsD-d-D9w6DQV-9PSkZ3kWrfllRqlsCCnpuDsyR Apr 18 08:40:07 kiwibox volumio[805]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 18 08:40:07 kiwibox volumio[805]: info: New Spotify access token = BQBwCgES0ZcWZ1uPOjgBVg41Qorz2WgFa4fFm9__0oHnsDipB9bPARnHxd3p534gulzHBq3sMjcEg2rxVnFT8en2oD_eFiV2t8jlTzLCdXHf9eC8dz_5MO_UXeTc5xG4ih1Nv99sBEkczrHHs4M9YlVu4MypcJ15u4D79QhL6KfndALJfkYNehrVJNYM9ALw6OOpiUoGO0JEubopyJGhl_BMgstqwiPlXOZ5FP3yzoGbwJsD-d-D9w6DQV-9PSkZ3kWrfllRqlsCCnpuDsyR Apr 18 08:40:07 kiwibox volumio[805]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 18 08:40:07 kiwibox go-librespot[996]: time="2025-04-18T08:40:07Z" level=debug msg="completed challenge" Apr 18 08:40:07 kiwibox go-librespot[996]: time="2025-04-18T08:40:07Z" level=debug msg="authenticated as mmooppaa" Apr 18 08:40:08 kiwibox volumio[805]: info: Discovery: A device disappeared from network Apr 18 08:40:08 kiwibox go-librespot[996]: time="2025-04-18T08:40:08Z" level=debug msg="authenticated as mmooppaa" Apr 18 08:40:08 kiwibox go-librespot[996]: time="2025-04-18T08:40:08Z" level=debug msg="dealer connection opened" Apr 18 08:40:08 kiwibox go-librespot[996]: time="2025-04-18T08:40:08Z" level=debug msg="initializing zeroconf session, username: mmooppaa" Apr 18 08:40:08 kiwibox go-librespot[996]: time="2025-04-18T08:40:08Z" level=debug msg="received connection id: YTBlYzk4ZmQtYWIwZS00ZjI4LWFiOGYtMzJlMzllZjI0YzBjK2RlYWxlcit0Y3A6Ly8wYWNhNWE0YS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNzRBMDAxRTEyMzM4RTIwRjc0RTUyRTA4RkQ1MERGRTc4REVENEI3NDQ2NjA5OUU3ODA1OTQ1NjJENTQ1NDY5RA==" Apr 18 08:40:08 kiwibox go-librespot[996]: time="2025-04-18T08:40:08Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 18 08:40:08 kiwibox go-librespot[996]: time="2025-04-18T08:40:08Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 18 08:40:08 kiwibox volumio[805]: info: CoreCommandRouter::volumioGetState Apr 18 08:40:08 kiwibox volumio[805]: info: CorePlayQueue::getTrack 0 Apr 18 08:40:09 kiwibox go-librespot[996]: time="2025-04-18T08:40:09Z" level=debug msg="put connect state because NEW_DEVICE" Apr 18 08:40:09 kiwibox go-librespot[996]: time="2025-04-18T08:40:09Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 18 08:40:09 kiwibox go-librespot[996]: time="2025-04-18T08:40:09Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 18 08:40:09 kiwibox go-librespot[996]: time="2025-04-18T08:40:09Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 18 08:40:09 kiwibox volumio[805]: info: CoreCommandRouter::volumioClearQueue Apr 18 08:40:09 kiwibox go-librespot[996]: time="2025-04-18T08:40:09Z" level=debug msg="autoplay enabled: false" Apr 18 08:40:09 kiwibox volumio[805]: info: CoreStateMachine::ClearQueue Apr 18 08:40:09 kiwibox volumio[805]: info: CoreStateMachine::stop Apr 18 08:40:09 kiwibox volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 08:40:09 kiwibox volumio[805]: info: CorePlayQueue::clearPlayQueue Apr 18 08:40:09 kiwibox volumio[805]: info: CorePlayQueue::saveQueue Apr 18 08:40:09 kiwibox volumio[805]: info: CoreCommandRouter::volumioPushState Apr 18 08:40:09 kiwibox volumio[805]: info: CoreCommandRouter::volumioPushQueue Apr 18 08:40:09 kiwibox volumio[805]: info: CoreCommandRouter::volumioAddQueueItems Apr 18 08:40:09 kiwibox volumio[805]: info: CoreStateMachine::addQueueItems Apr 18 08:40:09 kiwibox volumio[805]: info: CorePlayQueue::addQueueItems Apr 18 08:40:09 kiwibox volumio[805]: info: Preload queue cleared Apr 18 08:40:09 kiwibox volumio[805]: info: Adding Item to queue: INTERNAL/Frozen/Frozen - Lass jetzt los.m4a Apr 18 08:40:09 kiwibox volumio[805]: info: Exploding uri INTERNAL/Frozen/Frozen - Lass jetzt los.m4a in service mpd Apr 18 08:40:09 kiwibox volumio[805]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 08:40:09 kiwibox volumio[805]: TypeError: Cannot read property 'then' of undefined Apr 18 08:40:09 kiwibox volumio[805]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Apr 18 08:40:09 kiwibox volumio[805]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Apr 18 08:40:09 kiwibox volumio[805]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1365:16) Apr 18 08:40:09 kiwibox volumio[805]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Apr 18 08:40:09 kiwibox volumio[805]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Apr 18 08:40:09 kiwibox volumio[805]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Apr 18 08:40:09 kiwibox volumio[805]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Apr 18 08:40:09 kiwibox volumio[805]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:447:28) Apr 18 08:40:09 kiwibox volumio[805]: at /volumio/app/playlistManager.js:726:30 Apr 18 08:40:09 kiwibox volumio[805]: at /volumio/node_modules/jsonfile/index.js:46:5 Apr 18 08:40:09 kiwibox volumio[805]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 08:40:17 kiwibox sudo[1079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-18 08:39 Apr 18 08:40:17 kiwibox sudo[1079]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"