-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sun 2024-11-24 21:47:35 CET. -- Nov 24 21:46:01 volumio volumio[8835]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 24 21:46:01 volumio volumio[8835]: info: Completed starting MyVolumio Plugin Nov 24 21:46:01 volumio volumio[8835]: [Metrics] CommandRouter: 57s 152.45ms Nov 24 21:46:01 volumio volumio[8835]: info: CoreCommandRouter::volumiosetStartupVolume Nov 24 21:46:01 volumio volumio[8835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:46:01 volumio volumio[8835]: info: VolumeController:: Setting startup Volume 100 Nov 24 21:46:01 volumio volumio[8835]: info: VolumeController::SetAlsaVolume100 Nov 24 21:46:01 volumio volumio[8835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:46:01 volumio volumio[8835]: info: CoreCommandRouter::Close All Modals sent Nov 24 21:46:01 volumio volumio[8835]: info: CoreCommandRouter::Close All Modals sent Nov 24 21:46:01 volumio volumio[8835]: info: CoreStateMachine::pushState Nov 24 21:46:01 volumio volumio[8835]: info: CorePlayQueue::getTrack 0 Nov 24 21:46:01 volumio volumio[8835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 21:46:01 volumio volumio[8835]: info: CoreCommandRouter::volumioPushState Nov 24 21:46:01 volumio volumio[8835]: info: [ASDebug] CurState: stop PrevState: na Nov 24 21:46:01 volumio volumio[8835]: info: [ASDebug] InitTimeout - Amp off in: 10000000 ms Nov 24 21:46:01 volumio volumio[8835]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 24 21:46:02 volumio volumio[8835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 24 21:46:02 volumio volumio[8835]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 24 21:46:02 volumio volumio[8835]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 24 21:46:08 volumio volumio[8835]: info: BOOT COMPLETED Nov 24 21:46:12 volumio volumio[8835]: info: CoreCommandRouter::volumioGetVisibleSources Nov 24 21:46:12 volumio volumio[8835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 21:46:12 volumio volumio[8835]: info: CoreCommandRouter::volumioGetQueue Nov 24 21:46:12 volumio volumio[8835]: info: CoreStateMachine::getQueue Nov 24 21:46:12 volumio volumio[8835]: info: CorePlayQueue::getQueue Nov 24 21:46:49 volumio kernel: hwmon hwmon1: Voltage normalised Nov 24 21:46:51 volumio kernel: hwmon hwmon1: Undervoltage detected! Nov 24 21:47:01 volumio volumio[8835]: info: CoreCommandRouter::volumioMoveQueue Nov 24 21:47:01 volumio volumio[8835]: info: CoreStateMachine::moveQueueItem 18 18 Nov 24 21:47:01 volumio volumio[8835]: info: CorePlayQueue::moveQueueItem 18 --> 18 Nov 24 21:47:01 volumio volumio[8835]: info: CoreCommandRouter::volumioPushQueue Nov 24 21:47:33 volumio volumio[8835]: info: CoreCommandRouter::volumioNext Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::next Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::stop Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::play index undefined Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 21:47:33 volumio volumio[8835]: info: CorePlayQueue::getTrack 1 Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::startPlaybackTimer Nov 24 21:47:33 volumio volumio[8835]: info: CorePlayQueue::getTrack 1 Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Reggae/Bob Marley & The Wailers - Exodus 1977 [Hi-Res 24-96kHz]/07. Waiting In Vain.flac Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::sendMpdCommand stop Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::updateTrackBlock Nov 24 21:47:33 volumio volumio[8835]: info: CorePlayQueue::getTrackBlock Nov 24 21:47:33 volumio volumio[8835]: info: sendMpdCommand stop took 11 milliseconds Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::sendMpdCommand clear Nov 24 21:47:33 volumio volumio[8835]: info: Nov 24 21:47:33 volumio volumio[8835]: ---------------------------- MPD announces system playlist update Nov 24 21:47:33 volumio volumio[8835]: info: Ignoring MPD Status Update Nov 24 21:47:33 volumio volumio[8835]: info: sendMpdCommand clear took 5 milliseconds Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Reggae/Bob Marley & The Wailers - Exodus 1977 [Hi-Res 24-96kHz]/07. Waiting In Vain.flac" Nov 24 21:47:33 volumio volumio[8835]: info: Nov 24 21:47:33 volumio volumio[8835]: ---------------------------- MPD announces system playlist update Nov 24 21:47:33 volumio volumio[8835]: info: Ignoring MPD Status Update Nov 24 21:47:33 volumio volumio[8835]: error: updateQueue error: null Nov 24 21:47:33 volumio volumio[8835]: info: Nov 24 21:47:33 volumio volumio[8835]: ---------------------------- MPD announces system playlist update Nov 24 21:47:33 volumio volumio[8835]: info: Ignoring MPD Status Update Nov 24 21:47:33 volumio volumio[8835]: info: ------------------------------ 19ms Nov 24 21:47:33 volumio volumio[8835]: info: sendMpdCommand add "NAS/FLAC/Reggae/Bob Marley & The Wailers - Exodus 1977 [Hi-Res 24-96kHz]/07. Waiting In Vain.flac" took 14 milliseconds Nov 24 21:47:33 volumio volumio[8835]: info: ------------------------------ 12ms Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::sendMpdCommand play Nov 24 21:47:33 volumio volumio[8835]: info: Nov 24 21:47:33 volumio volumio[8835]: ---------------------------- MPD announces system playlist update Nov 24 21:47:33 volumio volumio[8835]: info: Ignoring MPD Status Update Nov 24 21:47:33 volumio volumio[8835]: info: ------------------------------ 14ms Nov 24 21:47:33 volumio volumio[8835]: info: sendMpdCommand play took 8 milliseconds Nov 24 21:47:33 volumio volumio[8835]: info: ------------------------------ 7ms Nov 24 21:47:33 volumio volumio[8835]: info: Nov 24 21:47:33 volumio volumio[8835]: ---------------------------- MPD announces state update: player Nov 24 21:47:33 volumio volumio[8835]: info: ControllerMpd::getState Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::sendMpdCommand status Nov 24 21:47:33 volumio volumio[8835]: info: Nov 24 21:47:33 volumio volumio[8835]: ---------------------------- MPD announces state update: player Nov 24 21:47:33 volumio volumio[8835]: info: ControllerMpd::getState Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::sendMpdCommand status Nov 24 21:47:33 volumio volumio[8835]: info: Nov 24 21:47:33 volumio volumio[8835]: ---------------------------- MPD announces state update: player Nov 24 21:47:33 volumio volumio[8835]: info: sendMpdCommand status took 19 milliseconds Nov 24 21:47:33 volumio volumio[8835]: info: ControllerMpd::getState Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::sendMpdCommand status Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::parseState Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 21:47:33 volumio volumio[8835]: info: sendMpdCommand status took 25 milliseconds Nov 24 21:47:33 volumio volumio[8835]: info: sendMpdCommand status took 11 milliseconds Nov 24 21:47:33 volumio volumio[8835]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::parseState Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::parseState Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::parseTrackInfo Nov 24 21:47:33 volumio volumio[8835]: info: ControllerMpd::pushState Nov 24 21:47:33 volumio volumio[8835]: info: CoreCommandRouter::servicePushState Nov 24 21:47:33 volumio volumio[8835]: info: CorePlayQueue::getTrack 1 Nov 24 21:47:33 volumio volumio[8835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waiting In Vain","artist":"Bob Marley & The Wailers","album":"Exodus","uri":"NAS/FLAC/Reggae/Bob Marley & The Wailers - Exodus 1977 [Hi-Res 24-96kHz]/07. Waiting In Vain.flac","trackType":"flac"} Nov 24 21:47:33 volumio volumio[8835]: verbose: CURRENT POSITION 1 Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::syncState stateService play Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::syncState currentStatus stop Nov 24 21:47:33 volumio volumio[8835]: info: ------------------------------ 62ms Nov 24 21:47:33 volumio volumio[8835]: info: Nov 24 21:47:33 volumio volumio[8835]: ---------------------------- MPD announces state update: player Nov 24 21:47:33 volumio volumio[8835]: info: ControllerMpd::getState Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::sendMpdCommand status Nov 24 21:47:33 volumio volumio[8835]: info: sendMpdCommand playlistinfo took 31 milliseconds Nov 24 21:47:33 volumio volumio[8835]: info: sendMpdCommand playlistinfo took 30 milliseconds Nov 24 21:47:33 volumio volumio[8835]: info: sendMpdCommand status took 6 milliseconds Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::parseTrackInfo Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::parseTrackInfo Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::parseState Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 21:47:33 volumio volumio[8835]: info: ControllerMpd::pushState Nov 24 21:47:33 volumio volumio[8835]: info: CoreCommandRouter::servicePushState Nov 24 21:47:33 volumio volumio[8835]: info: CorePlayQueue::getTrack 1 Nov 24 21:47:33 volumio volumio[8835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waiting In Vain","artist":"Bob Marley & The Wailers","album":"Exodus","uri":"NAS/FLAC/Reggae/Bob Marley & The Wailers - Exodus 1977 [Hi-Res 24-96kHz]/07. Waiting In Vain.flac","trackType":"flac"} Nov 24 21:47:33 volumio volumio[8835]: verbose: CURRENT POSITION 1 Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::syncState stateService play Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::syncState currentStatus play Nov 24 21:47:33 volumio volumio[8835]: info: Received an update from plugin. extracting info from payload Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::pushState Nov 24 21:47:33 volumio volumio[8835]: info: CorePlayQueue::getTrack 1 Nov 24 21:47:33 volumio volumio[8835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 21:47:33 volumio volumio[8835]: info: CoreCommandRouter::volumioPushState Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::pushState Nov 24 21:47:33 volumio volumio[8835]: info: CorePlayQueue::getTrack 1 Nov 24 21:47:33 volumio volumio[8835]: info: CoreCommandRouter::volumioPushState Nov 24 21:47:33 volumio volumio[8835]: info: ControllerMpd::pushState Nov 24 21:47:33 volumio volumio[8835]: info: CoreCommandRouter::servicePushState Nov 24 21:47:33 volumio volumio[8835]: info: CorePlayQueue::getTrack 1 Nov 24 21:47:33 volumio volumio[8835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waiting In Vain","artist":"Bob Marley & The Wailers","album":"Exodus","uri":"NAS/FLAC/Reggae/Bob Marley & The Wailers - Exodus 1977 [Hi-Res 24-96kHz]/07. Waiting In Vain.flac","trackType":"flac"} Nov 24 21:47:33 volumio volumio[8835]: verbose: CURRENT POSITION 1 Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::syncState stateService play Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::syncState currentStatus play Nov 24 21:47:33 volumio volumio[8835]: info: Received an update from plugin. extracting info from payload Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::pushState Nov 24 21:47:33 volumio volumio[8835]: info: CorePlayQueue::getTrack 1 Nov 24 21:47:33 volumio volumio[8835]: info: CoreCommandRouter::volumioPushState Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::pushState Nov 24 21:47:33 volumio volumio[8835]: info: CorePlayQueue::getTrack 1 Nov 24 21:47:33 volumio volumio[8835]: info: CoreCommandRouter::volumioPushState Nov 24 21:47:33 volumio volumio[8835]: info: ------------------------------ 226ms Nov 24 21:47:33 volumio volumio[8835]: info: ------------------------------ 221ms Nov 24 21:47:33 volumio volumio[8835]: info: sendMpdCommand playlistinfo took 164 milliseconds Nov 24 21:47:33 volumio volumio[8835]: verbose: ControllerMpd::parseTrackInfo Nov 24 21:47:33 volumio volumio[8835]: info: ControllerMpd::pushState Nov 24 21:47:33 volumio volumio[8835]: info: CoreCommandRouter::servicePushState Nov 24 21:47:33 volumio volumio[8835]: info: CorePlayQueue::getTrack 1 Nov 24 21:47:33 volumio volumio[8835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waiting In Vain","artist":"Bob Marley & The Wailers","album":"Exodus","uri":"NAS/FLAC/Reggae/Bob Marley & The Wailers - Exodus 1977 [Hi-Res 24-96kHz]/07. Waiting In Vain.flac","trackType":"flac"} Nov 24 21:47:33 volumio volumio[8835]: verbose: CURRENT POSITION 1 Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::syncState stateService play Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::syncState currentStatus play Nov 24 21:47:33 volumio volumio[8835]: info: Received an update from plugin. extracting info from payload Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::pushState Nov 24 21:47:33 volumio volumio[8835]: info: CorePlayQueue::getTrack 1 Nov 24 21:47:33 volumio volumio[8835]: info: CoreCommandRouter::volumioPushState Nov 24 21:47:33 volumio volumio[8835]: info: CoreStateMachine::pushState Nov 24 21:47:33 volumio volumio[8835]: info: CorePlayQueue::getTrack 1 Nov 24 21:47:33 volumio volumio[8835]: info: CoreCommandRouter::volumioPushState Nov 24 21:47:33 volumio volumio[8835]: info: ------------------------------ 263ms Nov 24 21:47:33 volumio volumio[8835]: info: [ASDebug] CurState: play PrevState: na Nov 24 21:47:33 volumio volumio[8835]: info: [ASDebug] Togle GPIO: ON Nov 24 21:47:33 volumio volumio[8835]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 21:47:33 volumio volumio[8835]: Error: ESPIPE: invalid seek, write Nov 24 21:47:33 volumio volumio[8835]: at Object.writeSync (fs.js:711:3) Nov 24 21:47:33 volumio volumio[8835]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Nov 24 21:47:33 volumio volumio[8835]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Nov 24 21:47:33 volumio volumio[8835]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Nov 24 21:47:33 volumio volumio[8835]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 24 21:47:33 volumio volumio[8835]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Nov 24 21:47:33 volumio volumio[8835]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Nov 24 21:47:33 volumio volumio[8835]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 24 21:47:33 volumio volumio[8835]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 24 21:47:33 volumio volumio[8835]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Nov 24 21:47:33 volumio volumio[8835]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 24 21:47:33 volumio volumio[8835]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 24 21:47:33 volumio volumio[8835]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Nov 24 21:47:33 volumio volumio[8835]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Nov 24 21:47:33 volumio volumio[8835]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 24 21:47:33 volumio volumio[8835]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Nov 24 21:47:33 volumio volumio[8835]: errno: -29, Nov 24 21:47:33 volumio volumio[8835]: syscall: 'write', Nov 24 21:47:33 volumio volumio[8835]: code: 'ESPIPE' Nov 24 21:47:33 volumio volumio[8835]: } Nov 24 21:47:33 volumio volumio[8835]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 21:47:35 volumio sudo[9560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 21:46 Nov 24 21:47:35 volumio sudo[9560]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"