-- Logs begin at Fri 2020-12-11 14:53:56 UTC, end at Fri 2020-12-11 15:15:19 UTC. -- Dec 11 15:14:00 volumio volumio[9558]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=a487c6ed8a8db06c5ffb65c3b3fa3670" http://updates.volumio.org:7070/downloader-v1/track-device Dec 11 15:14:00 volumio volumio[9558]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 11 15:14:00 volumio volumio[9558]: Dload Upload Total Spent Left Speed Dec 11 15:14:00 volumio volumio[9558]: [235B blob data] Dec 11 15:14:00 volumio volumio[9558]: retrying in 5 seconds, trial 0 Dec 11 15:14:00 volumio volumio[9558]: Volumio Calling Home Dec 11 15:14:00 volumio volumio[9558]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 11 15:14:00 volumio volumio[9558]: info: CURURI: artists://%E5%91%A8%E6%85%A7%E6%95%8F Dec 11 15:14:04 volumio volumio[9558]: info: AutoStart - getting queue Dec 11 15:14:04 volumio volumio[9558]: info: CoreCommandRouter::volumioGetQueue Dec 11 15:14:04 volumio volumio[9558]: info: CoreStateMachine::getQueue Dec 11 15:14:04 volumio volumio[9558]: info: CorePlayQueue::getQueue Dec 11 15:14:04 volumio volumio[9558]: info: AutoStart - start playing -> queue is not empty Dec 11 15:14:04 volumio volumio[9558]: info: CoreCommandRouter::volumioPlay Dec 11 15:14:04 volumio volumio[9558]: UNSET VOLATILE Dec 11 15:14:04 volumio volumio[9558]: info: CoreStateMachine::play index undefined Dec 11 15:14:04 volumio volumio[9558]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 15:14:04 volumio volumio[9558]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:04 volumio volumio[9558]: info: CoreStateMachine::startPlaybackTimer Dec 11 15:14:04 volumio volumio[9558]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:04 volumio volumio[9558]: info: [1607699644698] ControllerWebradio::clearAddPlayTrack Dec 11 15:14:04 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 15:14:04 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 15:14:04 volumio volumio[9558]: info: Dec 11 15:14:04 volumio volumio[9558]: ---------------------------- MPD announces system playlist update Dec 11 15:14:04 volumio volumio[9558]: info: Ignoring MPD Status Update Dec 11 15:14:04 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand load "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" Dec 11 15:14:04 volumio volumio[9558]: error: updateQueue error: null Dec 11 15:14:04 volumio volumio[9558]: info: ------------------------------ 22ms Dec 11 15:14:04 volumio volumio[9558]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 15:14:04 volumio volumio[9558]: info: CoreStateMachine::ClearQueue Dec 11 15:14:04 volumio volumio[9558]: info: CoreStateMachine::stop Dec 11 15:14:04 volumio volumio[9558]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 15:14:04 volumio volumio[9558]: UNSET VOLATILE Dec 11 15:14:04 volumio volumio[9558]: info: CorePlayQueue::clearPlayQueue Dec 11 15:14:04 volumio volumio[9558]: info: CorePlayQueue::saveQueue Dec 11 15:14:04 volumio volumio[9558]: info: CoreCommandRouter::volumioPushState Dec 11 15:14:04 volumio volumio[9558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 15:14:04 volumio volumio[9558]: info: CoreCommandRouter::volumioPushQueue Dec 11 15:14:04 volumio volumio[9558]: info: CoreStateMachine::addQueueItems Dec 11 15:14:04 volumio volumio[9558]: info: CorePlayQueue::addQueueItems Dec 11 15:14:04 volumio volumio[9558]: info: Adding Item to queue: music-library/NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac Dec 11 15:14:04 volumio volumio[9558]: info: Exploding uri music-library/NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac in service mpd Dec 11 15:14:10 volumio volumio[9558]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=a487c6ed8a8db06c5ffb65c3b3fa3670" http://updates.volumio.org:7070/downloader-v1/track-device Dec 11 15:14:10 volumio volumio[9558]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 11 15:14:10 volumio volumio[9558]: Dload Upload Total Spent Left Speed Dec 11 15:14:10 volumio volumio[9558]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Dec 11 15:14:10 volumio volumio[9558]: retrying in 5 seconds, trial 1 Dec 11 15:14:10 volumio volumio[9558]: Volumio Calling Home Dec 11 15:14:12 volumio volumio[9558]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 15:14:12 volumio volumio[9558]: info: CoreStateMachine::ClearQueue Dec 11 15:14:12 volumio volumio[9558]: info: CoreStateMachine::stop Dec 11 15:14:12 volumio volumio[9558]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 15:14:12 volumio volumio[9558]: UNSET VOLATILE Dec 11 15:14:12 volumio volumio[9558]: info: CorePlayQueue::clearPlayQueue Dec 11 15:14:12 volumio volumio[9558]: info: CorePlayQueue::saveQueue Dec 11 15:14:12 volumio volumio[9558]: info: CoreCommandRouter::volumioPushState Dec 11 15:14:12 volumio volumio[9558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 15:14:12 volumio volumio[9558]: info: CoreCommandRouter::volumioPushQueue Dec 11 15:14:12 volumio volumio[9558]: info: CoreStateMachine::addQueueItems Dec 11 15:14:12 volumio volumio[9558]: info: CorePlayQueue::addQueueItems Dec 11 15:14:12 volumio volumio[9558]: info: Adding Item to queue: music-library/NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac Dec 11 15:14:12 volumio volumio[9558]: info: Exploding uri music-library/NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac in service mpd Dec 11 15:14:14 volumio volumio[9558]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 15:14:14 volumio volumio[9558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand add "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" Dec 11 15:14:14 volumio volumio[9558]: info: ALBUMART /albumart?cacheid=642&web=%E5%91%A8%E6%85%A7%E6%95%8F/%E6%9C%80%E7%88%B1/extralarge&path=%2Fmnt%2FNAS%2FFNOS%2Fuser%2Fmusic%2F%E5%91%A8%E6%85%A7%E6%95%8F%2F%E6%9C%80%E7%88%B1%2F%E5%91%A8%E6%85%A7%E6%95%8F%20-%20%E6%9C%80%E7%88%B1.flac&metadata=false Dec 11 15:14:14 volumio volumio[9558]: info: URI /mnt/NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac Dec 11 15:14:14 volumio volumio[9558]: info: ALBUMART /albumart?cacheid=642&web=%E5%91%A8%E6%85%A7%E6%95%8F/%E6%9C%80%E7%88%B1/extralarge&path=%2Fmnt%2FNAS%2FFNOS%2Fuser%2Fmusic%2F%E5%91%A8%E6%85%A7%E6%95%8F%2F%E6%9C%80%E7%88%B1%2F%E5%91%A8%E6%85%A7%E6%95%8F%20-%20%E6%9C%80%E7%88%B1.flac&metadata=false Dec 11 15:14:14 volumio volumio[9558]: info: URI /mnt/NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac Dec 11 15:14:14 volumio volumio[9558]: info: Dec 11 15:14:14 volumio volumio[9558]: ---------------------------- MPD announces system playlist update Dec 11 15:14:14 volumio volumio[9558]: info: Ignoring MPD Status Update Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand play Dec 11 15:14:14 volumio volumio[9558]: info: CorePlayQueue::saveQueue Dec 11 15:14:14 volumio volumio[9558]: info: CoreCommandRouter::volumioPushQueue Dec 11 15:14:14 volumio volumio[9558]: info: CorePlayQueue::saveQueue Dec 11 15:14:14 volumio volumio[9558]: info: CoreCommandRouter::volumioPushQueue Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::updateTrackBlock Dec 11 15:14:14 volumio volumio[9558]: info: CorePlayQueue::getTrackBlock Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::updateTrackBlock Dec 11 15:14:14 volumio volumio[9558]: info: CorePlayQueue::getTrackBlock Dec 11 15:14:14 volumio volumio[9558]: info: CoreCommandRouter::volumioPlay Dec 11 15:14:14 volumio volumio[9558]: UNSET VOLATILE Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::play index 0 Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 15:14:14 volumio volumio[9558]: info: CoreCommandRouter::volumioPlay Dec 11 15:14:14 volumio volumio[9558]: UNSET VOLATILE Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::play index 0 Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::stop Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 15:14:14 volumio volumio[9558]: UNSET VOLATILE Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::stop Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 15:14:14 volumio volumio[9558]: UNSET VOLATILE Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::play index undefined Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::play index undefined Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 15:14:14 volumio volumio[9558]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::startPlaybackTimer Dec 11 15:14:14 volumio volumio[9558]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::clearAddPlayTracks NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 15:14:14 volumio volumio[9558]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:14 volumio volumio[9558]: info: CoreStateMachine::startPlaybackTimer Dec 11 15:14:14 volumio volumio[9558]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::clearAddPlayTracks NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 15:14:14 volumio volumio[9558]: info: Dec 11 15:14:14 volumio volumio[9558]: ---------------------------- MPD announces state update: player Dec 11 15:14:14 volumio volumio[9558]: info: ------------------------------ 63ms Dec 11 15:14:14 volumio volumio[9558]: info: ControllerMpd::getState Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand status Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 15:14:14 volumio volumio[9558]: info: Dec 11 15:14:14 volumio volumio[9558]: ---------------------------- MPD announces system playlist update Dec 11 15:14:14 volumio volumio[9558]: info: Ignoring MPD Status Update Dec 11 15:14:14 volumio volumio[9558]: info: Dec 11 15:14:14 volumio volumio[9558]: ---------------------------- MPD announces system playlist update Dec 11 15:14:14 volumio volumio[9558]: info: Ignoring MPD Status Update Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::parseState Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand add "NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac" Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand add "NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac" Dec 11 15:14:14 volumio volumio[9558]: error: updateQueue error: null Dec 11 15:14:14 volumio volumio[9558]: error: updateQueue error: null Dec 11 15:14:14 volumio volumio[9558]: info: Dec 11 15:14:14 volumio volumio[9558]: ---------------------------- MPD announces system playlist update Dec 11 15:14:14 volumio volumio[9558]: info: Ignoring MPD Status Update Dec 11 15:14:14 volumio volumio[9558]: info: Dec 11 15:14:14 volumio volumio[9558]: ---------------------------- MPD announces system playlist update Dec 11 15:14:14 volumio volumio[9558]: info: Ignoring MPD Status Update Dec 11 15:14:14 volumio volumio[9558]: info: ------------------------------ 21ms Dec 11 15:14:14 volumio volumio[9558]: info: ------------------------------ 20ms Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::parseTrackInfo Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand play Dec 11 15:14:14 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand play Dec 11 15:14:14 volumio volumio[9558]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 11 15:14:14 volumio volumio[9558]: info: ------------------------------ 48ms Dec 11 15:14:14 volumio volumio[9558]: info: ------------------------------ 17ms Dec 11 15:14:14 volumio volumio[9558]: info: ------------------------------ 15ms Dec 11 15:14:15 volumio volumio[9558]: info: Dec 11 15:14:15 volumio volumio[9558]: ---------------------------- MPD announces state update: player Dec 11 15:14:15 volumio volumio[9558]: info: ControllerMpd::getState Dec 11 15:14:15 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand status Dec 11 15:14:15 volumio volumio[9558]: info: Dec 11 15:14:15 volumio volumio[9558]: ---------------------------- MPD announces state update: player Dec 11 15:14:15 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 15:14:15 volumio volumio[9558]: info: ControllerMpd::getState Dec 11 15:14:15 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand status Dec 11 15:14:15 volumio volumio[9558]: verbose: ControllerMpd::parseState Dec 11 15:14:15 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 15:14:15 volumio volumio[9558]: verbose: ControllerMpd::parseState Dec 11 15:14:15 volumio volumio[9558]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 15:14:15 volumio volumio[9558]: verbose: ControllerMpd::parseTrackInfo Dec 11 15:14:15 volumio volumio[9558]: info: ControllerMpd::pushState Dec 11 15:14:15 volumio volumio[9558]: info: CoreCommandRouter::servicePushState Dec 11 15:14:15 volumio volumio[9558]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:15 volumio volumio[9558]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"最爱","artist":"周慧敏","album":"最爱","uri":"NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac","trackType":"flac"} Dec 11 15:14:15 volumio volumio[9558]: verbose: CURRENT POSITION 0 Dec 11 15:14:15 volumio volumio[9558]: info: CoreStateMachine::syncState stateService pause Dec 11 15:14:15 volumio volumio[9558]: info: CoreStateMachine::syncState currentStatus stop Dec 11 15:14:15 volumio volumio[9558]: info: CoreStateMachine::pushState Dec 11 15:14:15 volumio volumio[9558]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:15 volumio volumio[9558]: info: CoreCommandRouter::volumioPushState Dec 11 15:14:15 volumio volumio[9558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 15:14:15 volumio volumio[9558]: info: ------------------------------ 50ms Dec 11 15:14:15 volumio volumio[9558]: verbose: ControllerMpd::parseTrackInfo Dec 11 15:14:15 volumio volumio[9558]: info: ControllerMpd::pushState Dec 11 15:14:15 volumio volumio[9558]: info: CoreCommandRouter::servicePushState Dec 11 15:14:15 volumio volumio[9558]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:15 volumio volumio[9558]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"最爱","artist":"周慧敏","album":"最爱","uri":"NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac","trackType":"flac"} Dec 11 15:14:15 volumio volumio[9558]: verbose: CURRENT POSITION 0 Dec 11 15:14:15 volumio volumio[9558]: info: CoreStateMachine::syncState stateService pause Dec 11 15:14:15 volumio volumio[9558]: info: CoreStateMachine::syncState currentStatus stop Dec 11 15:14:15 volumio volumio[9558]: info: CoreStateMachine::pushState Dec 11 15:14:15 volumio volumio[9558]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:15 volumio volumio[9558]: info: CoreCommandRouter::volumioPushState Dec 11 15:14:15 volumio volumio[9558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 15:14:15 volumio volumio[9558]: info: ------------------------------ 66ms Dec 11 15:14:19 volumio volumio[9558]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 15:14:19 volumio volumio[9558]: { Error: certificate is not yet valid Dec 11 15:14:19 volumio volumio[9558]: at TLSSocket. (_tls_wrap.js:1105:38) Dec 11 15:14:19 volumio volumio[9558]: at emitNone (events.js:106:13) Dec 11 15:14:19 volumio volumio[9558]: at TLSSocket.emit (events.js:208:7) Dec 11 15:14:19 volumio volumio[9558]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Dec 11 15:14:19 volumio volumio[9558]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Dec 11 15:14:19 volumio volumio[9558]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 15:14:19 volumio sudo[9854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2020-12-11 15:13 Dec 11 15:14:19 volumio sudo[9854]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:19 volumio sudo[9854]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:21 volumio avahi-daemon[4634]: Invalid response packet from host 192.168.1.115. Dec 11 15:14:21 volumio avahi-daemon[4634]: Invalid response packet from host 192.168.1.115. Dec 11 15:14:22 volumio avahi-daemon[4634]: Invalid response packet from host 192.168.1.115. Dec 11 15:14:22 volumio volumio-remote-updater[4536]: [2020-12-11 15:14:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 11 15:14:22 volumio volumio-remote-updater[4536]: [2020-12-11 15:14:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 11 15:14:22 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Dec 11 15:14:22 volumio systemd[1]: Unit volumio.service entered failed state. Dec 11 15:14:22 volumio systemd[1]: Starting dynamicswap service... Dec 11 15:14:22 volumio systemd[1]: Started dynamicswap service. Dec 11 15:14:22 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Dec 11 15:14:22 volumio systemd[1]: Starting dynamicswap service... Dec 11 15:14:22 volumio systemd[1]: Started dynamicswap service. Dec 11 15:14:22 volumio systemd[1]: Stopping Volumio Backend Module... Dec 11 15:14:22 volumio systemd[1]: Starting Volumio Backend Module... Dec 11 15:14:22 volumio systemd[1]: Started Volumio Backend Module. Dec 11 15:14:24 volumio volumio[9885]: info: ------------------------------------------- Dec 11 15:14:24 volumio volumio[9885]: info: ----- Volumio2 ---- Dec 11 15:14:24 volumio volumio[9885]: info: ------------------------------------------- Dec 11 15:14:24 volumio volumio[9885]: info: ----- System startup ---- Dec 11 15:14:24 volumio volumio[9885]: info: ------------------------------------------- Dec 11 15:14:25 volumio volumio[9885]: info: MYVOLUMIO Environment detected Dec 11 15:14:25 volumio volumio[9885]: info: Plugin folders cleanup Dec 11 15:14:25 volumio volumio[9885]: info: Scanning into folder /volumio/app/plugins/ Dec 11 15:14:25 volumio volumio[9885]: info: Scanning category audio_interface Dec 11 15:14:25 volumio volumio[9885]: info: Scanning category miscellanea Dec 11 15:14:25 volumio volumio[9885]: info: Scanning category music_service Dec 11 15:14:25 volumio volumio[9885]: info: Scanning category plugins.json Dec 11 15:14:25 volumio volumio[9885]: info: Scanning category system_controller Dec 11 15:14:25 volumio volumio[9885]: info: Scanning category user_interface Dec 11 15:14:25 volumio volumio[9885]: info: Scanning into folder /data/plugins/ Dec 11 15:14:25 volumio volumio[9885]: info: Scanning category accessory Dec 11 15:14:25 volumio volumio[9885]: info: Scanning category miscellanea Dec 11 15:14:25 volumio volumio[9885]: info: Scanning category music_service Dec 11 15:14:25 volumio volumio[9885]: info: Plugin folders cleanup completed Dec 11 15:14:25 volumio volumio[9885]: info: ------------------------------------------- Dec 11 15:14:25 volumio volumio[9885]: info: ----- Core plugins startup ---- Dec 11 15:14:25 volumio volumio[9885]: info: ------------------------------------------- Dec 11 15:14:25 volumio volumio[9885]: info: Loading plugins from folder /volumio/app/plugins/ Dec 11 15:14:25 volumio volumio[9885]: info: Adding plugin upnp to MyMusic Plugins Dec 11 15:14:25 volumio volumio[9885]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 11 15:14:25 volumio volumio[9885]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 11 15:14:25 volumio volumio[9885]: info: Loading plugins from folder /data/plugins/ Dec 11 15:14:25 volumio volumio[9885]: info: Loading plugin "system"... Dec 11 15:14:25 volumio volumio[9885]: info: Loading plugin "appearance"... Dec 11 15:14:26 volumio avahi-daemon[4634]: Invalid response packet from host 192.168.1.115. Dec 11 15:14:26 volumio avahi-daemon[4634]: Invalid response packet from host 192.168.1.115. Dec 11 15:14:26 volumio avahi-daemon[4634]: Invalid response packet from host 192.168.1.115. Dec 11 15:14:26 volumio volumio[9885]: info: Loading plugin "network"... Dec 11 15:14:27 volumio volumio[9885]: info: Refreshing Cached IP Addresses Dec 11 15:14:27 volumio sudo[9907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 11 15:14:27 volumio sudo[9907]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:27 volumio sudo[9907]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:27 volumio volumio[9885]: info: Loading plugin "services"... Dec 11 15:14:27 volumio volumio[9885]: info: Loading plugin "alsa_controller"... Dec 11 15:14:27 volumio sudo[9910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 15:14:27 volumio sudo[9910]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:27 volumio sudo[9910]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:27 volumio nmbd[4802]: [2020/12/11 15:14:27.210234, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 11 15:14:27 volumio nmbd[4802]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.117 for name WORKGROUP<1d>. Dec 11 15:14:27 volumio nmbd[4802]: This response was from IP 192.168.1.192, reporting an IP address of 192.168.1.192. Dec 11 15:14:27 volumio volumio[9885]: info: Loading plugin "wizard"... Dec 11 15:14:27 volumio volumio[9885]: info: Loading plugin "volumio_command_line_client"... Dec 11 15:14:27 volumio volumio[9885]: info: Loading plugin "upnp"... Dec 11 15:14:27 volumio volumio[9885]: info: [1607699667368] Starting Upmpd Daemon Dec 11 15:14:27 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 11 15:14:27 volumio volumio[9885]: info: Loading plugin "my_music"... Dec 11 15:14:27 volumio volumio[9885]: info: Loading plugin "mpd"... Dec 11 15:14:27 volumio volumio-remote-updater[4536]: [2020-12-11 15:14:27] [connect] Successful connection Dec 11 15:14:27 volumio volumio[9885]: info: Loading plugin "upnp_browser"... Dec 11 15:14:28 volumio volumio[9885]: info: Loading plugin "networkfs"... Dec 11 15:14:28 volumio volumio[9885]: info: Cannot mount NAS FNOS at system boot, trial number 1 ,retrying in 5 seconds Dec 11 15:14:28 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 11 15:14:28 volumio volumio[9885]: info: Loading plugin "alarm-clock"... Dec 11 15:14:28 volumio volumio[9885]: info: Loading plugin "airplay_emulation"... Dec 11 15:14:28 volumio volumio[9885]: info: Starting Shairport Sync Dec 11 15:14:28 volumio volumio[9885]: info: Loading plugin "last_100"... Dec 11 15:14:28 volumio volumio[9885]: info: Loading plugin "webradio"... Dec 11 15:14:28 volumio volumio[9885]: info: Loading plugin "i2s_dacs"... Dec 11 15:14:28 volumio volumio[9885]: info: I2S DAC not set, start Auto-detection Dec 11 15:14:28 volumio volumio[9885]: info: Loading plugin "volumiodiscovery"... Dec 11 15:14:28 volumio volumio[9885]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 11 15:14:28 volumio node[9885]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 11 15:14:28 volumio node[9885]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 11 15:14:28 volumio node[9885]: *** WARNING *** For more information see Dec 11 15:14:28 volumio node[9885]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 11 15:14:28 volumio node[9885]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 11 15:14:28 volumio node[9885]: *** WARNING *** For more information see Dec 11 15:14:28 volumio volumio[9885]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 11 15:14:28 volumio volumio[9885]: *** WARNING *** For more information see Dec 11 15:14:28 volumio volumio[9885]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 11 15:14:28 volumio volumio[9885]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 11 15:14:28 volumio volumio[9885]: *** WARNING *** For more information see Dec 11 15:14:28 volumio volumio[9885]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 11 15:14:28 volumio volumio[9885]: Discovery: StartAdv! undefined Dec 11 15:14:28 volumio volumio[9885]: Discovery: Started advertising... Volumio - undefined Dec 11 15:14:28 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 11 15:14:28 volumio volumio[9885]: info: Loading plugin "autostart"... Dec 11 15:14:29 volumio volumio[9885]: info: Applying required configuration parameters for plugin autostart Dec 11 15:14:29 volumio volumio[9885]: info: Loading plugin "outputs"... Dec 11 15:14:29 volumio volumio[9885]: info: Loading plugin "albumart"... Dec 11 15:14:29 volumio volumio[9885]: info: Plugin example_plugin is not enabled Dec 11 15:14:29 volumio volumio[9885]: info: Loading plugin "inputs"... Dec 11 15:14:29 volumio volumio[9885]: info: Loading plugin "updater_comm"... Dec 11 15:14:29 volumio volumio[9885]: info: Plugin mpdemulation is not enabled Dec 11 15:14:29 volumio volumio[9885]: info: Loading plugin "rest_api"... Dec 11 15:14:29 volumio volumio[9885]: info: Loading plugin "websocket"... Dec 11 15:14:29 volumio volumio[9885]: info: Loading plugin "ir_controller"... Dec 11 15:14:29 volumio volumio[9885]: Forking 3 albumart workers Dec 11 15:14:29 volumio volumio[9885]: info: Applying required configuration parameters for plugin ir_controller Dec 11 15:14:29 volumio volumio[9885]: info: Loading plugin "RoonBridge"... Dec 11 15:14:30 volumio volumio[9885]: info: Applying required configuration parameters for plugin RoonBridge Dec 11 15:14:30 volumio volumio[9885]: info: ___________ START PLUGINS ___________ Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 11 15:14:30 volumio volumio[9885]: info: [1607699670133] CoreMusicLibrary::Adding element Media Servers Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 15:14:30 volumio volumio[9885]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 15:14:30 volumio volumio[9885]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 11 15:14:30 volumio volumio[9885]: info: [1607699670279] CoreMusicLibrary::Adding element Last_100 Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 11 15:14:30 volumio volumio[9885]: info: [1607699670285] CoreMusicLibrary::Adding element Webradio Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 15:14:30 volumio volumio[9885]: info: Loading i18n strings for locale zh Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 15:14:30 volumio volumio[9885]: info: ------------------------------------------- Dec 11 15:14:30 volumio volumio[9885]: info: ----- MyVolumio plugins startup ---- Dec 11 15:14:30 volumio volumio[9885]: info: ------------------------------------------- Dec 11 15:14:30 volumio volumio[9885]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 11 15:14:30 volumio volumio[9885]: info: Loading i18n strings for locale zh Dec 11 15:14:30 volumio sudo[9962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/hardware.conf /etc/lirc/lircd.conf /etc/lirc/lircmd.conf /etc/lirc/lircrc Dec 11 15:14:30 volumio sudo[9962]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:30 volumio sudo[9963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Dec 11 15:14:30 volumio volumio[9885]: Updating browse sources language Dec 11 15:14:30 volumio volumio[9885]: Cannot find translation for sourceMedia Servers Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 15:14:30 volumio sudo[9963]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::initPlayerControls Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 11 15:14:30 volumio sudo[9963]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:30 volumio sudo[9962]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 15:14:30 volumio volumio[9885]: info: BOOT COMPLETED Dec 11 15:14:30 volumio volumio[9885]: [Metrics] CommandRouter: 5s 842.26ms Dec 11 15:14:30 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 15:14:30 volumio volumio[9885]: Starting albumart workers Dec 11 15:14:30 volumio kernel: aml_spdif_dai: aml_hw_iec958_init,runtime->rate=44100, same source mode(0) Dec 11 15:14:30 volumio kernel: aml_spdif_dai: set normal 512 fs /4 fs Dec 11 15:14:30 volumio kernel: aml_spdif_dai: iec958 mode PCM16 Dec 11 15:14:30 volumio kernel: aml_audio_hw: IEC958 16bit Dec 11 15:14:30 volumio kernel: hdmitx: audio: aout notify rate 44100 Dec 11 15:14:30 volumio kernel: hdmitx: audio: aout notify size 16 Dec 11 15:14:30 volumio kernel: hdmitx: audio: no update Dec 11 15:14:30 volumio kernel: aml_spdif_dai: aiu 958 playback enable Dec 11 15:14:30 volumio volumio[9885]: Starting albumart workers Dec 11 15:14:30 volumio volumio[9885]: Starting albumart workers Dec 11 15:14:35 volumio kernel: aml_spdif_dai: aiu 958 playback disable Dec 11 15:14:35 volumio volumio[9885]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Dec 11 15:14:35 volumio volumio[9885]: info: CoreCommandRouter::Close All Modals sent Dec 11 15:14:35 volumio volumio[9885]: info: CoreCommandRouter::Close All Modals sent Dec 11 15:14:35 volumio volumio[9885]: Express server listening on port 3000 Dec 11 15:14:35 volumio volumio[9885]: [Metrics] WebUI: 11s 703.14ms Dec 11 15:14:35 volumio volumio[9885]: Volumio Calling Home Dec 11 15:14:35 volumio volumio[9885]: info: CoreStateMachine::resetVolumioState Dec 11 15:14:35 volumio volumio[9885]: info: CoreStateMachine::getcurrentVolume Dec 11 15:14:35 volumio volumio[9885]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 15:14:36 volumio volumio[9885]: info: Cannot mount NAS FNOS at system boot, trial number 2 ,retrying in 5 seconds Dec 11 15:14:36 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 15:14:36 volumio volumio[9885]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 11 15:14:36 volumio volumio[9885]: Unable to parse: Dec 11 15:14:36 volumio volumio[9885]: Simple mixer control 'Audio i2s mute',0 Dec 11 15:14:36 volumio volumio[9885]: Capabilities: pswitch pswitch-joined Dec 11 15:14:36 volumio volumio[9885]: Playback channels: Mono Dec 11 15:14:36 volumio volumio[9885]: Mono: Playback [on] Dec 11 15:14:36 volumio volumio[9885]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 11 15:14:36 volumio volumio[9885]: info: Setting Device type: Amlogic Dec 11 15:14:36 volumio volumio[9885]: error: Cannot start Roon Bridge Error: Command failed: /usr/bin/sudo /bin/systemctl start roonbridge.service Dec 11 15:14:36 volumio volumio[9885]: Failed to start roonbridge.service: Unit roonbridge.service failed to load: No such file or directory. Dec 11 15:14:36 volumio volumio[9885]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Dec 11 15:14:36 volumio volumio[9885]: info: MPD running with PID5957 Dec 11 15:14:36 volumio volumio[9885]: ,establishing connection Dec 11 15:14:36 volumio sudo[10010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Dec 11 15:14:36 volumio sudo[10010]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:36 volumio sudo[10010]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:36 volumio volumio[9885]: Unable to parse: Dec 11 15:14:36 volumio volumio[9885]: Simple mixer control 'Audio i2s mute',0 Dec 11 15:14:36 volumio volumio[9885]: Capabilities: pswitch pswitch-joined Dec 11 15:14:36 volumio volumio[9885]: Playback channels: Mono Dec 11 15:14:36 volumio volumio[9885]: Mono: Playback [on] Dec 11 15:14:36 volumio volumio[9885]: info: VolumeController:: Volume=undefined Mute =false Dec 11 15:14:36 volumio volumio[9885]: info: CoreStateMachine::pushState Dec 11 15:14:36 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:36 volumio volumio[9885]: info: CoreCommandRouter::volumioPushState Dec 11 15:14:36 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 15:14:36 volumio volumio[9885]: info: CoreStateMachine::updateTrackBlock Dec 11 15:14:36 volumio volumio[9885]: info: CorePlayQueue::getTrackBlock Dec 11 15:14:36 volumio volumio[9885]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 15:14:37 volumio volumio[9885]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Dec 11 15:14:37 volumio volumio[9885]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 11 15:14:37 volumio volumio[9885]: info: Reloading queue from file Dec 11 15:14:37 volumio volumio[9885]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E6%85%A7%E6%95%8F/%E6%9C%80%E7%88%B1/8c488bd1-e4ed-4ca5-a8a8-ac3451348724.jpg' Dec 11 15:14:37 volumio volumio[9885]: error: Failed LSINFO: Error: [50@0] {} No such directory Dec 11 15:14:37 volumio volumio[9885]: Unable to parse: Dec 11 15:14:37 volumio volumio[9885]: Simple mixer control 'Audio i2s mute',0 Dec 11 15:14:37 volumio volumio[9885]: Capabilities: pswitch pswitch-joined Dec 11 15:14:37 volumio volumio[9885]: Playback channels: Mono Dec 11 15:14:37 volumio volumio[9885]: Mono: Playback [on] Dec 11 15:14:37 volumio volumio[9885]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 11 15:14:37 volumio volumio[9885]: info: CoreStateMachine::setRepeat null single undefined Dec 11 15:14:37 volumio volumio[9885]: info: CoreStateMachine::pushState Dec 11 15:14:37 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::volumioPushState Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 15:14:37 volumio volumio[9885]: info: CoreStateMachine::setRandom null Dec 11 15:14:37 volumio volumio[9885]: info: CoreStateMachine::pushState Dec 11 15:14:37 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::volumioPushState Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::volumioGetState Dec 11 15:14:37 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::volumioGetQueue Dec 11 15:14:37 volumio volumio[9885]: info: CoreStateMachine::getQueue Dec 11 15:14:37 volumio volumio[9885]: info: CorePlayQueue::getQueue Dec 11 15:14:37 volumio volumio[9885]: info: Listing playlists Dec 11 15:14:37 volumio sudo[10018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 11 15:14:37 volumio sudo[10018]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:37 volumio sudo[10018]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:37 volumio sudo[10020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 15:14:37 volumio sudo[10020]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:37 volumio sudo[10020]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:37 volumio volumio[9885]: Unable to parse: Dec 11 15:14:37 volumio volumio[9885]: Simple mixer control 'Audio i2s mute',0 Dec 11 15:14:37 volumio volumio[9885]: Capabilities: pswitch pswitch-joined Dec 11 15:14:37 volumio volumio[9885]: Playback channels: Mono Dec 11 15:14:37 volumio volumio[9885]: Mono: Playback [on] Dec 11 15:14:37 volumio volumio[9885]: info: VolumeController:: Volume=undefined Mute =false Dec 11 15:14:37 volumio volumio[9885]: info: CoreStateMachine::pushState Dec 11 15:14:37 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::volumioPushState Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 15:14:37 volumio volumio[9885]: info: Starting Shairport Sync Dec 11 15:14:37 volumio volumio[9885]: info: Starting Shairport Sync Dec 11 15:14:37 volumio sudo[10024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 15:14:37 volumio sudo[10024]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 11 15:14:37 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Dec 11 15:14:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 11 15:14:37 volumio sudo[10024]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:37 volumio sudo[10026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 15:14:37 volumio sudo[10026]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 11 15:14:37 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Dec 11 15:14:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 11 15:14:37 volumio sudo[10026]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:37 volumio volumio[9885]: info: ir_controller: LIRC correctly updated. Dec 11 15:14:37 volumio volumio[9885]: info: Shairport-Sync Started Dec 11 15:14:37 volumio volumio[9885]: Error adding Membership: Error: addMembership EINVAL Dec 11 15:14:37 volumio volumio[9885]: Discovery: adding 79bfc2c3-d29c-4839-9b52-f907310bef76 Dec 11 15:14:37 volumio volumio[9885]: info: mDNS: Found device Volumio Dec 11 15:14:37 volumio volumio[9885]: info: CoreCommandRouter::volumioGetState Dec 11 15:14:37 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:38 volumio volumio[9885]: info: Shairport-Sync Started Dec 11 15:14:38 volumio volumio[9885]: info: CoreCommandRouter::volumioGetState Dec 11 15:14:38 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:38 volumio sudo[10046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 11 15:14:38 volumio sudo[10046]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:38 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 11 15:14:38 volumio sudo[10046]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:38 volumio sudo[10049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 11 15:14:38 volumio sudo[10049]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:38 volumio volumio[9885]: info: Upmpdcli Daemon Started Dec 11 15:14:38 volumio sudo[10049]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:38 volumio sudo[10051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 15:14:38 volumio sudo[10051]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:38 volumio sudo[10051]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:38 volumio volumio[9885]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 15:14:38 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 15:14:38 volumio volumio[9885]: info: CoreCommandRouter::volumioGetState Dec 11 15:14:38 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:38 volumio volumio[9885]: info: CoreCommandRouter::volumioGetQueue Dec 11 15:14:38 volumio volumio[9885]: info: CoreStateMachine::getQueue Dec 11 15:14:38 volumio volumio[9885]: info: CorePlayQueue::getQueue Dec 11 15:14:38 volumio volumio[9885]: info: Listing playlists Dec 11 15:14:38 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 15:14:38 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 11 15:14:38 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 11 15:14:38 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 11 15:14:38 volumio volumio[9885]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E6%85%A7%E6%95%8F/%E6%9C%80%E7%88%B1/8c488bd1-e4ed-4ca5-a8a8-ac3451348724.jpg' Dec 11 15:14:38 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 11 15:14:38 volumio sudo[10061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lirc.service Dec 11 15:14:38 volumio sudo[10061]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:38 volumio systemd[1]: Stopping LSB: Starts LIRC daemon.... Dec 11 15:14:39 volumio lircd-0.9.0-pre1[9773]: removed client Dec 11 15:14:39 volumio lirc[10063]: Stopping execution daemon: irexec:. Dec 11 15:14:39 volumio lircd-0.9.0-pre1[9773]: caught signal Dec 11 15:14:39 volumio lirc[10063]: Stopping remote control daemon(s): LIRC:. Dec 11 15:14:39 volumio systemd[1]: Stopped LSB: Starts LIRC daemon.. Dec 11 15:14:39 volumio sudo[10061]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:39 volumio volumio[9885]: info: ir_controller: systemctl stop lirc.service succeeded. Dec 11 15:14:39 volumio volumio-remote-updater[4536]: [2020-12-11 15:14:39] [connect] Successful connection Dec 11 15:14:39 volumio volumio-remote-updater[4536]: [2020-12-11 15:14:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1607699679 101 Dec 11 15:14:40 volumio sudo[10079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start lirc.service Dec 11 15:14:40 volumio sudo[10079]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:14:40 volumio systemd[1]: Starting LSB: Starts LIRC daemon.... Dec 11 15:14:40 volumio lirc[10081]: Loading LIRC modules:. Dec 11 15:14:40 volumio kernel: input: lircd as /devices/virtual/input/input7 Dec 11 15:14:40 volumio lircd-0.9.0-pre1[10093]: lircd(default) ready, using /var/run/lirc/lircd Dec 11 15:14:40 volumio lirc[10081]: Starting remote control daemon(s) : LIRC :. Dec 11 15:14:40 volumio lircd-0.9.0-pre1[10093]: accepted new client on /var/run/lirc/lircd Dec 11 15:14:40 volumio lirc[10081]: Starting execution daemon: irexec:. Dec 11 15:14:40 volumio systemd[1]: Started LSB: Starts LIRC daemon.. Dec 11 15:14:40 volumio sudo[10079]: pam_unix(sudo:session): session closed for user root Dec 11 15:14:40 volumio volumio[9885]: info: ir_controller: systemctl start lirc.service succeeded. Dec 11 15:14:40 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 15:14:41 volumio volumio[9885]: info: Cannot mount NAS FNOS at system boot, trial number 3 ,retrying in 5 seconds Dec 11 15:14:41 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 11 15:14:41 volumio volumio[9885]: info: CURURI: music-library Dec 11 15:14:42 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 11 15:14:42 volumio volumio[9885]: info: CURURI: music-library/NAS Dec 11 15:14:47 volumio volumio[9885]: info: Cannot mount NAS FNOS at system boot, trial number 4 ,retrying in 5 seconds Dec 11 15:14:47 volumio volumio[9885]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=a487c6ed8a8db06c5ffb65c3b3fa3670" http://updates.volumio.org:7070/downloader-v1/track-device Dec 11 15:14:47 volumio volumio[9885]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 11 15:14:47 volumio volumio[9885]: Dload Upload Total Spent Left Speed Dec 11 15:14:47 volumio volumio[9885]: [235B blob data] Dec 11 15:14:47 volumio volumio[9885]: retrying in 5 seconds, trial 0 Dec 11 15:14:47 volumio volumio[9885]: Volumio Calling Home Dec 11 15:14:47 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 11 15:14:47 volumio volumio[9885]: info: CURURI: music-library/NAS/FNOS Dec 11 15:14:50 volumio volumio[9885]: info: AutoStart - getting queue Dec 11 15:14:50 volumio volumio[9885]: info: CoreCommandRouter::volumioGetQueue Dec 11 15:14:50 volumio volumio[9885]: info: CoreStateMachine::getQueue Dec 11 15:14:50 volumio volumio[9885]: info: CorePlayQueue::getQueue Dec 11 15:14:50 volumio volumio[9885]: info: AutoStart - start playing -> queue is not empty Dec 11 15:14:50 volumio volumio[9885]: info: CoreCommandRouter::volumioPlay Dec 11 15:14:50 volumio volumio[9885]: UNSET VOLATILE Dec 11 15:14:50 volumio volumio[9885]: info: CoreStateMachine::play index undefined Dec 11 15:14:50 volumio volumio[9885]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 15:14:50 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:50 volumio volumio[9885]: info: CoreStateMachine::startPlaybackTimer Dec 11 15:14:50 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:50 volumio volumio[9885]: verbose: ControllerMpd::clearAddPlayTracks NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac Dec 11 15:14:50 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 15:14:50 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 11 15:14:50 volumio volumio[9885]: info: CURURI: music-library/NAS/FNOS/user Dec 11 15:14:51 volumio volumio[9885]: info: Dec 11 15:14:51 volumio volumio[9885]: ---------------------------- MPD announces state update: player Dec 11 15:14:51 volumio volumio[9885]: info: ControllerMpd::getState Dec 11 15:14:51 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand status Dec 11 15:14:51 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 15:14:51 volumio volumio[9885]: info: Dec 11 15:14:51 volumio volumio[9885]: ---------------------------- MPD announces system playlist update Dec 11 15:14:51 volumio volumio[9885]: info: Ignoring MPD Status Update Dec 11 15:14:51 volumio avahi-daemon[4634]: Invalid response packet from host 192.168.1.115. Dec 11 15:14:51 volumio volumio[9885]: verbose: ControllerMpd::parseState Dec 11 15:14:51 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 15:14:51 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand add "NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac" Dec 11 15:14:51 volumio volumio[9885]: error: updateQueue error: null Dec 11 15:14:51 volumio volumio[9885]: info: Dec 11 15:14:51 volumio volumio[9885]: ---------------------------- MPD announces system playlist update Dec 11 15:14:51 volumio volumio[9885]: info: Ignoring MPD Status Update Dec 11 15:14:51 volumio volumio[9885]: info: ------------------------------ 28ms Dec 11 15:14:51 volumio volumio[9885]: verbose: ControllerMpd::parseTrackInfo Dec 11 15:14:51 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand play Dec 11 15:14:51 volumio volumio[9885]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 11 15:14:51 volumio volumio[9885]: info: ------------------------------ 51ms Dec 11 15:14:51 volumio volumio[9885]: info: ------------------------------ 28ms Dec 11 15:14:51 volumio avahi-daemon[4634]: Invalid response packet from host 192.168.1.115. Dec 11 15:14:52 volumio avahi-daemon[4634]: Invalid response packet from host 192.168.1.115. Dec 11 15:14:56 volumio avahi-daemon[4634]: Invalid response packet from host 192.168.1.115. Dec 11 15:14:56 volumio avahi-daemon[4634]: Invalid response packet from host 192.168.1.115. Dec 11 15:14:56 volumio avahi-daemon[4634]: Invalid response packet from host 192.168.1.115. Dec 11 15:14:57 volumio volumio[9885]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Dec 11 15:14:57 volumio volumio[9885]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Dec 11 15:14:57 volumio volumio[9885]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Dec 11 15:14:57 volumio volumio[9885]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Dec 11 15:14:57 volumio volumio[9885]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Dec 11 15:14:57 volumio volumio[9885]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) Dec 11 15:14:57 volumio volumio[9885]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 11 15:14:57 volumio volumio[9885]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Dec 11 15:14:57 volumio volumio[9885]: at process._tickCallback (internal/process/next_tick.js:180:9) Dec 11 15:14:57 volumio volumio[9885]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Dec 11 15:14:57 volumio volumio[9885]: info: Dec 11 15:14:57 volumio volumio[9885]: ---------------------------- MPD announces state update: player Dec 11 15:14:57 volumio volumio[9885]: info: ControllerMpd::getState Dec 11 15:14:57 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand status Dec 11 15:14:57 volumio volumio[9885]: info: Dec 11 15:14:57 volumio volumio[9885]: ---------------------------- MPD announces state update: player Dec 11 15:14:57 volumio volumio[9885]: info: ControllerMpd::getState Dec 11 15:14:57 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand status Dec 11 15:14:57 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 15:14:57 volumio volumio[9885]: verbose: ControllerMpd::parseState Dec 11 15:14:57 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 15:14:57 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 15:14:57 volumio volumio[9885]: verbose: ControllerMpd::parseState Dec 11 15:14:57 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 15:14:57 volumio volumio[9885]: verbose: ControllerMpd::parseTrackInfo Dec 11 15:14:57 volumio volumio[9885]: info: ControllerMpd::pushState Dec 11 15:14:57 volumio volumio[9885]: info: CoreCommandRouter::servicePushState Dec 11 15:14:57 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:57 volumio volumio[9885]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"最爱","artist":"周慧敏","album":"最爱","uri":"NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac","trackType":"flac"} Dec 11 15:14:57 volumio volumio[9885]: verbose: CURRENT POSITION 0 Dec 11 15:14:57 volumio volumio[9885]: info: CoreStateMachine::syncState stateService pause Dec 11 15:14:57 volumio volumio[9885]: info: CoreStateMachine::syncState currentStatus stop Dec 11 15:14:57 volumio volumio[9885]: info: CoreStateMachine::pushState Dec 11 15:14:57 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:57 volumio volumio[9885]: info: CoreCommandRouter::volumioPushState Dec 11 15:14:57 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 15:14:57 volumio volumio[9885]: info: ------------------------------ 48ms Dec 11 15:14:57 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 11 15:14:57 volumio volumio[9885]: info: CURURI: music-library/NAS/FNOS/user/music Dec 11 15:14:57 volumio volumio[9885]: verbose: ControllerMpd::parseTrackInfo Dec 11 15:14:57 volumio volumio[9885]: info: ControllerMpd::pushState Dec 11 15:14:57 volumio volumio[9885]: info: CoreCommandRouter::servicePushState Dec 11 15:14:57 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:57 volumio volumio[9885]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"最爱","artist":"周慧敏","album":"最爱","uri":"NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac","trackType":"flac"} Dec 11 15:14:57 volumio volumio[9885]: verbose: CURRENT POSITION 0 Dec 11 15:14:57 volumio volumio[9885]: info: CoreStateMachine::syncState stateService pause Dec 11 15:14:57 volumio volumio[9885]: info: CoreStateMachine::syncState currentStatus stop Dec 11 15:14:57 volumio volumio[9885]: info: CoreStateMachine::pushState Dec 11 15:14:57 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:14:57 volumio volumio[9885]: info: CoreCommandRouter::volumioPushState Dec 11 15:14:57 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 15:14:57 volumio volumio[9885]: info: ------------------------------ 63ms Dec 11 15:14:57 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 11 15:14:57 volumio volumio[9885]: info: CURURI: music-library/NAS/FNOS/user/music Dec 11 15:15:11 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 15:15:11 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 15:15:11 volumio volumio[9885]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=a487c6ed8a8db06c5ffb65c3b3fa3670" http://updates.volumio.org:7070/downloader-v1/track-device Dec 11 15:15:11 volumio volumio[9885]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 11 15:15:11 volumio volumio[9885]: Dload Upload Total Spent Left Speed Dec 11 15:15:11 volumio volumio[9885]: [235B blob data] Dec 11 15:15:11 volumio volumio[9885]: retrying in 5 seconds, trial 1 Dec 11 15:15:11 volumio volumio[9885]: Volumio Calling Home Dec 11 15:15:11 volumio volumio[9885]: info: CoreCommandRouter::volumioPlay Dec 11 15:15:11 volumio volumio[9885]: UNSET VOLATILE Dec 11 15:15:11 volumio volumio[9885]: info: CoreStateMachine::play index undefined Dec 11 15:15:11 volumio volumio[9885]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 15:15:11 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:15:11 volumio volumio[9885]: info: CoreStateMachine::startPlaybackTimer Dec 11 15:15:11 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::clearAddPlayTracks NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 15:15:11 volumio volumio[9885]: info: Dec 11 15:15:11 volumio volumio[9885]: ---------------------------- MPD announces state update: player Dec 11 15:15:11 volumio volumio[9885]: info: ControllerMpd::getState Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand status Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 15:15:11 volumio volumio[9885]: info: Dec 11 15:15:11 volumio volumio[9885]: ---------------------------- MPD announces system playlist update Dec 11 15:15:11 volumio volumio[9885]: info: Ignoring MPD Status Update Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::parseState Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand add "NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac" Dec 11 15:15:11 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 11 15:15:11 volumio volumio[9885]: info: CURURI: music-library Dec 11 15:15:11 volumio volumio[9885]: error: updateQueue error: null Dec 11 15:15:11 volumio volumio[9885]: info: Dec 11 15:15:11 volumio volumio[9885]: ---------------------------- MPD announces system playlist update Dec 11 15:15:11 volumio volumio[9885]: info: Ignoring MPD Status Update Dec 11 15:15:11 volumio volumio[9885]: info: ------------------------------ 10ms Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::parseTrackInfo Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand play Dec 11 15:15:11 volumio volumio[9885]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 11 15:15:11 volumio volumio[9885]: info: ------------------------------ 21ms Dec 11 15:15:11 volumio volumio[9885]: info: ------------------------------ 10ms Dec 11 15:15:11 volumio volumio[9885]: info: Dec 11 15:15:11 volumio volumio[9885]: ---------------------------- MPD announces state update: player Dec 11 15:15:11 volumio volumio[9885]: info: ControllerMpd::getState Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand status Dec 11 15:15:11 volumio volumio[9885]: info: Dec 11 15:15:11 volumio volumio[9885]: ---------------------------- MPD announces state update: player Dec 11 15:15:11 volumio volumio[9885]: info: ControllerMpd::getState Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand status Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::parseState Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::parseState Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::parseTrackInfo Dec 11 15:15:11 volumio volumio[9885]: info: ControllerMpd::pushState Dec 11 15:15:11 volumio volumio[9885]: info: CoreCommandRouter::servicePushState Dec 11 15:15:11 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:15:11 volumio volumio[9885]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"最爱","artist":"周慧敏","album":"最爱","uri":"NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac","trackType":"flac"} Dec 11 15:15:11 volumio volumio[9885]: verbose: CURRENT POSITION 0 Dec 11 15:15:11 volumio volumio[9885]: info: CoreStateMachine::syncState stateService pause Dec 11 15:15:11 volumio volumio[9885]: info: CoreStateMachine::syncState currentStatus stop Dec 11 15:15:11 volumio volumio[9885]: info: CoreStateMachine::pushState Dec 11 15:15:11 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:15:11 volumio volumio[9885]: info: CoreCommandRouter::volumioPushState Dec 11 15:15:11 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 15:15:11 volumio volumio[9885]: info: ------------------------------ 36ms Dec 11 15:15:11 volumio volumio[9885]: verbose: ControllerMpd::parseTrackInfo Dec 11 15:15:11 volumio volumio[9885]: info: ControllerMpd::pushState Dec 11 15:15:11 volumio volumio[9885]: info: CoreCommandRouter::servicePushState Dec 11 15:15:11 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:15:11 volumio volumio[9885]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"最爱","artist":"周慧敏","album":"最爱","uri":"NAS/FNOS/user/music/周慧敏/最爱/周慧敏 - 最爱.flac","trackType":"flac"} Dec 11 15:15:11 volumio volumio[9885]: verbose: CURRENT POSITION 0 Dec 11 15:15:11 volumio volumio[9885]: info: CoreStateMachine::syncState stateService pause Dec 11 15:15:11 volumio volumio[9885]: info: CoreStateMachine::syncState currentStatus stop Dec 11 15:15:11 volumio volumio[9885]: info: CoreStateMachine::pushState Dec 11 15:15:11 volumio volumio[9885]: info: CorePlayQueue::getTrack 0 Dec 11 15:15:11 volumio volumio[9885]: info: CoreCommandRouter::volumioPushState Dec 11 15:15:11 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 15:15:11 volumio volumio[9885]: info: ------------------------------ 67ms Dec 11 15:15:14 volumio volumio[9885]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 15:15:14 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 15:15:14 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 11 15:15:14 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 11 15:15:14 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 11 15:15:14 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 15:15:14 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 15:15:14 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 15:15:14 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 15:15:14 volumio volumio[9885]: info: CoreCommandRouter::volumioGetBrowseSources Dec 11 15:15:14 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 11 15:15:14 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 11 15:15:18 volumio volumio[9885]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Dec 11 15:15:18 volumio sudo[10187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/FNOS Dec 11 15:15:18 volumio sudo[10187]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 15:15:18 volumio sudo[10187]: pam_unix(sudo:session): session closed for user root Dec 11 15:15:18 volumio volumio[9885]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 15:15:18 volumio volumio[9885]: { Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/FNOS' Dec 11 15:15:18 volumio volumio[9885]: at Object.fs.rmdirSync (fs.js:846:18) Dec 11 15:15:18 volumio volumio[9885]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Dec 11 15:15:18 volumio volumio[9885]: at ChildProcess.exithandler (child_process.js:267:7) Dec 11 15:15:18 volumio volumio[9885]: at emitTwo (events.js:126:13) Dec 11 15:15:18 volumio volumio[9885]: at ChildProcess.emit (events.js:214:7) Dec 11 15:15:18 volumio volumio[9885]: at maybeClose (internal/child_process.js:925:16) Dec 11 15:15:18 volumio volumio[9885]: at Process.ChildProcess._handle.onexit (internal/child_process.js:209:5) Dec 11 15:15:18 volumio volumio[9885]: errno: -16, Dec 11 15:15:18 volumio volumio[9885]: code: 'EBUSY', Dec 11 15:15:18 volumio volumio[9885]: syscall: 'rmdir', Dec 11 15:15:18 volumio volumio[9885]: path: '/mnt/NAS/FNOS' } Dec 11 15:15:18 volumio volumio[9885]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 15:15:19 volumio sudo[10198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2020-12-11 15:14 Dec 11 15:15:19 volumio sudo[10198]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="99dfb342fe07851f1e26bd1d8046d0234eda91dd" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 11 15:53:56 CET 2020" VOLUMIO_VERSION="2.857" VOLUMIO_HARDWARE="aml9xxxarmv7" VOLUMIO_HASH="5e595833ee6075a27fef1ba35f928abb"