-- Logs begin at Thu 2019-02-14 04:11:59 CST, end at Wed 2024-09-04 17:49:06 CDT. -- Sep 04 17:48:17 volumio kernel: input: 2C:3B:70:96:D7:9A as /devices/virtual/input/input8 Sep 04 17:48:18 volumio systemd-udevd[25695]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 04 17:48:18 volumio systemd-logind[666]: Watching system buttons on /dev/input/event0 (2C:3B:70:96:D7:9A) Sep 04 17:48:18 volumio volumio[1073]: BT PLUGIN MESSAGE: Duration is 0 Sep 04 17:48:20 volumio pulseaudio[1426]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Sep 04 17:48:20 volumio pulseaudio[1426]: Cannot set requested source latency of 66.67 ms, adjusting to 250.00 ms Sep 04 17:48:22 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 17:48:22 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 17:48:22 volumio volumio[1073]: info: Discovery: Getting this device information Sep 04 17:48:22 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Sep 04 17:48:22 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Sep 04 17:48:22 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 17:48:22 volumio volumio[1073]: verbose: New Socket.io Connection to 192.168.0.93:3000 from 192.168.0.12 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Sep 04 17:48:22 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Sep 04 17:48:22 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Sep 04 17:48:22 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 04 17:48:22 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 04 17:48:27 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 17:48:27 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 17:48:27 volumio volumio[1073]: info: Discovery: Getting this device information Sep 04 17:48:27 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Sep 04 17:48:27 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Sep 04 17:48:27 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 17:48:27 volumio sudo[25702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 17:48:27 volumio sudo[25702]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 17:48:27 volumio sudo[25702]: pam_unix(sudo:session): session closed for user root Sep 04 17:48:27 volumio sudo[25705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 17:48:27 volumio sudo[25705]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 17:48:27 volumio sudo[25705]: pam_unix(sudo:session): session closed for user root Sep 04 17:48:27 volumio volumio[1073]: verbose: New Socket.io Connection to 192.168.0.93 from 192.168.0.12 UA: Mozilla/5.0 (Linux; Android 14; Pixel 6a Build/AP2A.240805.005.F1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/128.0.6613.88 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 04 17:48:28 volumio volumio[1073]: info: CoreCommandRouter::volumioGetVisibleSources Sep 04 17:48:28 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 17:48:28 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 04 17:48:28 volumio volumio[1073]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 04 17:48:28 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Sep 04 17:48:28 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Sep 04 17:48:28 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 04 17:48:28 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 04 17:48:28 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 04 17:48:28 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 17:48:28 volumio sudo[25709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 17:48:28 volumio sudo[25709]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 17:48:28 volumio sudo[25709]: pam_unix(sudo:session): session closed for user root Sep 04 17:48:28 volumio sudo[25712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 17:48:28 volumio sudo[25712]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 17:48:28 volumio sudo[25712]: pam_unix(sudo:session): session closed for user root Sep 04 17:48:28 volumio volumio[1073]: verbose: New Socket.io Connection to 192.168.0.93 from 192.168.0.12 UA: Mozilla/5.0 (Linux; Android 14; Pixel 6a Build/AP2A.240805.005.F1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/128.0.6613.88 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 04 17:48:28 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Sep 04 17:48:28 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Sep 04 17:48:28 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 04 17:48:28 volumio volumio[1073]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 04 17:48:28 volumio volumio[1073]: info: Listing playlists Sep 04 17:48:28 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 04 17:48:28 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 04 17:48:28 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 04 17:48:28 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 17:48:41 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 04 17:48:41 volumio volumio[1073]: error: MRS: impossible to set dcb17e32-5d09-4f4d-b4c8-06fe6c6cdad2 volume: device not found Sep 04 17:48:41 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 04 17:48:41 volumio volumio[1073]: error: MRS: impossible to set dcb17e32-5d09-4f4d-b4c8-06fe6c6cdad2 volume: device not found Sep 04 17:48:41 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 04 17:48:41 volumio volumio[1073]: error: MRS: impossible to set dcb17e32-5d09-4f4d-b4c8-06fe6c6cdad2 volume: device not found Sep 04 17:48:41 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 04 17:48:41 volumio volumio[1073]: error: MRS: impossible to set dcb17e32-5d09-4f4d-b4c8-06fe6c6cdad2 volume: device not found Sep 04 17:48:41 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 04 17:48:41 volumio volumio[1073]: error: MRS: impossible to set dcb17e32-5d09-4f4d-b4c8-06fe6c6cdad2 volume: device not found Sep 04 17:48:41 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 04 17:48:41 volumio volumio[1073]: error: MRS: impossible to set dcb17e32-5d09-4f4d-b4c8-06fe6c6cdad2 volume: device not found Sep 04 17:48:47 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 17:48:47 volumio volumio[1073]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 04 17:48:47 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 04 17:48:47 volumio volumio[1073]: info: Received Get System Version Sep 04 17:48:47 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 17:48:47 volumio volumio[1073]: info: Received Get System Info Sep 04 17:48:47 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 17:48:47 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 17:48:47 volumio volumio[1073]: info: Discovery: Getting this device information Sep 04 17:48:47 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Sep 04 17:48:47 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Sep 04 17:48:47 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 17:48:51 volumio volumio[1073]: info: CoreCommandRouter::volumioPlay Sep 04 17:48:51 volumio volumio[1073]: info: CoreStateMachine::play index undefined Sep 04 17:48:51 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 17:48:51 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Sep 04 17:48:51 volumio volumio[1073]: info: CoreStateMachine::startPlaybackTimer Sep 04 17:48:51 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Sep 04 17:48:51 volumio volumio[1073]: verbose: ControllerMpd::clearAddPlayTracks USB/BC2C0F972C0F4BB8/Phil Collins - In the Air Tonight.mp3 Sep 04 17:48:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand stop Sep 04 17:48:51 volumio volumio[1073]: info: sendMpdCommand stop took 2 milliseconds Sep 04 17:48:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand clear Sep 04 17:48:51 volumio volumio[1073]: info: Sep 04 17:48:51 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Sep 04 17:48:51 volumio volumio[1073]: info: Ignoring MPD Status Update Sep 04 17:48:51 volumio volumio[1073]: info: sendMpdCommand clear took 1 milliseconds Sep 04 17:48:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand add "USB/BC2C0F972C0F4BB8/Phil Collins - In the Air Tonight.mp3" Sep 04 17:48:51 volumio volumio[1073]: error: updateQueue error: null Sep 04 17:48:51 volumio volumio[1073]: info: Sep 04 17:48:51 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Sep 04 17:48:51 volumio volumio[1073]: info: Ignoring MPD Status Update Sep 04 17:48:51 volumio volumio[1073]: info: ------------------------------ 4ms Sep 04 17:48:51 volumio volumio[1073]: info: sendMpdCommand add "USB/BC2C0F972C0F4BB8/Phil Collins - In the Air Tonight.mp3" took 2 milliseconds Sep 04 17:48:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand play Sep 04 17:48:51 volumio volumio[1073]: info: ------------------------------ 3ms Sep 04 17:48:51 volumio volumio[1073]: info: sendMpdCommand play took 2 milliseconds Sep 04 17:48:51 volumio volumio[1073]: info: Sep 04 17:48:51 volumio volumio[1073]: ---------------------------- MPD announces state update: player Sep 04 17:48:51 volumio volumio[1073]: info: ControllerMpd::getState Sep 04 17:48:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Sep 04 17:48:51 volumio volumio[1073]: info: Sep 04 17:48:51 volumio volumio[1073]: ---------------------------- MPD announces state update: player Sep 04 17:48:51 volumio volumio[1073]: info: sendMpdCommand status took 4 milliseconds Sep 04 17:48:51 volumio volumio[1073]: info: ControllerMpd::getState Sep 04 17:48:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Sep 04 17:48:51 volumio volumio[1073]: verbose: ControllerMpd::parseState Sep 04 17:48:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 17:48:51 volumio volumio[1073]: info: sendMpdCommand status took 3 milliseconds Sep 04 17:48:51 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 04 17:48:51 volumio volumio[1073]: verbose: ControllerMpd::parseState Sep 04 17:48:51 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 17:48:51 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Sep 04 17:48:51 volumio volumio[1073]: info: ControllerMpd::pushState Sep 04 17:48:51 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Sep 04 17:48:51 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Sep 04 17:48:51 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":309,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"In the Air Tonight","artist":"Phil Collins","album":"...Hits","uri":"USB/BC2C0F972C0F4BB8/Phil Collins - In the Air Tonight.mp3","trackType":"mp3"} Sep 04 17:48:51 volumio volumio[1073]: verbose: CURRENT POSITION 0 Sep 04 17:48:51 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Sep 04 17:48:51 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus stop Sep 04 17:48:51 volumio volumio[1073]: info: ------------------------------ 13ms Sep 04 17:48:51 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 04 17:48:51 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Sep 04 17:48:51 volumio volumio[1073]: info: ControllerMpd::pushState Sep 04 17:48:51 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Sep 04 17:48:51 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Sep 04 17:48:51 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":309,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"In the Air Tonight","artist":"Phil Collins","album":"...Hits","uri":"USB/BC2C0F972C0F4BB8/Phil Collins - In the Air Tonight.mp3","trackType":"mp3"} Sep 04 17:48:51 volumio volumio[1073]: verbose: CURRENT POSITION 0 Sep 04 17:48:51 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Sep 04 17:48:51 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Sep 04 17:48:51 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Sep 04 17:48:51 volumio volumio[1073]: info: CoreStateMachine::pushState Sep 04 17:48:51 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Sep 04 17:48:51 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 17:48:51 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Sep 04 17:48:51 volumio volumio[1073]: info: MRS: Pushing multiroomSync output update for this device Sep 04 17:48:51 volumio volumio[1073]: info: MRS: Pushing multiroomSync output Sep 04 17:48:51 volumio volumio[1073]: info: CoreStateMachine::pushState Sep 04 17:48:51 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Sep 04 17:48:51 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Sep 04 17:48:51 volumio volumio[1073]: info: MRS: Pushing multiroomSync output update for this device Sep 04 17:48:51 volumio volumio[1073]: info: MRS: Pushing multiroomSync output Sep 04 17:48:51 volumio volumio[1073]: info: ------------------------------ 30ms Sep 04 17:48:51 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 17:48:51 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 17:48:51 volumio volumio[1073]: info: Discovery: Getting this device information Sep 04 17:48:51 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Sep 04 17:48:51 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Sep 04 17:48:51 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 17:48:56 volumio volumio[1073]: info: VolumeController::SetAlsaVolume100 Sep 04 17:48:56 volumio volumio[1073]: info: CoreStateMachine::pushState Sep 04 17:48:56 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Sep 04 17:48:56 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 17:48:56 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Sep 04 17:48:56 volumio volumio[1073]: info: MRS: Pushing multiroomSync output update for this device Sep 04 17:48:56 volumio volumio[1073]: info: MRS: Pushing multiroomSync output Sep 04 17:49:05 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPause Sep 04 17:49:05 volumio volumio[1073]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPause] in plugin multiroom Sep 04 17:49:05 volumio volumio[1073]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 17:49:05 volumio volumio[1073]: TypeError: Cannot read property 'then' of undefined Sep 04 17:49:05 volumio volumio[1073]: at outputs.audioOutputPause (/volumio/app/plugins/audio_interface/outputs/index.js:417:9) Sep 04 17:49:05 volumio volumio[1073]: at CoreCommandRouter.audioOutputPause (/volumio/app/index.js:2271:30) Sep 04 17:49:05 volumio volumio[1073]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1448:26) Sep 04 17:49:05 volumio volumio[1073]: at Socket.emit (events.js:315:20) Sep 04 17:49:05 volumio volumio[1073]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 04 17:49:05 volumio volumio[1073]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 04 17:49:05 volumio volumio[1073]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 17:49:06 volumio sudo[25763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-04 17:48 Sep 04 17:49:06 volumio sudo[25763]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"