-- Logs begin at Wed 2024-04-24 12:12:56 UTC, end at Sat 2024-05-04 14:22:00 UTC. -- May 04 14:21:05 volumio volumio[810]: info: CoreCommandRouter::volumioPause May 04 14:21:05 volumio volumio[810]: info: CoreStateMachine::pause May 04 14:21:05 volumio volumio[810]: info: CoreStateMachine::stPlaybackTimer May 04 14:21:05 volumio volumio[810]: info: CoreStateMachine::servicePause May 04 14:21:05 volumio volumio[810]: info: CorePlayQueue::getTrack 185 May 04 14:21:05 volumio volumio[810]: info: CoreCommandRouter::servicePause May 04 14:21:05 volumio volumio[810]: info: ControllerMpd::pause May 04 14:21:05 volumio volumio[810]: info: ControllerMpd::sendMpdCommand pause May 04 14:21:05 volumio volumio[810]: info: sending command... May 04 14:21:05 volumio volumio[810]: info: parsing response... May 04 14:21:05 volumio volumio[810]: info: May 04 14:21:05 volumio volumio[810]: ---------------------------- MPD announces state update: player May 04 14:21:05 volumio volumio[810]: info: ControllerMpd::getState May 04 14:21:05 volumio volumio[810]: info: ControllerMpd::sendMpdCommand status May 04 14:21:05 volumio volumio[810]: info: sending command... May 04 14:21:05 volumio volumio[810]: info: parsing response... May 04 14:21:05 volumio volumio[810]: info: ControllerMpd::parseState May 04 14:21:05 volumio volumio[810]: info: ControllerMpd::sendMpdCommand playlistinfo May 04 14:21:05 volumio volumio[810]: info: sending command... May 04 14:21:05 volumio volumio[810]: info: parsing response... May 04 14:21:05 volumio volumio[810]: info: ControllerMpd::parseTrackInfo May 04 14:21:05 volumio volumio[810]: info: ControllerMpd::pushState May 04 14:21:05 volumio volumio[810]: info: CoreCommandRouter::servicePushState May 04 14:21:05 volumio volumio[810]: info: CoreStateMachine::syncState May 04 14:21:05 volumio volumio[810]: info: CorePlayQueue::getTrack 185 May 04 14:21:05 volumio volumio[810]: info: STATE SERVICE {"status":"pause","position":0,"seek":37268,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"isStreaming":false,"title":"ひとり上手","artist":"中島みゆき","album":"臨月","uri":"NAS/MUSIC/iTunes Music/中島みゆき/臨月/04 ひとり上手.m4a","trackType":"m4a"} May 04 14:21:05 volumio volumio[810]: info: CURRENT POSITION 185 May 04 14:21:05 volumio volumio[810]: info: CoreStateMachine::syncState stateService pause May 04 14:21:05 volumio volumio[810]: info: CoreStateMachine::syncState currentStatus pause May 04 14:21:05 volumio volumio[810]: info: CoreStateMachine::pushState May 04 14:21:05 volumio volumio[810]: info: CoreStateMachine::getState May 04 14:21:05 volumio volumio[810]: info: CorePlayQueue::getTrack 185 May 04 14:21:05 volumio volumio[810]: info: CoreCommandRouter::volumioPushState May 04 14:21:05 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:21:05 volumio volumio[810]: info: CoreStateMachine::stPlaybackTimer May 04 14:21:05 volumio volumio[810]: info: ------------------------------ 76ms May 04 14:21:05 volumio sudo[23731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 14:21:05 volumio sudo[23731]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:21:05 volumio sudo[23731]: pam_unix(sudo:session): session closed for user root May 04 14:21:31 volumio volumio[810]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] May 04 14:21:31 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts May 04 14:21:31 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions May 04 14:21:31 volumio sudo[23912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 04 14:21:31 volumio sudo[23912]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:21:31 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:21:31 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:21:31 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:21:31 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:21:31 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:21:31 volumio sudo[23914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 04 14:21:31 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:21:31 volumio sudo[23912]: pam_unix(sudo:session): session closed for user root May 04 14:21:31 volumio sudo[23914]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 14:21:31 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 14:21:31 volumio volumio[810]: info: MPD Permissions set May 04 14:21:31 volumio systemd[1]: Stopping Music Player Daemon... May 04 14:21:31 volumio systemd[1]: Starting Music Player Daemon... May 04 14:21:31 volumio volumio[810]: error: Upnp client error: Error: This socket has been ended by the other party May 04 14:21:34 volumio volumio[810]: error: message=Cannot read property '0' of undefined, stack=TypeError: Cannot read property '0' of undefined May 04 14:21:34 volumio volumio[810]: at /volumio/app/plugins/music_service/upnp_browser/index.js:77:52 May 04 14:21:34 volumio volumio[810]: at /volumio/app/plugins/music_service/upnp_browser/index.js:625:21 May 04 14:21:34 volumio volumio[810]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:303:18) May 04 14:21:34 volumio volumio[810]: at emitOne (events.js:116:13) May 04 14:21:34 volumio volumio[810]: at Parser.emit (events.js:211:7) May 04 14:21:34 volumio volumio[810]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:261:26) May 04 14:21:34 volumio volumio[810]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 04 14:21:34 volumio volumio[810]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 04 14:21:34 volumio volumio[810]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 04 14:21:34 volumio volumio[810]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 04 14:21:34 volumio volumio[810]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:322:31) May 04 14:21:34 volumio volumio[810]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 04 14:21:34 volumio volumio[810]: at /volumio/app/plugins/music_service/upnp_browser/index.js:624:24 May 04 14:21:34 volumio volumio[810]: at Request.handleRequestResponse [as _callback] (/volumio/node_modules/unirest/index.js:463:26) May 04 14:21:34 volumio volumio[810]: at Request.self.callback (/volumio/node_modules/unirest/node_modules/request/request.js:187:22) May 04 14:21:34 volumio volumio[810]: at emitTwo (events.js:126:13) May 04 14:21:39 volumio volumio[810]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 14:21:39 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 04 14:21:39 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 14:21:39 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 04 14:21:39 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 14:21:39 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 14:21:39 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 14:21:39 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 14:21:39 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 14:21:39 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 14:21:39 volumio volumio[810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 14:21:49 volumio volumio[810]: info: CoreCommandRouter::volumioAddQueueItems May 04 14:21:49 volumio volumio[810]: info: CoreStateMachine::addQueueItems May 04 14:21:49 volumio volumio[810]: info: CorePlayQueue::addQueueItems May 04 14:21:49 volumio volumio[810]: info: Adding Item to queue: music-library/NAS/MUSIC/iTunes Music/中島みゆき/臨月/04 ひとり上手.m4a May 04 14:21:49 volumio volumio[810]: info: Exploding uri music-library/NAS/MUSIC/iTunes Music/中島みゆき/臨月/04 ひとり上手.m4a in service mpd May 04 14:21:49 volumio volumio[810]: error: MPD error: Error: This socket has been ended by the other party May 04 14:21:49 volumio volumio[810]: error: MPD error: Error: This socket has been ended by the other party May 04 14:21:49 volumio volumio[810]: error: MPD error: Error: This socket has been ended by the other party May 04 14:21:50 volumio volumio[810]: info: CoreCommandRouter::volumioAddQueueItems May 04 14:21:50 volumio volumio[810]: info: CoreStateMachine::addQueueItems May 04 14:21:50 volumio volumio[810]: info: CorePlayQueue::addQueueItems May 04 14:21:50 volumio volumio[810]: info: Adding Item to queue: music-library/NAS/MUSIC/iTunes Music/中島みゆき/臨月/04 ひとり上手.m4a May 04 14:21:50 volumio volumio[810]: info: Exploding uri music-library/NAS/MUSIC/iTunes Music/中島みゆき/臨月/04 ひとり上手.m4a in service mpd May 04 14:21:50 volumio volumio[810]: error: MPD error: Error: This socket has been ended by the other party May 04 14:21:50 volumio volumio[810]: error: MPD error: Error: This socket has been ended by the other party May 04 14:21:50 volumio volumio[810]: error: MPD error: Error: This socket has been ended by the other party May 04 14:21:52 volumio volumio[810]: info: CoreCommandRouter::volumioPlay May 04 14:21:52 volumio volumio[810]: UNSET VOLATILE May 04 14:21:52 volumio volumio[810]: info: CoreStateMachine::play index undefined May 04 14:21:52 volumio volumio[810]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:21:52 volumio volumio[810]: info: CorePlayQueue::getTrack 185 May 04 14:21:52 volumio volumio[810]: info: CoreStateMachine::startPlaybackTimer May 04 14:21:52 volumio volumio[810]: info: CorePlayQueue::getTrack 185 May 04 14:21:52 volumio volumio[810]: info: ControllerMpd::resume May 04 14:21:52 volumio volumio[810]: info: ControllerMpd::sendMpdCommand play May 04 14:21:52 volumio volumio[810]: info: sending command... May 04 14:21:52 volumio volumio[810]: error: MPD error: Error: This socket has been ended by the other party May 04 14:21:52 volumio volumio[810]: error: MPD error: Error: This socket has been ended by the other party May 04 14:21:52 volumio volumio[810]: error: MPD error: Error: This socket has been ended by the other party May 04 14:21:55 volumio volumio[810]: info: CoreCommandRouter::volumioAddQueueItems May 04 14:21:55 volumio volumio[810]: info: CoreStateMachine::addQueueItems May 04 14:21:55 volumio volumio[810]: info: CorePlayQueue::addQueueItems May 04 14:21:55 volumio volumio[810]: info: Adding Item to queue: music-library/NAS/MUSIC/iTunes Music/中島みゆき/臨月/04 ひとり上手.m4a May 04 14:21:55 volumio volumio[810]: info: Exploding uri music-library/NAS/MUSIC/iTunes Music/中島みゆき/臨月/04 ひとり上手.m4a in service mpd May 04 14:21:57 volumio volumio[810]: error: MPD error: Error: connect EAGAIN /run/mpd/socket May 04 14:21:57 volumio volumio[810]: error: MPD error: Error: connect EAGAIN /run/mpd/socket May 04 14:21:57 volumio volumio[810]: error: MPD error: Error: connect EAGAIN /run/mpd/socket May 04 14:21:57 volumio volumio[810]: info: CoreCommandRouter::volumioAddQueueItems May 04 14:21:57 volumio volumio[810]: info: CoreStateMachine::addQueueItems May 04 14:21:57 volumio volumio[810]: info: CorePlayQueue::addQueueItems May 04 14:21:57 volumio volumio[810]: info: Adding Item to queue: albums://%E4%B8%AD%E5%B3%B6%E3%81%BF%E3%82%86%E3%81%8D/%E8%87%A8%E6%9C%88 May 04 14:21:57 volumio volumio[810]: info: Exploding uri albums://%E4%B8%AD%E5%B3%B6%E3%81%BF%E3%82%86%E3%81%8D/%E8%87%A8%E6%9C%88 in service mpd May 04 14:21:57 volumio volumio[810]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 14:21:57 volumio volumio[810]: { AssertionError [ERR_ASSERTION]: false == true May 04 14:21:57 volumio volumio[810]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) May 04 14:21:57 volumio volumio[810]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2071:18) May 04 14:21:57 volumio volumio[810]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1255:22) May 04 14:21:57 volumio volumio[810]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:151:56) May 04 14:21:57 volumio volumio[810]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:239:24) May 04 14:21:57 volumio volumio[810]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:425:27) May 04 14:21:57 volumio volumio[810]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:112:36) May 04 14:21:57 volumio volumio[810]: at emitTwo (events.js:126:13) May 04 14:21:57 volumio volumio[810]: at Socket.emit (events.js:214:7) May 04 14:21:57 volumio volumio[810]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 04 14:21:57 volumio volumio[810]: at _combinedTickCallback (internal/process/next_tick.js:131:7) May 04 14:21:57 volumio volumio[810]: at process._tickCallback (internal/process/next_tick.js:180:9) May 04 14:21:58 volumio volumio[810]: generatedMessage: true, May 04 14:21:58 volumio volumio[810]: name: 'AssertionError [ERR_ASSERTION]', May 04 14:21:58 volumio volumio[810]: code: 'ERR_ASSERTION', May 04 14:21:58 volumio volumio[810]: actual: false, May 04 14:21:58 volumio volumio[810]: expected: true, May 04 14:21:58 volumio volumio[810]: operator: '==' } May 04 14:21:58 volumio volumio[810]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 14:22:00 volumio sudo[23946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-04 14:21 May 04 14:22:00 volumio sudo[23946]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="19491f50eded236de6874d31640dedebc248dfa0" VOLUMIO_FE_VERSION="75196daf676451ec8abb1bfee6fbff6aa386acf1" VOLUMIO_BE_VERSION="3d80c88edac36c39d2d15da2584cd26597f9d85c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Sep 28 15:59:39 UTC 2018" VOLUMIO_VERSION="2.457" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="d41d8cd98f00b204e9800998ecf8427e"